deadlock writing to stdout in remotely controlled soffice

Hey,

so I experience some strange deadlocks recently with quite interesting
backlogs.

Did anyone of you see something similar recently? Attached is a backtrace
for all threads and I wonder why thread 2 deadlocks. My build on that
machine is about two weeks old and it seems like I'm able to reproduce the
problem each time I hit the same file.

Regards,
Markus

Thread 7 (Thread 0x7fb87d0a6700 (LWP 22325)):
#0  0x000000352dc0b912 in pthread_cond_timedwait@@GLIBC_2.3.2 () from /lib64/libpthread.so.0
#1  0x00007fb88e85b301 in rtl_cache_wsupdate_wait (seconds=10) at 
/home/buildslave/source/libo-core/sal/rtl/alloc_cache.cxx:1377
#2  0x00007fb88e85b4e9 in rtl_cache_wsupdate_all (arg=0xa) at 
/home/buildslave/source/libo-core/sal/rtl/alloc_cache.cxx:1518
#3  0x000000352dc07c53 in start_thread () from /lib64/libpthread.so.0
#4  0x000000352d4f5dbd in clone () from /lib64/libc.so.6

Thread 6 (Thread 0x7fb87afcb700 (LWP 22328)):
#0  0x000000352d4f6a6d in accept () from /lib64/libc.so.6
#1  0x00007fb88e842f88 in osl_acceptPipe (pPipe=0x10ff720) at 
/home/buildslave/source/libo-core/sal/osl/unx/pipe.c:443
#2  0x00007fb88e59de9e in osl::Pipe::accept (this=0x10fcdb8, Connection=...) at 
/home/buildslave/source/libo-core/include/osl/pipe.hxx:132
#3  0x00007fb88e59b5d1 in desktop::OfficeIPCThread::execute (this=0x10fcd90) at 
/home/buildslave/source/libo-core/desktop/source/app/officeipcthread.cxx:679
#4  0x00007fb88d3729c4 in salhelper::Thread::run (this=0x10fcd90) at 
/home/buildslave/source/libo-core/salhelper/source/thread.cxx:40
#5  0x00007fb88d372d8d in osl::threadFunc (param=0x10fcda0) at 
/home/buildslave/source/libo-core/include/osl/thread.hxx:187
#6  0x00007fb88e84c2e7 in osl_thread_start_Impl (pData=0x1100740) at 
/home/buildslave/source/libo-core/sal/osl/unx/thread.c:236
#7  0x000000352dc07c53 in start_thread () from /lib64/libpthread.so.0
#8  0x000000352d4f5dbd in clone () from /lib64/libc.so.6

Thread 5 (Thread 0x7fb87b9ea700 (LWP 22329)):
#0  0x000000352d4f6a6d in accept () from /lib64/libc.so.6
#1  0x00007fb88e842f88 in osl_acceptPipe (pPipe=0x12164f0) at 
/home/buildslave/source/libo-core/sal/osl/unx/pipe.c:443
#2  0x00007fb87a34ff72 in osl::Pipe::accept (this=0x7fb87b9e98b0, Connection=...) at 
/home/buildslave/source/libo-core/include/osl/pipe.hxx:132
#3  0x00007fb87a34f7cf in io_acceptor::PipeAcceptor::accept (this=0x11292a0) at 
/home/buildslave/source/libo-core/io/source/acceptor/acc_pipe.cxx:181
#4  0x00007fb87a3587fe in io_acceptor::OAcceptor::accept (this=0x1101a70, 
sConnectionDescription="pipe,name=pytest188aabc4-5ca6-11e3-a31f-52540097aac2")
    at /home/buildslave/source/libo-core/io/source/acceptor/acceptor.cxx:238
#5  0x00007fb87a5b6847 in desktop::Acceptor::run (this=0x7b3d30) at 
/home/buildslave/source/libo-core/desktop/source/offacc/acceptor.cxx:100
#6  0x00007fb87a5b622b in desktop::offacc_workerfunc (acc=0x7b3d30) at 
/home/buildslave/source/libo-core/desktop/source/offacc/acceptor.cxx:41
#7  0x00007fb88e84c2e7 in osl_thread_start_Impl (pData=0x1105ee0) at 
/home/buildslave/source/libo-core/sal/osl/unx/thread.c:236
#8  0x000000352dc07c53 in start_thread () from /lib64/libpthread.so.0
#9  0x000000352d4f5dbd in clone () from /lib64/libc.so.6

