[
https://jira.nuxeo.com/browse/LIVED-159?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=91498#action_91498
]
Olivier Lacroix commented on LIVED-159:
---------------------------------------
I am more than willing to help debug that issue. Please find below the result
of running wireshark during a liveedit opening. 172.20.65.61 is the client, and
172.20.67.5 the nuxeo server
No. Time Source Destination Protocol
Info
1 10:01:25.590758 172.20.65.61 172.20.67.5 TCP
8570 > http [SYN] Seq=0 Win=8192 Len=0 MSS=1460 WS=2 SACK_PERM=1
2 10:01:25.590948 172.20.67.5 172.20.65.61 TCP
http > 8570 [SYN, ACK] Seq=0 Ack=1 Win=5840 Len=0 MSS=1460 SACK_PERM=1 WS=9
3 10:01:25.591016 172.20.65.61 172.20.67.5 TCP
8570 > http [ACK] Seq=1 Ack=1 Win=65700 Len=0
4 10:01:25.591315 172.20.65.61 172.20.67.5 HTTP
GET
/nuxeo/nxliveedit.faces?action=edit&repoID=default&docRef=4b0141c8-4228-49bf-8df2-f4a9a512c2eb&schema=file&blobField=content&filenameField=filename&conversationId=0NXMAIN1&JSESSIONID=7EC6B85DB2392591C799B86433E39791
HTTP/1.1
5 10:01:25.591569 172.20.67.5 172.20.65.61 TCP
http > 8570 [ACK] Seq=1 Ack=1231 Win=8704 Len=0
6 10:01:25.605209 172.20.67.5 172.20.65.61 TCP
[TCP segment of a reassembled PDU]
7 10:01:25.605213 172.20.67.5 172.20.65.61 HTTP/XML
HTTP/1.1 200 OK
8 10:01:25.605598 172.20.65.61 172.20.67.5 TCP
8570 > http [ACK] Seq=1231 Ack=1557 Win=65700 Len=0
9 10:01:40.602781 172.20.67.5 172.20.65.61 TCP
http > 8570 [FIN, ACK] Seq=1557 Ack=1231 Win=8704 Len=0
10 10:01:40.602852 172.20.65.61 172.20.67.5 TCP
8570 > http [ACK] Seq=1231 Ack=1558 Win=65700 Len=0
11 10:01:40.694864 172.20.65.61 172.20.67.5 TCP
8572 > http [SYN] Seq=0 Win=8192 Len=0 MSS=1460 WS=2 SACK_PERM=1
12 10:01:40.695065 172.20.67.5 172.20.65.61 TCP
http > 8572 [SYN, ACK] Seq=0 Ack=1 Win=5840 Len=0 MSS=1460 SACK_PERM=1 WS=9
13 10:01:40.695132 172.20.65.61 172.20.67.5 TCP
8572 > http [ACK] Seq=1 Ack=1 Win=65700 Len=0
14 10:01:40.697466 172.20.65.61 172.20.67.5 HTTP
GET
/nuxeo/restAPI/default/4b0141c8-4228-49bf-8df2-f4a9a512c2eb/downloadFile?filenamePropertyName=file%3afilename&blobPropertyName=file%3acontent
HTTP/1.1
.....
940 10:01:40.800469 172.20.65.61 172.20.67.5 TCP
8572 > http [ACK] Seq=372 Ack=908128 Win=65700 Len=0
941 10:01:40.820098 172.20.65.61 172.20.67.5 HTTP
GET /nuxeo/restAPI/default/4b0141c8-4228-49bf-8df2-f4a9a512c2eb/Locking/status
HTTP/1.1
942 10:01:40.824807 172.20.67.5 172.20.65.61 HTTP/XML
HTTP/1.1 200 OK
943 10:01:40.832677 172.20.65.61 172.20.67.5 HTTP
GET /nuxeo/restAPI/default/4b0141c8-4228-49bf-8df2-f4a9a512c2eb/Locking/status
HTTP/1.1
944 10:01:40.837605 172.20.67.5 172.20.65.61 HTTP/XML
HTTP/1.1 200 OK
945 10:01:40.842985 172.20.65.61 172.20.67.5 HTTP
GET /nuxeo/restAPI/default/4b0141c8-4228-49bf-8df2-f4a9a512c2eb/Locking/lock
HTTP/1.1
946 10:01:40.849517 172.20.67.5 172.20.65.61 HTTP/XML
HTTP/1.1 200 OK
947 10:01:41.056306 172.20.67.5 172.20.65.61 HTTP/XML
[TCP Retransmission] HTTP/1.1 200 OK
948 10:01:41.056364 172.20.65.61 172.20.67.5 TCP
8572 > http [ACK] Seq=1210 Ack=908887 Win=64940 Len=0 SLE=908590 SRE=908887
949 10:01:41.751124 172.20.65.61 172.20.67.5 TCP
8570 > http [FIN, ACK] Seq=1231 Ack=1558 Win=65700 Len=0
950 10:01:41.751390 172.20.67.5 172.20.65.61 TCP
http > 8570 [ACK] Seq=1558 Ack=1232 Win=8704 Len=0
951 10:01:44.612721 172.20.65.61 172.20.67.5 TCP
8572 > http [RST, ACK] Seq=1210 Ack=908887 Win=0 Len=0
As you see, the transfer of the file itself is instantaneaous, but there is a
really long delay between packet 8 and 9. I don't really have any knowledge
about that stuff, but it seems to me that the client waits until the FIN,ACK
packet of the server to proceed, and that packet takes a loooong time to come.
Below is the relevant section of the Liveedit log (the time reference is off):
2011-03-31 09:52:57,836 [1] INFO Launcher - Started
2011-03-31 09:52:57,876 [1] INFO Launcher - Live Edit called with this
parameter : [...].xml
2011-03-31 09:52:58,375 [1] INFO Launcher - loading xml file [...]
2011-03-31 09:52:58,376 [1] INFO Launcher - action selected :edit
2011-03-31 09:52:58,389 [1] INFO Launcher - Connecting to http://[...]
2011-03-31 09:53:11,929 [1] INFO Launcher - [...] download successful.
2011-03-31 09:53:11,978 [1] INFO Launcher - Selection MS Office Application
with mimetype :
"application/vnd.openxmlformats-officedocument.wordprocessingml.document"
2011-03-31 09:53:13,841 [1] INFO Launcher - Version of office detected : 12.0
Your insight would be much appreciated.
> LiveEdit is very slow to open a document
> ----------------------------------------
>
> Key: LIVED-159
> URL: https://jira.nuxeo.com/browse/LIVED-159
> Project: Nuxeo Live Desktop
> Issue Type: Improvement
> Components: NLE MSO
> Environment: Windows Seven Client, debian server
> Reporter: Olivier Lacroix
> Assignee: Benjamin Jalon
>
> Opening a document from nuxeo with liveEdit is really slow. In the log (urls
> removed) I get :
> 2011-02-25 10:16:45,942 [1] INFO Launcher - Started
> 2011-02-25 10:16:46,038 [1] INFO Launcher - Live Edit called with this
> parameter : [...]
> 2011-02-25 10:16:46,745 [1] INFO Launcher - loading xml file [...]
> 2011-02-25 10:16:46,746 [1] INFO Launcher - action selected :edit
> 2011-02-25 10:16:46,809 [1] INFO Launcher - Connecting to [...]
> 2011-02-25 10:17:12,574 [1] INFO Launcher - [...] download successful.
> 2011-02-25 10:17:12,622 [1] INFO Launcher - Selection MS Office Application
> with mimetype :
> "application/vnd.openxmlformats-officedocument.wordprocessingml.document"
> 2011-02-25 10:17:14,995 [1] INFO Launcher - Version of office detected : 12.0
> So that is 26 seconds to download the document. Copy&Pasting the rest url in
> the browser opens it right away.
> Do you have any idea what LiveEdit is waiting for ?
--
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators:
https://jira.nuxeo.com/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira
_______________________________________________
ECM-tickets mailing list
[email protected]
http://lists.nuxeo.com/mailman/listinfo/ecm-tickets
