Bug 69496 - FILEOPEN: It takes 15 minutes to display a document
Summary: FILEOPEN: It takes 15 minutes to display a document
Status: RESOLVED WORKSFORME
Alias: None
Product: LibreOffice
Classification: Unclassified
Component: Writer (show other bugs)
Version:
(earliest affected)
3.6.3.2 release
Hardware: x86 (IA32) Linux (All)
: medium normal
Assignee: Not Assigned
URL:
Whiteboard: BSA needsWindows
Keywords: filter:doc, haveBacktrace, perf
Depends on:
Blocks: DOC
  Show dependency treegraph
 
Reported: 2013-09-17 21:36 UTC by Christopher Yeleighton
Modified: 2018-06-13 16:59 UTC (History)
6 users (show)

See Also:
Crash report or crash signature:


Attachments
The offending document (48.00 KB, application/msword)
2013-09-17 21:36 UTC, Christopher Yeleighton
Details
bt at random (22.65 KB, text/plain)
2013-09-18 19:19 UTC, Julien Nabet
Details

Note You need to log in before you can comment on or make changes to this bug.
Description Christopher Yeleighton 2013-09-17 21:36:43 UTC
Created attachment 86027 [details]
The offending document

Problem description: 

Steps to reproduce:
1. Tell Writer to open the document "Z punktu widzenia różowego trójkąta.DOC".

Current behavior:
The program soffice.bin spends about 15 minuts waiting for POSIX connect to fail.  The document window is open but it does not update.
The program wakes up immediately after you switch all Ethernet connections off.

Program received signal SIGINT, Interrupt.
connect () at ../sysdeps/unix/sysv/linux/i386/socket.S:98
98      in ../sysdeps/unix/sysv/linux/i386/socket.S
(gdb) bt
#0  connect () at ../sysdeps/unix/sysv/linux/i386/socket.S:98
#1  0xac020b48 in raw_connect (salen=<optimized out>, sa=<optimized out>, 
    fd=<optimized out>) at ne_socket.c:1167
#2  timed_connect (sock=sock@entry=0xa67f090, fd=fd@entry=35, 
    sa=sa@entry=0xbf95b034, salen=16) at ne_socket.c:1241
#3  0xac0217e6 in connect_socket (port=<optimized out>, fd=35, sock=0xa67f090, 
    addr=<optimized out>) at ne_socket.c:1274
#4  ne_sock_connect (sock=0xa67f090, addr=0x85d38c8, port=80)
    at ne_socket.c:1445
#5  0xac017b32 in do_connect (sess=sess@entry=0xa6cfce0, 
    host=host@entry=0xa6cfcf4) at ne_request.c:1510
#6  0xac019913 in open_connection (sess=0xa6cfce0) at ne_request.c:1582
#7  send_request (req=req@entry=0xa60f730, request=<optimized out>, 
    request=<optimized out>) at ne_request.c:955
#8  0xac019c00 in ne_begin_request (req=req@entry=0xa60f730)
    at ne_request.c:1189
#9  0xac0192c5 in ne_request_dispatch (req=0xa60f730) at ne_request.c:1400
#10 0xac0641f9 in webdav_ucp::NeonHeadRequest::NeonHeadRequest(ne_session_s*, rtl::OUString const&, std::vector<rtl::OUString, std::allocator<rtl::OUString> > const&, webdav_ucp::DAVResource&, int&) ()
   from /usr/lib/libreoffice/program/../program/libucpdav1.so
#11 0xac06dd3f in webdav_ucp::NeonSession::HEAD(rtl::OUString const&, std::vector<rtl::OUString, std::allocator<rtl::OUString> > const&, webdav_ucp::DAVResource&, webdav_ucp::DAVRequestEnvironment const&) ()
   from /usr/lib/libreoffice/program/../program/libucpdav1.so
#12 0xac05fc83 in webdav_ucp::DAVResourceAccess::HEAD(std::vector<rtl::OUString, std::allocator<rtl::OUString> > const&, webdav_ucp::DAVResource&, com::sun::star::uno::Reference<com::sun::star::ucb::XCommandEnvironment> const&) ()
   from /usr/lib/libreoffice/program/../program/libucpdav1.so
#13 0xac088467 in webdav_ucp::Content::getPropertyValues(com::sun::star::uno::Sequence<com::sun::star::beans::Property> const&, com::sun::star::uno::Reference<com::sun::star::ucb::XCommandEnvironment> const&) ()
   from /usr/lib/libreoffice/program/../program/libucpdav1.so
