From mboxrd@z Thu Jan 1 00:00:00 1970 From: ludo@gnu.org (Ludovic =?UTF-8?Q?Court=C3=A8s?=) Subject: bug#29335: 'guix publish' workers occasionally crash Date: Fri, 17 Nov 2017 13:22:58 +0100 Message-ID: <87vai93xi5.fsf@gnu.org> References: <878tf55i6u.fsf@gnu.org> Mime-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable Return-path: Received: from eggs.gnu.org ([2001:4830:134:3::10]:58169) by lists.gnu.org with esmtp (Exim 4.71) (envelope-from ) id 1eFfgl-0002ef-Im for bug-guix@gnu.org; Fri, 17 Nov 2017 07:24:11 -0500 Received: from Debian-exim by eggs.gnu.org with spam-scanned (Exim 4.71) (envelope-from ) id 1eFfgg-0007OG-IW for bug-guix@gnu.org; Fri, 17 Nov 2017 07:24:07 -0500 Received: from debbugs.gnu.org ([208.118.235.43]:35689) by eggs.gnu.org with esmtps (TLS1.0:RSA_AES_128_CBC_SHA1:16) (Exim 4.71) (envelope-from ) id 1eFfgg-0007OC-Dh for bug-guix@gnu.org; Fri, 17 Nov 2017 07:24:02 -0500 Received: from Debian-debbugs by debbugs.gnu.org with local (Exim 4.84_2) (envelope-from ) id 1eFfgg-0003yz-2g for bug-guix@gnu.org; Fri, 17 Nov 2017 07:24:02 -0500 Sender: "Debbugs-submit" Resent-Message-ID: In-Reply-To: <878tf55i6u.fsf@gnu.org> ("Ludovic \=\?utf-8\?Q\?Court\=C3\=A8s\=22'\?\= \=\?utf-8\?Q\?s\?\= message of "Fri, 17 Nov 2017 11:10:49 +0100") List-Id: Bug reports for GNU Guix List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Errors-To: bug-guix-bounces+gcggb-bug-guix=m.gmane.org@gnu.org Sender: "bug-Guix" To: 29335@debbugs.gnu.org If we look at our worker threads, they=E2=80=99re all stuck in mutex_lock: --8<---------------cut here---------------start------------->8--- (gdb) info threads Id Target Id Frame * 1 Thread 0x7f7ee99d5f00 (LWP 4105) "guix publish" 0x00007f7ee79c60ad i= n poll () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5silnakh6-glibc-2.25/lib/l= ibc.so.6 2 Thread 0x7f7ee78e6700 (LWP 4106) ".guix-real" 0x00007f7ee900d58f in = pthread_cond_wait@@GLIBC_2.3.2 () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5s= ilnakh6-glibc-2.25/lib/libpthread.so.0 3 Thread 0x7f7ee70e5700 (LWP 4107) ".guix-real" 0x00007f7ee900d58f in = pthread_cond_wait@@GLIBC_2.3.2 () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5s= ilnakh6-glibc-2.25/lib/libpthread.so.0 4 Thread 0x7f7ee68e4700 (LWP 4108) ".guix-real" 0x00007f7ee900d58f in = pthread_cond_wait@@GLIBC_2.3.2 () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5s= ilnakh6-glibc-2.25/lib/libpthread.so.0 5 Thread 0x7f7ee60e3700 (LWP 4109) ".guix-real" 0x00007f7ee900d58f in = pthread_cond_wait@@GLIBC_2.3.2 () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5s= ilnakh6-glibc-2.25/lib/libpthread.so.0 6 Thread 0x7f7ee58e2700 (LWP 4110) ".guix-real" 0x00007f7ee900d58f in = pthread_cond_wait@@GLIBC_2.3.2 () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5s= ilnakh6-glibc-2.25/lib/libpthread.so.0 7 Thread 0x7f7ee50e1700 (LWP 4111) ".guix-real" 0x00007f7ee900d58f in = pthread_cond_wait@@GLIBC_2.3.2 () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5s= ilnakh6-glibc-2.25/lib/libpthread.so.0 8 Thread 0x7f7ee48e0700 (LWP 4112) ".guix-real" 0x00007f7ee900d58f in = pthread_cond_wait@@GLIBC_2.3.2 () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5s= ilnakh6-glibc-2.25/lib/libpthread.so.0 9 Thread 0x7f7ee3a4f700 (LWP 4113) ".guix-real" 0x00007f7ee9010aed in = read () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5silnakh6-glibc-2.25/lib/lib= pthread.so.0 10 Thread 0x7f7ee114b700 (LWP 4114) ".guix-real" 0x00007f7ee9010aed in = read () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5silnakh6-glibc-2.25/lib/lib= pthread.so.0 11 Thread 0x7f7edf892700 (LWP 4116) "publish worker" 0x00007f7ee90108fc= in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5silnakh6-gli= bc-2.25/lib/libpthread.so.0 12 Thread 0x7f7edf090700 (LWP 4117) "publish worker" 0x00007f7ee90108fc= in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5silnakh6-gli= bc-2.25/lib/libpthread.so.0 13 Thread 0x7f7ede88e700 (LWP 4118) "publish worker" 0x00007f7ee90108fc= in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5silnakh6-gli= bc-2.25/lib/libpthread.so.0 14 Thread 0x7f7ede08c700 (LWP 4119) "publish worker" 0x00007f7ee90108fc= in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5silnakh6-gli= bc-2.25/lib/libpthread.so.0 15 Thread 0x7f7edd88a700 (LWP 4120) "publish worker" 0x00007f7ee90108fc= in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5silnakh6-gli= bc-2.25/lib/libpthread.so.0 16 Thread 0x7f7edd088700 (LWP 4121) "publish worker" 0x00007f7ee90108fc= in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5silnakh6-gli= bc-2.25/lib/libpthread.so.0 (gdb) thread apply 11-16 bt Thread 11 (Thread 0x7f7edf892700 (LWP 4116)): #0 0x00007f7ee90108fc in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysff= jh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #1 0x00007f7ee9009b05 in pthread_mutex_lock () from /gnu/store/n6nvxlk2j8y= sffjh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #2 0x00007f7ee9545815 in scm_pthread_mutex_lock (mutex=3D) = at threads.c:1600 #3 0x00007f7ee9545969 in scm_dynwind_pthread_mutex_lock (mutex=3D0x7f7ee97= bbfa0 ) at threads.c:1613 #4 0x00007f7ee94e2927 in scm_readdir (port=3D#) at fi= lesys.c:1749 #5 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bea0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #6 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #7 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #8 0x00007f7ee94cbef8 in scm_call_with_unblocked_asyncs (proc=3D#) at async.c:400 #9 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bea0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #10 0x00007f7ee95572aa in scm_call_n (proc=3D#, argv= =3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #11 0x00007f7ee94da7d9 in scm_call_0 (proc=3D) at eval.c:481 #12 0x00007f7ee9544fe6 in really_launch (d=3D0x266ab80) at threads.c:793 #13 0x00007f7ee94d4cba in c_body (d=3D0x7f7edf891e60) at continuations.c:422 #14 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bea0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #15 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #16 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #17 0x00007f7ee95464e6 in catch (tag=3Dtag@entry=3D#t, thunk=3D#, handler=3D#, pre_unwind_handler=3D#) at throw.c:137 #18 0x00007f7ee9546825 in scm_catch_with_pre_unwind_handler (key=3Dkey@entr= y=3D#t, thunk=3D, handler=3D, pre_unwind_hand= ler=3D) at throw.c:254 #19 0x00007f7ee95469df in scm_c_catch (tag=3Dtag@entry=3D#t, body=3Dbody@en= try=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7edf891e60= , handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7edf891e60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at throw.c:377 #20 0x00007f7ee94d52a0 in scm_i_with_continuation_barrier (body=3Dbody@entr= y=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7edf891e60, = handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7edf891e60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at continuations.c:360 #21 0x00007f7ee94d5335 in scm_c_with_continuation_barrier (func=3D, data=3D) at continuations.c:456 #22 0x00007f7ee954510c in with_guile (base=3D0x7f7edf891ec0, data=3D0x7f7ed= f891ef0) at threads.c:660 #23 0x00007f7ee923a732 in GC_call_with_stack_base () from /gnu/store/9blidf= 28xz0a0glnv6a4xcw6nxadg427-libgc-7.6.0/lib/libgc.so.1 #24 0x00007f7ee95447dd in scm_i_with_guile (dynamic_state=3D= , data=3D0x266ab80, func=3D0x7f7ee9544f70 ) at threads.c:703 #25 launch_thread (d=3D0x266ab80) at threads.c:802 #26 0x00007f7ee9007454 in start_thread () from /gnu/store/n6nvxlk2j8ysffjh3= jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #27 0x00007f7ee79cf7bf in clone () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5= silnakh6-glibc-2.25/lib/libc.so.6 Thread 12 (Thread 0x7f7edf090700 (LWP 4117)): #0 0x00007f7ee90108fc in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysff= jh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #1 0x00007f7ee9009b05 in pthread_mutex_lock () from /gnu/store/n6nvxlk2j8y= sffjh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #2 0x00007f7ee9545815 in scm_pthread_mutex_lock (mutex=3D) = at threads.c:1600 #3 0x00007f7ee9545969 in scm_dynwind_pthread_mutex_lock (mutex=3D0x7f7ee97= bbfa0 ) at threads.c:1613 #4 0x00007f7ee94d8dbb in scm_strerror (err=3Derr@entry=3D17) at error.c:128 #5 0x00007f7ee94d8e73 in scm_syserror (subr=3Dsubr@entry=3D0x7f7ee956b07a = "mkdir") at error.c:169 #6 0x00007f7ee94e2189 in scm_mkdir (path=3D, mode=3D) at filesys.c:1274 #7 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211be10, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #8 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #9 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #10 0x00007f7ee94cbef8 in scm_call_with_unblocked_asyncs (proc=3D#) at async.c:400 #11 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211be10, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #12 0x00007f7ee95572aa in scm_call_n (proc=3D#, argv= =3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #13 0x00007f7ee94da7d9 in scm_call_0 (proc=3D) at eval.c:481 #14 0x00007f7ee9544fe6 in really_launch (d=3D0x266ab40) at threads.c:793 #15 0x00007f7ee94d4cba in c_body (d=3D0x7f7edf08fe60) at continuations.c:422 #16 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211be10, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #17 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #18 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #19 0x00007f7ee95464e6 in catch (tag=3Dtag@entry=3D#t, thunk=3D#, handler=3D#, pre_unwind_handler=3D#) at throw.c:137 #20 0x00007f7ee9546825 in scm_catch_with_pre_unwind_handler (key=3Dkey@entr= y=3D#t, thunk=3D, handler=3D, pre_unwind_hand= ler=3D) at throw.c:254 #21 0x00007f7ee95469df in scm_c_catch (tag=3Dtag@entry=3D#t, body=3Dbody@en= try=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7edf08fe60= , handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7edf08fe60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at throw.c:377 #22 0x00007f7ee94d52a0 in scm_i_with_continuation_barrier (body=3Dbody@entr= y=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7edf08fe60, = handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7edf08fe60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at continuations.c:360 #23 0x00007f7ee94d5335 in scm_c_with_continuation_barrier (func=3D, data=3D) at continuations.c:456 #24 0x00007f7ee954510c in with_guile (base=3D0x7f7edf08fec0, data=3D0x7f7ed= f08fef0) at threads.c:660 #25 0x00007f7ee923a732 in GC_call_with_stack_base () from /gnu/store/9blidf= 28xz0a0glnv6a4xcw6nxadg427-libgc-7.6.0/lib/libgc.so.1 #26 0x00007f7ee95447dd in scm_i_with_guile (dynamic_state=3D= , data=3D0x266ab40, func=3D0x7f7ee9544f70 ) at threads.c:703 #27 launch_thread (d=3D0x266ab40) at threads.c:802 #28 0x00007f7ee9007454 in start_thread () from /gnu/store/n6nvxlk2j8ysffjh3= jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #29 0x00007f7ee79cf7bf in clone () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5= silnakh6-glibc-2.25/lib/libc.so.6 Thread 13 (Thread 0x7f7ede88e700 (LWP 4118)): #0 0x00007f7ee90108fc in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysff= jh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #1 0x00007f7ee9009b05 in pthread_mutex_lock () from /gnu/store/n6nvxlk2j8y= sffjh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #2 0x00007f7ee9545815 in scm_pthread_mutex_lock (mutex=3D) = at threads.c:1600 #3 0x00007f7ee9545969 in scm_dynwind_pthread_mutex_lock (mutex=3D0x7f7ee97= bbfa0 ) at threads.c:1613 #4 0x00007f7ee94e2927 in scm_readdir (port=3D#) at fi= lesys.c:1749 #5 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bd80, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #6 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #7 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #8 0x00007f7ee94cbef8 in scm_call_with_unblocked_asyncs (proc=3D#) at async.c:400 #9 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bd80, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #10 0x00007f7ee95572aa in scm_call_n (proc=3D#, argv= =3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #11 0x00007f7ee94da7d9 in scm_call_0 (proc=3D) at eval.c:481 #12 0x00007f7ee9544fe6 in really_launch (d=3D0x266ab00) at threads.c:793 #13 0x00007f7ee94d4cba in c_body (d=3D0x7f7ede88de60) at continuations.c:422 #14 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bd80, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #15 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #16 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #17 0x00007f7ee95464e6 in catch (tag=3Dtag@entry=3D#t, thunk=3D#, handler=3D#, pre_unwind_handler=3D#) at throw.c:137 #18 0x00007f7ee9546825 in scm_catch_with_pre_unwind_handler (key=3Dkey@entr= y=3D#t, thunk=3D, handler=3D, pre_unwind_hand= ler=3D) at throw.c:254 #19 0x00007f7ee95469df in scm_c_catch (tag=3Dtag@entry=3D#t, body=3Dbody@en= try=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7ede88de60= , handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7ede88de60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at throw.c:377 #20 0x00007f7ee94d52a0 in scm_i_with_continuation_barrier (body=3Dbody@entr= y=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7ede88de60, = handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7ede88de60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at continuations.c:360 #21 0x00007f7ee94d5335 in scm_c_with_continuation_barrier (func=3D, data=3D) at continuations.c:456 #22 0x00007f7ee954510c in with_guile (base=3D0x7f7ede88dec0, data=3D0x7f7ed= e88def0) at threads.c:660 #23 0x00007f7ee923a732 in GC_call_with_stack_base () from /gnu/store/9blidf= 28xz0a0glnv6a4xcw6nxadg427-libgc-7.6.0/lib/libgc.so.1 #24 0x00007f7ee95447dd in scm_i_with_guile (dynamic_state=3D= , data=3D0x266ab00, func=3D0x7f7ee9544f70 ) at threads.c:703 #25 launch_thread (d=3D0x266ab00) at threads.c:802 #26 0x00007f7ee9007454 in start_thread () from /gnu/store/n6nvxlk2j8ysffjh3= jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #27 0x00007f7ee79cf7bf in clone () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5= silnakh6-glibc-2.25/lib/libc.so.6 Thread 14 (Thread 0x7f7ede08c700 (LWP 4119)): #0 0x00007f7ee90108fc in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysff= jh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #1 0x00007f7ee9009b05 in pthread_mutex_lock () from /gnu/store/n6nvxlk2j8y= sffjh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #2 0x00007f7ee9545815 in scm_pthread_mutex_lock (mutex=3D) = at threads.c:1600 #3 0x00007f7ee9545969 in scm_dynwind_pthread_mutex_lock (mutex=3D0x7f7ee97= bbfa0 ) at threads.c:1613 #4 0x00007f7ee94d8dbb in scm_strerror (err=3Derr@entry=3D9) at error.c:128 #5 0x00007f7ee94d8e73 in scm_syserror (subr=3Dsubr@entry=3D0x7f7ee956afa0 = "readdir") at error.c:169 #6 0x00007f7ee94e29b4 in scm_readdir (port=3D#) at fi= lesys.c:1754 #7 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bcf0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #8 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #9 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #10 0x00007f7ee94cbef8 in scm_call_with_unblocked_asyncs (proc=3D#) at async.c:400 #11 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bcf0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #12 0x00007f7ee95572aa in scm_call_n (proc=3D#, argv= =3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #13 0x00007f7ee94da7d9 in scm_call_0 (proc=3D) at eval.c:481 #14 0x00007f7ee9544fe6 in really_launch (d=3D0x266aac0) at threads.c:793 #15 0x00007f7ee94d4cba in c_body (d=3D0x7f7ede08be60) at continuations.c:422 #16 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bcf0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #17 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #18 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #19 0x00007f7ee95464e6 in catch (tag=3Dtag@entry=3D#t, thunk=3D#, handler=3D#, pre_unwind_handler=3D#) at throw.c:137 #20 0x00007f7ee9546825 in scm_catch_with_pre_unwind_handler (key=3Dkey@entr= y=3D#t, thunk=3D, handler=3D, pre_unwind_hand= ler=3D) at throw.c:254 #21 0x00007f7ee95469df in scm_c_catch (tag=3Dtag@entry=3D#t, body=3Dbody@en= try=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7ede08be60= , handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7ede08be60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at throw.c:377 #22 0x00007f7ee94d52a0 in scm_i_with_continuation_barrier (body=3Dbody@entr= y=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7ede08be60, = handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7ede08be60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at continuations.c:360 #23 0x00007f7ee94d5335 in scm_c_with_continuation_barrier (func=3D, data=3D) at continuations.c:456 #24 0x00007f7ee954510c in with_guile (base=3D0x7f7ede08bec0, data=3D0x7f7ed= e08bef0) at threads.c:660 #25 0x00007f7ee923a732 in GC_call_with_stack_base () from /gnu/store/9blidf= 28xz0a0glnv6a4xcw6nxadg427-libgc-7.6.0/lib/libgc.so.1 #26 0x00007f7ee95447dd in scm_i_with_guile (dynamic_state=3D= , data=3D0x266aac0, func=3D0x7f7ee9544f70 ) at threads.c:703 #27 launch_thread (d=3D0x266aac0) at threads.c:802 #28 0x00007f7ee9007454 in start_thread () from /gnu/store/n6nvxlk2j8ysffjh3= jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #29 0x00007f7ee79cf7bf in clone () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5= silnakh6-glibc-2.25/lib/libc.so.6 Thread 15 (Thread 0x7f7edd88a700 (LWP 4120)): #0 0x00007f7ee90108fc in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysff= jh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #1 0x00007f7ee9009b05 in pthread_mutex_lock () from /gnu/store/n6nvxlk2j8y= sffjh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #2 0x00007f7ee9545815 in scm_pthread_mutex_lock (mutex=3D) = at threads.c:1600 #3 0x00007f7ee9545969 in scm_dynwind_pthread_mutex_lock (mutex=3D0x7f7ee97= bbfa0 ) at threads.c:1613 #4 0x00007f7ee94e2927 in scm_readdir (port=3D#) at fi= lesys.c:1749 #5 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bc60, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #6 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #7 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #8 0x00007f7ee94cbef8 in scm_call_with_unblocked_asyncs (proc=3D#) at async.c:400 #9 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bc60, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #10 0x00007f7ee95572aa in scm_call_n (proc=3D#, argv= =3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #11 0x00007f7ee94da7d9 in scm_call_0 (proc=3D) at eval.c:481 ---Type to continue, or q to quit--- #12 0x00007f7ee9544fe6 in really_launch (d=3D0x266aa80) at threads.c:793 #13 0x00007f7ee94d4cba in c_body (d=3D0x7f7edd889e60) at continuations.c:422 #14 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bc60, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #15 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #16 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #17 0x00007f7ee95464e6 in catch (tag=3Dtag@entry=3D#t, thunk=3D#, handler=3D#, pre_unwind_handler=3D#) at throw.c:137 #18 0x00007f7ee9546825 in scm_catch_with_pre_unwind_handler (key=3Dkey@entr= y=3D#t, thunk=3D, handler=3D, pre_unwind_hand= ler=3D) at throw.c:254 #19 0x00007f7ee95469df in scm_c_catch (tag=3Dtag@entry=3D#t, body=3Dbody@en= try=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7edd889e60= , handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7edd889e60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at throw.c:377 #20 0x00007f7ee94d52a0 in scm_i_with_continuation_barrier (body=3Dbody@entr= y=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7edd889e60, = handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7edd889e60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at continuations.c:360 #21 0x00007f7ee94d5335 in scm_c_with_continuation_barrier (func=3D, data=3D) at continuations.c:456 #22 0x00007f7ee954510c in with_guile (base=3D0x7f7edd889ec0, data=3D0x7f7ed= d889ef0) at threads.c:660 #23 0x00007f7ee923a732 in GC_call_with_stack_base () from /gnu/store/9blidf= 28xz0a0glnv6a4xcw6nxadg427-libgc-7.6.0/lib/libgc.so.1 #24 0x00007f7ee95447dd in scm_i_with_guile (dynamic_state=3D= , data=3D0x266aa80, func=3D0x7f7ee9544f70 ) at threads.c:703 #25 launch_thread (d=3D0x266aa80) at threads.c:802 #26 0x00007f7ee9007454 in start_thread () from /gnu/store/n6nvxlk2j8ysffjh3= jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #27 0x00007f7ee79cf7bf in clone () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5= silnakh6-glibc-2.25/lib/libc.so.6 Thread 16 (Thread 0x7f7edd088700 (LWP 4121)): #0 0x00007f7ee90108fc in __lll_lock_wait () from /gnu/store/n6nvxlk2j8ysff= jh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #1 0x00007f7ee9009b05 in pthread_mutex_lock () from /gnu/store/n6nvxlk2j8y= sffjh3jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #2 0x00007f7ee9545815 in scm_pthread_mutex_lock (mutex=3D) = at threads.c:1600 #3 0x00007f7ee9545969 in scm_dynwind_pthread_mutex_lock (mutex=3D0x7f7ee97= bbfa0 ) at threads.c:1613 #4 0x00007f7ee94d8dbb in scm_strerror (err=3Derr@entry=3D9) at error.c:128 #5 0x00007f7ee94d8e73 in scm_syserror (subr=3Dsubr@entry=3D0x7f7ee956bbd9 = "fport_close") at error.c:169 #6 0x00007f7ee94e7a85 in fport_close (port=3D) at fports.c:= 677 #7 0x00007f7ee9514785 in release_port (port=3D#) at ports.= c:158 #8 0x00007f7ee9517a0b in scm_close_port (port=3D#) at port= s.c:887 #9 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bbd0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #10 0x00007f7ee95572aa in scm_call_n (proc=3D#, argv= =3D, nargs=3D0) at vm.c:1257 #11 0x00007f7ee9514785 in release_port (port=3D#) at ports.= c:158 #12 0x00007f7ee9517a0b in scm_close_port (port=3D#) at port= s.c:887 #13 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bbd0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #14 0x00007f7ee95572aa in scm_call_n (proc=3D#, argv= =3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #15 0x00007f7ee94da7d9 in scm_call_0 (proc=3D) at eval.c:481 #16 0x00007f7ee94d7ba0 in scm_dynstack_unwind_1 (dynstack=3Ddynstack@entry= =3D0x1fd9088) at dynstack.c:407 #17 0x00007f7ee94d7cd0 in scm_dynstack_unwind (dynstack=3Ddynstack@entry=3D= 0x1fd9088, base=3D0x29e1720) at dynstack.c:437 #18 0x00007f7ee94d4b02 in scm_c_abort (vp=3D0x211bbd0, tag=3D, n=3D5, argv=3D0x7f7edd087200, current_registers=3D) at co= ntrol.c:162 #19 0x00007f7ee9548df1 in vm_abort (vp=3D, tag=3D, nargs=3D, current_registers=3D) at vm.c= :340 #20 0x00007f7ee9553cc6 in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bbd0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:1004 #21 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3D, nargs=3D5) at vm.c:1257 #22 0x00007f7ee94dab6b in scm_apply_0 (proc=3D#, args= =3D()) at eval.c:594 #23 0x00007f7ee94db76d in scm_apply_1 (proc=3D, arg1=3Darg1@= entry=3Dsystem-error, args=3D) at eval.c:600 #24 0x00007f7ee954686c in scm_throw (key=3Dkey@entry=3Dsystem-error, args= =3D) at throw.c:266 #25 0x00007f7ee9546de5 in scm_ithrow (key=3Dkey@entry=3Dsystem-error, args= =3D, no_return=3Dno_return@entry=3D1) at throw.c:611 #26 0x00007f7ee94d8d75 in scm_error_scm (key=3Dkey@entry=3Dsystem-error, su= br=3D, message=3Dmessage@entry=3D"~A", args=3Dargs@entry=3D(= "Invalid argument"), data=3Ddata@entry=3D(22)) at error.c:94 #27 0x00007f7ee94d8e2f in scm_error (key=3Dsystem-error, subr=3Dsubr@entry= =3D0x7f7ee956bbf1 "fport_read", message=3Dmessage@entry=3D0x7f7ee95732db "~= A", args=3D("Invalid argument"), rest=3Drest@entry=3D(22)) at error.c:59 #28 0x00007f7ee94d8eaa in scm_syserror (subr=3Dsubr@entry=3D0x7f7ee956bbf1 = "fport_read") at error.c:169 #29 0x00007f7ee94e7c75 in fport_read (port=3D, dst=3D, start=3D, count=3D58928) at fports.c:614 #30 0x00007f7ee9514d77 in scm_i_read_bytes (port=3Dport@entry=3D#, dst=3Ddst@entry=3D"#" =3D {...}, start=3Dstart@entry=3D0= , count=3Dcount@entry=3D58928) at ports.c:1544 #31 0x00007f7ee95193fc in scm_c_read_bytes (port=3Dport@entry=3D#, dst=3Ddst@entry=3D"#" =3D {...}, start=3Dstart@entry=3D0= , count=3D58928) at ports.c:1624 #32 0x00007f7ee951eb78 in scm_get_bytevector_n_x (port=3D#,= bv=3D"#" =3D {...}, start=3D, count=3D58928) at = r6rs-ports.c:461 #33 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bbd0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #34 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #35 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #36 0x00007f7ee94cbef8 in scm_call_with_unblocked_asyncs (proc=3D#) at async.c:400 #37 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bbd0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #38 0x00007f7ee95572aa in scm_call_n (proc=3D#, argv= =3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #39 0x00007f7ee94da7d9 in scm_call_0 (proc=3D) at eval.c:481 #40 0x00007f7ee9544fe6 in really_launch (d=3D0x266aa40) at threads.c:793 #41 0x00007f7ee94d4cba in c_body (d=3D0x7f7edd087e60) at continuations.c:422 #42 0x00007f7ee9553c4d in vm_regular_engine (thread=3D0x7f7ee97bbfa0 , vp=3D0x211bbd0, registers=3D0x0, resume=3D-385808132) at vm-e= ngine.c:784 #43 0x00007f7ee95572aa in scm_call_n (proc=3Dproc@entry=3D#, argv=3Dargv@entry=3D0x0, nargs=3Dnargs@entry=3D0) at vm.c:1257 #44 0x00007f7ee94da7d9 in scm_call_0 (proc=3Dproc@entry=3D#) at eval.c:481 #45 0x00007f7ee95464e6 in catch (tag=3Dtag@entry=3D#t, thunk=3D#, handler=3D#, pre_unwind_handler=3D#) at throw.c:137 #46 0x00007f7ee9546825 in scm_catch_with_pre_unwind_handler (key=3Dkey@entr= y=3D#t, thunk=3D, handler=3D, pre_unwind_hand= ler=3D) at throw.c:254 #47 0x00007f7ee95469df in scm_c_catch (tag=3Dtag@entry=3D#t, body=3Dbody@en= try=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7edd087e60= , handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7edd087e60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at throw.c:377 #48 0x00007f7ee94d52a0 in scm_i_with_continuation_barrier (body=3Dbody@entr= y=3D0x7f7ee94d4cb0 , body_data=3Dbody_data@entry=3D0x7f7edd087e60, = handler=3Dhandler@entry=3D0x7f7ee94d4f40 , handler_data=3Dhandler_data@entry=3D0x7f7edd087e60, pre_unwind_handler= =3Dpre_unwind_handler@entry=3D0x7f7ee94d4da0 , pre_unwi= nd_handler_data=3D0x2079ce0) at continuations.c:360 #49 0x00007f7ee94d5335 in scm_c_with_continuation_barrier (func=3D, data=3D) at continuations.c:456 #50 0x00007f7ee954510c in with_guile (base=3D0x7f7edd087ec0, data=3D0x7f7ed= d087ef0) at threads.c:660 #51 0x00007f7ee923a732 in GC_call_with_stack_base () from /gnu/store/9blidf= 28xz0a0glnv6a4xcw6nxadg427-libgc-7.6.0/lib/libgc.so.1 #52 0x00007f7ee95447dd in scm_i_with_guile (dynamic_state=3D= , data=3D0x266aa40, func=3D0x7f7ee9544f70 ) at threads.c:703 #53 launch_thread (d=3D0x266aa40) at threads.c:802 #54 0x00007f7ee9007454 in start_thread () from /gnu/store/n6nvxlk2j8ysffjh3= jphn1k5silnakh6-glibc-2.25/lib/libpthread.so.0 #55 0x00007f7ee79cf7bf in clone () from /gnu/store/n6nvxlk2j8ysffjh3jphn1k5= silnakh6-glibc-2.25/lib/libc.so.6 --8<---------------cut here---------------end--------------->8--- Thread 14 is in scm_readdir, so it holds the terrible =E2=80=98scm_i_misc_mutex=E2=80=99, but calling SCM_SYSERROR there causes i= t to try and lock it again, leading to a deadlock: --8<---------------cut here---------------start------------->8--- #3 0x00007f7ee9545969 in scm_dynwind_pthread_mutex_lock (mutex=3D0x7f7ee97= bbfa0 ) at threads.c:1613 #4 0x00007f7ee94d8dbb in scm_strerror (err=3Derr@entry=3D9) at error.c:128 #5 0x00007f7ee94d8e73 in scm_syserror (subr=3Dsubr@entry=3D0x7f7ee956afa0 = "readdir") at error.c:169 #6 0x00007f7ee94e29b4 in scm_readdir (port=3D#) at fi= lesys.c:1754 --8<---------------cut here---------------end--------------->8--- Thread 16 got EINVAL from read(2), which sounds fishy: --8<---------------cut here---------------start------------->8--- #29 0x00007f7ee94e7c75 in fport_read (port=3D, dst=3D, start=3D, count=3D58928) at fports.c:614 614 in fports.c (gdb) p *fp $1 =3D {fdes =3D 35, revealed =3D 0, options =3D 0} (gdb) shell ls -l /proc/4105/fd/35 ls: cannot access '/proc/4105/fd/35': No such file or directory --8<---------------cut here---------------end--------------->8--- To be continued=E2=80=A6 Ludo=E2=80=99.