Thread 4 (Thread 0x7fb8782fe700 (LWP 22331)):
#0  0x000000352dc0b565 in pthread_cond_wait@@GLIBC_2.3.2 () from /lib64/libpthread.so.0
#1  0x00007fb88e885266 in osl_waitCondition (Condition=0x121b100, pTimeout=0x0) at 
/home/buildslave/source/libo-core/sal/osl/unx/conditn.cxx:275
#2  0x00007fb87a0be1da in osl::Condition::wait (this=0x121d1c0, pTimeout=0x0) at 
/home/buildslave/source/libo-core/include/osl/conditn.hxx:75
#3  0x00007fb87a0f8985 in binaryurp::Writer::execute (this=0x121cff0) at 
/home/buildslave/source/libo-core/binaryurp/source/writer.cxx:140
#4  0x00007fb88d3729c4 in salhelper::Thread::run (this=0x121cff0) at 
/home/buildslave/source/libo-core/salhelper/source/thread.cxx:40
#5  0x00007fb88d372d8d in osl::threadFunc (param=0x121d000) at 
/home/buildslave/source/libo-core/include/osl/thread.hxx:187
#6  0x00007fb88e84c2e7 in osl_thread_start_Impl (pData=0x121d4a0) at 
/home/buildslave/source/libo-core/sal/osl/unx/thread.c:236
#7  0x000000352dc07c53 in start_thread () from /lib64/libpthread.so.0
#8  0x000000352d4f5dbd in clone () from /lib64/libc.so.6

Thread 3 (Thread 0x7fb877afd700 (LWP 22333)):
#0  0x000000352d4f6c6b in recv () from /lib64/libc.so.6
#1  0x00007fb88e843195 in osl_receivePipe (pPipe=0x1217510, pBuffer=0x11633e8, BytesToRead=8) at 
/home/buildslave/source/libo-core/sal/osl/unx/pipe.c:506
#2  0x00007fb88e8433eb in osl_readPipe (pPipe=0x1217510, pBuffer=0x11633e8, n=8) at 
/home/buildslave/source/libo-core/sal/osl/unx/pipe.c:590
#3  0x00007fb87a350090 in osl::StreamPipe::read (this=0x1121a30, pBuffer=0x11633e8, n=8) at 
/home/buildslave/source/libo-core/include/osl/pipe.hxx:182
#4  0x00007fb87a34f1f4 in io_acceptor::PipeConnection::read (this=0x1121a00, 
aReadBytes=uno::Sequence of length 8 = {...}, nBytesToRead=8) at 
/home/buildslave/source/libo-core/io/source/acceptor/acc_pipe.cxx:95
#5  0x00007fb87a0f1752 in binaryurp::(anonymous namespace)::read (connection=uno::Reference to 
(io_acceptor::PipeConnection *) 0x1121a28, size=8, eofOk=true)
    at /home/buildslave/source/libo-core/binaryurp/source/reader.cxx:70
#6  0x00007fb87a0f1b74 in binaryurp::Reader::execute (this=0x121b790) at 
/home/buildslave/source/libo-core/binaryurp/source/reader.cxx:106
#7  0x00007fb88d3729c4 in salhelper::Thread::run (this=0x121b790) at 
/home/buildslave/source/libo-core/salhelper/source/thread.cxx:40
#8  0x00007fb88d372d8d in osl::threadFunc (param=0x121b7a0) at 
/home/buildslave/source/libo-core/include/osl/thread.hxx:187
#9  0x00007fb88e84c2e7 in osl_thread_start_Impl (pData=0x121d6b0) at 
/home/buildslave/source/libo-core/sal/osl/unx/thread.c:236
#10 0x000000352dc07c53 in start_thread () from /lib64/libpthread.so.0
#11 0x000000352d4f5dbd in clone () from /lib64/libc.so.6