#14 0xac08b5ff in webdav_ucp::Content::execute(com::sun::star::ucb::Command const&, long, com::sun::star::uno::Reference<com::sun::star::ucb::XCommandEnvironment> const&) () from /usr/lib/libreoffice/program/../program/libucpdav1.so

Expected behavior:
I have no idea what the program is trying to connect to but the time out should be acceptable to a human operator (shorter).
              
Operating System: openSUSE
Version: 3.6.3.2 release
Comment 1 Arnaud Versini 2013-09-17 21:50:17 UTC
confirmed on Ubuntu 64/LibreOffice 4.1.1.2
Comment 2 Julien Nabet 2013-09-18 19:19:24 UTC
Created attachment 86097 [details]
bt at random

On pc Debian x86-64 with master sources updated today, I confirm this.
I attached console logs and a bt at random
Comment 3 Julien Nabet 2013-09-18 19:20:26 UTC
Michael: any idea who could take a look to this one? (bt+console logs attached)
Comment 4 Michael Meeks 2013-09-18 19:30:24 UTC
No idea - sorry; it's a nice bug report for sure; patches appreciated. I guess there is some web link to an image:

http://<somewhere>/bmi/www.e-teatr.pl/pl/zdjecia/dramatopisarze/obcy/ameryka/%28150x0%29kushner_tony.jpg

And that connecting there is timing out / not working at all in whatever way and that this is the fundamental problem here.

I imagine that clobbering that stack trace to queue an asynchronous load of that:

#26 0x00007f2ad79bd7e2 in SfxMedium::GetMedium_Impl (this=0x7fff52186210) at /home/julien/compile-libreoffice/libo/sfx2/source/doc/docfile.cxx:2360
#27 0x00007f2ad79b46dc in SfxMedium::GetInStream (this=0x7fff52186210) at /home/julien/compile-libreoffice/libo/sfx2/source/doc/docfile.cxx:548
#28 0x00007f2ad79bdf91 in SfxMedium::DownLoad (this=0x7fff52186210, aLink=...) at /home/julien/compile-libreoffice/libo/sfx2/source/doc/docfile.cxx:2441
#29 0x00007f2ab8fc29a0 in ImpLoadLinkedGraphic (aFileName="http://1.1.1.1/bmi/www.e-teatr.pl/pl/zdjecia/dramatopisarze/obcy/ameryka/%28150x0%29kushner_tony.jpg", 
    aFilterName="JPEG - Joint Photographic Experts Group") at /home/julien/compile-libreoffice/libo/svx/source/svdraw/svdograf.cxx:110
#30 0x00007f2ab8fc5013 in SdrGrafObj::ImpUpdateGraphicLink (this=0x2cfb070, bAsynchron=false) at /home/julien/compile-libreoffice/libo/svx/source/svdraw/svdograf.cxx:736
#31 0x00007f2ab8fc4884 in SdrGrafObj::ForceSwapIn (this=0x2cfb070) at /home/julien/compile-libreoffice/libo/svx/source/svdraw/svdograf.cxx:611
#32 0x00007f2ab76fa3cf in SvxMSDffManager::ImportGraphic (this=0x2cfb810, rSt=..., rSet=..., rObjData=...)
    at /home/julien/compile-libreoffice/libo/filter/source/msfilter/msdffimp.cxx:3932
#33 0x00007f2ab76fc020 in SvxMSDffManager::ImportShape (this=0x2cfb810, rHd=..., rSt=..., pClientData=0x7fff52187c00, rClientRect=Rectangle = {...}, 
    rGlobalChildRect=Rectangle = {...}, nCalledByGroup=0, pShapeId=0x0) at /home/julien/compile-libreoffice/libo/filter/source/msfilter/msdffimp.cxx:4229
#34 0x00007f2ab76fa7b3 in SvxMSDffManager::ImportObj (this=0x2cfb810, rSt=..., pClientData=0x7fff52187c00, rClientRect=Rectangle = {...}, rGlobalChildRect=Rectangle = {...}, 
    nCalledByGroup=0, pShapeId=0x0) at /home/julien/compile-libreoffice/libo/filter/source/msfilter/msdffimp.cxx:3955
#35 0x00007f2ab7704a54 in SvxMSDffManager::GetShape (this=0x2cfb810, nId=1026, rpShape=@0x7fff52187cf8: 0x0, rData=...)
    at /home/julien/compile-libreoffice/libo/filter/source/msfilter/msdffimp.cxx:6105
#36 0x00007f2aa61cf9c0 in SwWW8ImplReader::Read_GrafLayer (this=0x2caece0, nGrafAnchorCp=262) at /home/julien/compile-libreoffice/libo/sw/source/filter/ww8/ww8graf.cxx:2448