Thread 2 (Thread 0x7fb868e6f700 (LWP 22391)):
#0  0x000000352d4e767d in write () from /lib64/libc.so.6
#1  0x000000352d476d53 in _IO_new_file_write () from /lib64/libc.so.6
#2  0x000000352d4774ef in __GI__IO_file_xsputn () from /lib64/libc.so.6
#3  0x000000352d46c73d in fputs () from /lib64/libc.so.6
#4  0x00007fb88e855cb3 in (anonymous namespace)::log (level=SAL_DETAIL_LOG_LEVEL_WARN, 
area=0x7fb8748331b9 "legacy.osl",
    where=0x7fb874833358 "/home/buildslave/source/libo-core/sc/source/core/data/documen7.cxx:276: 
", message=0x7fb868e6bbf0 "RemoveFromFormulaTree: nFormulaCodeInTree < nRPN")
    at /home/buildslave/source/libo-core/sal/osl/all/log.cxx:242
#5  0x00007fb88e855ee3 in osl::detail::logFormat (level=SAL_DETAIL_LOG_LEVEL_WARN, 
area=0x7fb8748331b9 "legacy.osl", 
    where=0x7fb874833358 "/home/buildslave/source/libo-core/sc/source/core/data/documen7.cxx:276: 
", format=0x7fb87483316b "%s", arguments=0x7fb868e6c038)
    at /home/buildslave/source/libo-core/sal/osl/all/log.cxx:282
#6  0x00007fb88e855e2b in sal_detail_logFormat (level=SAL_DETAIL_LOG_LEVEL_WARN, 
area=0x7fb8748331b9 "legacy.osl", 
    where=0x7fb874833358 "/home/buildslave/source/libo-core/sc/source/core/data/documen7.cxx:276: 
", format=0x7fb87483316b "%s") at /home/buildslave/source/libo-core/sal/osl/all/log.cxx:265
#7  0x00007fb873d16fa9 in ScDocument::RemoveFromFormulaTree (this=0x1622ff8, pCell=0x1a4ed10) at 
/home/buildslave/source/libo-core/sc/source/core/data/documen7.cxx:276
#8  0x00007fb873d17534 in ScDocument::AppendToFormulaTrack (this=0x1622ff8, pCell=0x1a4ed10) at 
/home/buildslave/source/libo-core/sc/source/core/data/documen7.cxx:424
#9  0x00007fb873e66688 in ScFormulaCell::Notify (this=0x1a4ed10, rHint=...) at 
/home/buildslave/source/libo-core/sc/source/core/data/formulacell.cxx:1743
#10 0x00007fb88c469064 in (anonymous namespace)::NotifyHandler::operator() (this=0x7fb868e6c1f0, 
p=0x1a4ed10) at /home/buildslave/source/libo-core/svl/source/notify/broadcast.cxx:59
#11 0x00007fb88c469c87 in 
std::for_each<__gnu_debug::_Safe_iterator<__gnu_cxx::__normal_iterator<SvtListener**, 
std::__cxx1998::vector<SvtListener*, std::allocator<SvtListener*> > >, std::__debug::vector<SvtList
ener*> >, (anonymous namespace)::NotifyHandler> (__first=, __last=, __f=...) at 
/usr/include/c++/4.8.2/bits/stl_algo.h:4417
#12 0x00007fb88c4698a8 in SvtBroadcaster::Broadcast (this=0x1759c40, rHint=...) at 
/home/buildslave/source/libo-core/svl/source/notify/broadcast.cxx:118
#13 0x00007fb873b194d8 in ScBroadcastAreaSlotMachine::AreaBroadcast (this=0x138a120, rHint=...) at 
/home/buildslave/source/libo-core/sc/source/core/data/bcaslot.cxx:663
#14 0x00007fb873d1623c in ScDocument::Broadcast (this=0x1622ff8, rHint=...) at 
/home/buildslave/source/libo-core/sc/source/core/data/documen7.cxx:83
#15 0x00007fb87437962e in ScDocShell::LoadXML (this=0x1622f00, pLoadMedium=0x156dad0, xStor=empty 
uno::Reference) at /home/buildslave/source/libo-core/sc/source/ui/docshell/docsh.cxx:478
#16 0x00007fb874379cf8 in ScDocShell::Load (this=0x1622f00, rMedium=...) at 
/home/buildslave/source/libo-core/sc/source/ui/docshell/docsh.cxx:554
#17 0x00007fb88cc56627 in SfxObjectShell::LoadOwnFormat (this=0x1622f00, rMedium=...) at 
/home/buildslave/source/libo-core/sfx2/source/doc/objstor.cxx:3046
#18 0x00007fb88cc4a50d in SfxObjectShell::DoLoad (this=0x1622f00, pMed=0x156dad0) at 
/home/buildslave/source/libo-core/sfx2/source/doc/objstor.cxx:708
#19 0x00007fb88cc8e28e in SfxBaseModel::load (this=0x1799b30, seqArguments=uno::Sequence of length 
13 = {...}) at /home/buildslave/source/libo-core/sfx2/source/doc/sfxbasemodel.cxx:1891
#20 0x00007fb88cd70fc8 in SfxFrameLoader_Impl::load (this=0x130b340, rArgs=uno::Sequence of length 
10 = {...}, _rTargetFrame=uno::Reference to (framework::Frame *) 0x130cae8)
    at /home/buildslave/source/libo-core/sfx2/source/view/frmload.cxx:598
#21 0x00007fb87987919d in framework::LoadEnv::impl_loadContent (this=0x7fb868e6dba0) at 
/home/buildslave/source/libo-core/framework/source/loadenv/loadenv.cxx:1184
#22 0x00007fb87987572f in framework::LoadEnv::startLoading (this=0x7fb868e6dba0) at 
/home/buildslave/source/libo-core/framework/source/loadenv/loadenv.cxx:402
#23 0x00007fb879874336 in framework::LoadEnv::loadComponentFromURL (xLoader=uno::Reference to 
(framework::Desktop *) 0x1130268, xContext=uno::Reference to (cppu::ComponentContext *) 0x6c84f0, 
    sURL="file:///home/buildslave/source/ods/fdo33365-1.xls", sTarget="_blank", nFlags=0, 
lArgs=uno::Sequence of length 2 = {...}) at 
/home/buildslave/source/libo-core/framework/source/loadenv/loadenv.cxx:173
#24 0x00007fb8798aff03 in framework::Desktop::loadComponentFromURL (this=0x1130150, 
sURL="file:///home/buildslave/source/ods/fdo33365-1.xls", sTargetFrameName="_blank", 
nSearchFlags=0, 
    lArguments=uno::Sequence of length 2 = {...}) at 
/home/buildslave/source/libo-core/framework/source/services/desktop.cxx:616
#25 0x00007fb87c06864f in gcc3::callVirtualMethod(void*, unsigned int, void*, 
_typelib_TypeDescriptionReference*, bool, unsigned long*, unsigned int, unsigned long*, unsigned 
int, double*, unsigned int) ()
   from /home/buildslave/build/instdir/ure/lib/libgcc3_uno.so
#26 0x00007fb87c06768a in cpp_call (pThis=0x17964c0, aVtableSlot=..., pReturnTypeRef=0x7a9310, 
nParams=4, pParams=0x18dceb0, pUnoReturn=0x1a12990, pUnoArgs=0x1a732d0, ppUnoExc=0x7fb868e6e6d8)
    at /home/buildslave/source/libo-core/bridges/source/cpp_uno/gcc3_linux_x86-64/uno2cpp.cxx:237
#27 0x00007fb87c068065 in bridges::cpp_uno::shared::unoInterfaceProxyDispatch (pUnoI=0x17964c0, 
pMemberDescr=0x15cb6d0, pReturn=0x1a12990, pArgs=0x1a732d0, ppException=0x7fb868e6e6d8)
    at /home/buildslave/source/libo-core/bridges/source/cpp_uno/gcc3_linux_x86-64/uno2cpp.cxx:433
#28 0x00007fb87a0d46c4 in binaryurp::IncomingRequest::execute_throw (this=0x144fea0, 
returnValue=0x7fb868e6eb30, outArguments=0x7fb868e6eaa0)
    at /home/buildslave/source/libo-core/binaryurp/source/incomingrequest.cxx:245