Rather than having the 'Download' phase in the import is the right thing to do.
Comment 5 tommy27 2014-10-19 19:14:31 UTC
tested under Win7x64 with a 5 years old laptop and it took just 2 minutes to load document under LO 4.3.2.2
what's your current performance?
Comment 6 Julien Nabet 2014-10-20 17:25:24 UTC
tommy27: I still can't open the file after several minutes on pc Debian x86-64 with 4.3.2 LO Debian package

Master sources updated yesterday show this:
warn:unotools.misc:2789:1:unotools/source/misc/mediadescriptor.cxx:736: caught Exception "" while opening <http://1.1.1.1/bmi/www.e-teatr.pl/pl/zdjecia/dramatopisarze/obcy/ameryka/%28150x0%29kushner_tony.jpg>
Comment 7 Xisco Faulí 2015-09-08 15:37:35 UTC
it takes 1 minute and 50 seconds to open in

Version: 5.0.1.2
Build ID: 81898c9f5c0d43f3473ba111d7b351050be20261
Locale: es-ES (es_ES)

on Windows 7 (64-bit)
Comment 8 tommy27 2015-09-08 15:49:21 UTC
any Linux user can retest with LibO 5.x ?
Comment 9 Julien Nabet 2015-09-08 19:58:06 UTC
With LO Debian package 5.0.1.2, I stopped to wait after 3 minutes. (i5, 6GB with just Iceweasel, Icedove and some console opened)
Comment 10 Giuseppe Castagno (aka beppec56) 2015-12-08 16:45:35 UTC
Looks similar to bug 90700.
The couple of path I have ready for  bug 90700 seem to cure this as well.
Comment 11 Robinson Tryon (qubit) 2015-12-09 17:44:27 UTC Comment hidden (obsolete)
Comment 12 Giuseppe Castagno (aka beppec56) 2016-03-21 09:31:31 UTC
In LO 5.1.0 this bug should be fixed.

Please see bug 90700, comment 13 for a way to adjust http(s) protocol timeouts.
Comment 13 Telesto 2016-12-03 20:07:14 UTC
Time to open: around 25 seconds
Version: 5.4.0.0.alpha0+
Build ID: 33f5bc54aaa7fe7aa9335726e30f9c349155e04d
CPU Threads: 4; OS Version: Windows 6.2; UI Render: default; 
TinderBox: Win-x86@62-merge-TDF, Branch:MASTER, Time: 2016-12-01_23:21:05
Locale: nl-NL (nl_NL); Calc: CL
Comment 14 QA Administrators 2017-12-10 16:38:31 UTC Comment hidden (obsolete)
Comment 15 Xisco Faulí 2018-06-13 12:28:39 UTC
I can no longer reproduce it in

Version: 6.2.0.0.alpha0+
Build ID: 4c6e11886a9d396bf7be18e9e3209a73c6e303ad
CPU threads: 4; OS: Linux 4.13; UI render: default; VCL: gtk3; 
Locale: ca-ES (ca_ES.UTF-8); Calc: group threaded

nor in

Version: 6.0.4.2
Build ID: 1:6.0.4~rc2-0ubuntu0.16.04.1
CPU threads: 4; OS: Linux 4.13; UI render: default; VCL: gtk3; 
Locale: ca-ES (ca_ES.UTF-8); Calc: group

Closing as RESOLVED WORKSFORME

@Julien Nabet, Could you please doublecheck?
Comment 16 Julien Nabet 2018-06-13 16:49:57 UTC
With LO Debian package 6.0.5, I don't reproduce this indeed but it seems there's a image at the beginning which doesn't display.

Idem with master sources updated today.
I noticed this kind of log:
warn:ucb.ucp.webdav:5053:5131:ucb/source/ucp/webdav-neon/NeonSession.cxx:1816: Neon returned NE_ERROR, http response status code was: 0 'Server certificate verification failed: issuer is not trusted'

Anyway, the initial pb is no more there.
Comment 17 Xisco Faulí 2018-06-13 16:59:34 UTC
(In reply to Julien Nabet from comment #16)
> With LO Debian package 6.0.5, I don't reproduce this indeed but it seems
> there's a image at the beginning which doesn't display.
> 
> Idem with master sources updated today.
> I noticed this kind of log:
> warn:ucb.ucp.webdav:5053:5131:ucb/source/ucp/webdav-neon/NeonSession.cxx:
> 1816: Neon returned NE_ERROR, http response status code was: 0 'Server
> certificate verification failed: issuer is not trusted'
> 
> Anyway, the initial pb is no more there.

Same behaviour on MSO 2010...