#29 0x00007fb87a0d356f in binaryurp::IncomingRequest::execute (this=0x144fea0) at 
/home/buildslave/source/libo-core/binaryurp/source/incomingrequest.cxx:74
#30 0x00007fb87a0f192b in binaryurp::(anonymous namespace)::request (pThreadSpecificData=0x144fea0) 
at /home/buildslave/source/libo-core/binaryurp/source/reader.cxx:87
#31 0x00007fb88ddec426 in cppu_threadpool::JobQueue::enter (this=0x1a120b0, nDisposeId=24763808, 
bReturnWhenNoJob=1 '\001') at 
/home/buildslave/source/libo-core/cppu/source/threadpool/jobqueue.cxx:115
#32 0x00007fb88ddefcc5 in cppu_threadpool::ORequestThread::run (this=0x179dda0) at 
/home/buildslave/source/libo-core/cppu/source/threadpool/thread.cxx:172
#33 0x00007fb88ddf04f3 in osl::threadFunc (param=0x179ddb0) at 
/home/buildslave/source/libo-core/include/osl/thread.hxx:187
#34 0x00007fb88e84c2e7 in osl_thread_start_Impl (pData=0x17f6100) at 
/home/buildslave/source/libo-core/sal/osl/unx/thread.c:236
#35 0x000000352dc07c53 in start_thread () from /lib64/libpthread.so.0
#36 0x000000352d4f5dbd in clone () from /lib64/libc.so.6

Thread 1 (Thread 0x7fb8835cb840 (LWP 22324)):
#0  0x000000352dc0dddd in __lll_lock_wait () from /lib64/libpthread.so.0
#1  0x000000352dc09bbc in _L_lock_839 () from /lib64/libpthread.so.0
#2  0x000000352dc09ad9 in pthread_mutex_lock () from /lib64/libpthread.so.0
#3  0x00007fb88e84183a in osl_acquireMutex (pMutex=0x6b3b40) at 
/home/buildslave/source/libo-core/sal/osl/unx/mutex.c:104
#4  0x00007fb889bbb9d0 in vcl::SolarMutexObject::acquire (this=0x6afc40) at 
/home/buildslave/source/libo-core/vcl/source/app/solarmutex.cxx:35
#5  0x00007fb88a171ee6 in SalYieldMutex::acquire (this=0x6afc40) at 
/home/buildslave/source/libo-core/vcl/generic/app/geninst.cxx:49
#6  0x00007fb88a1720bf in SalGenericInstance::AcquireYieldMutex (this=0x6b3ee0, nCount=1) at 
/home/buildslave/source/libo-core/vcl/generic/app/geninst.cxx:108
#7  0x00007fb87c67609a in SvpSalInstance::DoReleaseYield (this=0x6b3ee0, nTimeoutMS=50) at 
/home/buildslave/source/libo-core/vcl/headless/svpinst.cxx:329
#8  0x00007fb87c675f91 in SvpSalInstance::Yield (this=0x6b3ee0, bWait=true, 
bHandleAllCurrentEvents=false) at /home/buildslave/source/libo-core/vcl/headless/svpinst.cxx:311
#9  0x00007fb889bc0357 in ImplYield (i_bWait=true, i_bAllEvents=false) at 
/home/buildslave/source/libo-core/vcl/source/app/svapp.cxx:364
#10 0x00007fb889bbca91 in Application::Yield () at 
/home/buildslave/source/libo-core/vcl/source/app/svapp.cxx:396
#11 0x00007fb889bbca41 in Application::Execute () at 
/home/buildslave/source/libo-core/vcl/source/app/svapp.cxx:345
#12 0x00007fb88e55e3f9 in desktop::Desktop::Main (this=0x7fff9ebbd890) at 
/home/buildslave/source/libo-core/desktop/source/app/app.cxx:1715
#13 0x00007fb889bc9c6b in ImplSVMain () at 
/home/buildslave/source/libo-core/vcl/source/app/svmain.cxx:162
#14 0x00007fb889bc9d75 in SVMain () at 
/home/buildslave/source/libo-core/vcl/source/app/svmain.cxx:198
#15 0x00007fb88e59fffd in soffice_main () at 
/home/buildslave/source/libo-core/desktop/source/app/sofficemain.cxx:85
#16 0x000000000040096c in sal_main () at 
/home/buildslave/source/libo-core/desktop/source/app/main.c:48
#17 0x000000000040094d in main (argc=8, argv=0x7fff9ebbdb78) at 
/home/buildslave/source/libo-core/desktop/source/app/main.c:47