First creation of a new Memo is very slow

HCL Domino Domino Forum
1

Hello,

on our clients (V 8.5 de and en) the first open of “new memo” is very slow - approx. 40 sec!!!

The same on creating a new calender entry.

The problem occurs also after new fresh client installation and also on a new local replica of the Mail-Database. The Mail-Template is 8.5 (but also with a 8.02 Mail-Template we have the same problem.

We are using policies, but with no special settings (so I think). I had migrated the ODS to 5.1 but it solves not the problem…

Also

What can I do? I had no idea at this time.

Has someone the same problem???

Thanks for any hints!

Regards

Uwe

PS: this is the debug output (sorry in german) - I see no problems…

ndebug_PDMAC732_2009_03_19@12_24_17.txt

IM Thread info: (12:24:18,34)

Created successfully

Thread Id: (0x00000D4C)

(1-2 [1]) POLL_DEL_SEQNUM: (Connect to notes2/ILB/DE: 0 ms) (OPEN_SESSION: 1 ms)

3 ms. [110+28=138]

(2-10 [2]) OPEN_DB(CN=notes2/O=ILB/C=DE!!mail\sso4.nsf): (Connect to notes2/ILB/DE: 139 ms) (Exch names: 0 ms)(Authenticate: 201 ms.)

(OPEN_SESSION: 1 ms)

1 ms. [134+290=424]

(3-10 [3]) SERVER_AVAILABLE_LITE: 1 ms. [26+118=144]

(4-10 [4]) ISDB2_RQST: 1 ms. [14+16=30]

(5-10 [5]) GET_UNREAD_NOTE_TABLE: 1 ms. [290+74=364]

(6-10 [6]) OPEN_NOTE(REPC12574E9:0038D874-NTFFFF0010,03000400): 10 ms. [48+6176=6224]

(7-10 [7]) OPEN_COLLECTION(REPC12574E9:0038D874-NTFFFF0020,0000,0000): 13 ms. [42+772=814]

(8-10 [8]) READ_ENTRIES(REPC12574E9:0038D874-NTFFFF0020): 38 ms. [614+65536=66150]

(9-10 [9]) READ_ENTRIES(REPC12574E9:0038D874-NTFFFF0020): 32 ms. [614+65640=66254]

(10-10 [10]) READ_ENTRIES(REPC12574E9:0038D874-NTFFFF0020): 216 ms. [614+65368=65982]

(11-10 [11]) READ_ENTRIES(REPC12574E9:0038D874-NTFFFF0020): 19.03.2009 12:24:28 Dynamische Client-Konfiguration gestartet

24 ms. [614+65196=65810]

(12-10 [12]) READ_ENTRIES(REPC12574E9:0038D874-NTFFFF0020): 19.03.2009 12:24:29 Dynamische Client-Konfiguration wird initialisiert

(1-10 [13]) NAME_LOOKUP(CN=Test SSO4/O=ILB/C=DE,00000102): 178 ms. [614+65520=66134]

(13-11 [13]) READ_ENTRIES(REPC12574E9:0038D874-NTFFFF0020): (Connect to notes2/ILB/DE: 130 ms) (Exch names: 0 ms)(Authenticate: 2 ms.)

(OPEN_SESSION: 1 ms)

2 ms. [236+80=316]

(2-11 [15]) SERVER_AVAILABLE_LITE: 1 ms. [26+118=144]

(3-11 [16]) SV_INFO_GET_RQST: 1 ms. [14+44=58]

(4-11 [17]) OPEN_DB(CN=notes2/O=ILB/C=DE!!names.nsf): 1 ms. [134+290=424]

(5-11 [18]) FINDDESIGN_NOTES: 0 ms. [44+16=60]

(6-11 [19]) ISDB2_RQST: 0 ms. [14+16=30]

(7-11 [20]) OPEN_COLLECTION(REPC1256B97:004C8B48-NT00002ADE,0000,0000): 1 ms. [42+34=76]

(8-11 [21]) UPDATE_COLLECTION(REPC1256B97:004C8B48-NT00002ADE): 1 ms. [24+12=36]

(9-11 [22]) READ_ENTRIES(REPC1256B97:004C8B48-NT00002ADE): 1 ms. [76+84=160]

(10-11 [23]) CLOSE_COLLECTION(REPC1256B97:004C8B48-NT00002ADE): 0 ms. [12+0=12]

(11-11 [24]) SV_INFO_GET_RQST: 1 ms. [14+44=58]

(12-11 [25]) GET_POLICY_RQST: 4 ms. [1046+1952=2998]

(13-11 [26]) GET_NOTE_INFO_BY_UNID: 1 ms. [52+66=118]

(14-11 [27]) OPEN_NOTE(REPC1256B97:004C8B48-NT000027CA,03408000): 200 ms. [614+65358=65972]

(14-11 [28]) READ_ENTRIES(REPC12574E9:0038D874-NTFFFF0020): 150 ms. [48+4602=4650]

(15-11 [29]) SV_INFO_GET_RQST: 1 ms. [14+44=58]

(16-11 [30]) GET_POLICY_RQST: 8 ms. [1046+15410=16456]

(17-11 [31]) SV_INFO_GET_RQST: 1 ms. [14+44=58]

(18-11 [32]) GET_POLICY_RQST: 7 ms. [1046+5926=6972]

(19-11 [33]) SV_INFO_GET_RQST: 1 ms. [14+44=58]

(20-11 [34]) GET_POLICY_RQST: 8 ms. [1046+9210=10256]

(21-11 [35]) SV_INFO_GET_RQST: 1 ms. [14+44=58]

(22-11 [36]) GET_POLICY_RQST: 4 ms. [1046+2218=3264]

(23-11 [37]) SV_INFO_GET_RQST: 0 ms. [14+44=58]

(24-11 [38]) GET_POLICY_RQST: 4 ms. [1046+3274=4320]

4 ms. [1046+3274=4320] (Sitzung beendet)

(25-11 [39]) NAME_LOOKUP(CN=Test SSO4/O=ILB/C=DE,00000100): (Connect to notes2/ILB/DE: 7 ms) (Exch names: 0 ms)(Authenticate: 2 ms.)

(OPEN_SESSION: 1 ms)

2 ms. [332+172=504]

19.03.2009 12:24:29 Dynamische Client-Konfiguration aktualisiert Richtlinieninformationen

200 ms. [614+33228=33842]

(15-11 [40]) CLOSE_COLLECTION(REPC12574E9:0038D874-NTFFFF0020): 1 ms. [12+0=12]

(16-11 [41]) GET_NAMED_OBJECT_ID($PrivateDesign): 3 ms. [40+24=64]

(17-11 [42]) OPEN_COLLECTION(REPC12574E9:0038D874-NT0000183A,0000,0000): 17 ms. [42+842=884]

(18-11 [43]) READ_ENTRIES(REPC12574E9:0038D874-NT0000183A): 5 ms. [76+84=160]

(19-11 [44]) CLOSE_COLLECTION(REPC12574E9:0038D874-NT0000183A): 0 ms. [12+0=12]

(20-11 [45]) DB_MODIFIED_TIME: 1 ms. [14+68=82]

19.03.2009 12:24:29 Dynamische Client-Konfiguration: Richtlinienlesezeichen werden angewandt

(21-11 [46]) GET_MULT_NOTE_INFO_BY_UNID: 38 ms. [1160+2294=3454]

19.03.2009 12:24:29 Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung

19.03.2009 12:24:29 Dynamische Client-Konfiguration heruntergefahren

2 ms. [-546±496=-1042] (Sitzung beendet)

(22-11 [47]) DB_REPLINFO_GET: 1 ms. [14+32=46]

(23-11 [48]) DB_REPLINFO_GET: 1 ms. [14+32=46]

(24-11 [49]) DB_INFO_GET: 1 ms. [14+140=154]

(25-11 [50]) OPEN_NOTE(REPC12574E9:0038D874-NTFFFF0010,03400000): 2 ms. [48+5984=6032]

(26-11 [51]) OPEN_NOTE(REPC12574E9:0038D874-NTFFFF0010,03400000): 5 ms. [48+5984=6032]

(27-11 [52]) OPEN_NOTE(REPC12574E9:0038D874-NTFFFF0010,03000400): 2 ms. [48+6176=6224]

(28-11 [53]) GET_NAMED_OBJECT_ID($profile_015calendarprofile_): 6 ms. [54+24=78]

(29-11 [54]) OPEN_NOTE(REPC12574E9:0038D874-NT000008FA,00400020): 7 ms. [48+17416=17464]

(30-11 [55]) GET_NAMED_OBJECT_ID($profile_024archive database profile_): 4 ms. [64+24=88]

(31-11 [56]) OPEN_NOTE(REPC12574E9:0038D874-NT000008FE,00400020): 1 ms. [48+218=266]

(32-12 [57]) OPEN_COLLECTION(REPC12574E9:0038D874-NT000013BE,0040,4000): 7 ms. [130+52=182]

(33-12 [58]) GET_COLLATION: 2 ms. [12+14=26]

(34-12 [59]) POLL_DEL_SEQNUM: (35-12 [59]) READ_ENTRIES(REPC12574E9:0038D874-NT000013BE): 2 ms. [76+3566=3642]

(36-12 [60]) GET_ALLFOLDERCHANGES_RQST: 1 ms. [62+64=126]

(37-12 [61]) GET LAST INDEX TIME: 2 ms. [14+68=82]

(Connect to notes2/ILB/DE: 114 ms) (OPEN_SESSION: 0 ms)

1 ms. [110+28=138]

(38-13 [63]) GET_DBOPTIONS: 1 ms. [46+60=106]

(39-13 [64]) SET_DBOPTIONS: 1 ms. [78+44=122]

(40-14 [65]) POLL_DEL_SEQNUM: (Connect to notes2/ILB/DE: 116 ms) (OPEN_SESSION: 2 ms)

1 ms. [110+28=138]

19.03.2009 12:24:40 Dynamische Client-Konfiguration gestartet

19.03.2009 12:24:41 Dynamische Client-Konfiguration wird initialisiert

19.03.2009 12:24:41 Dynamische Client-Konfiguration aktualisiert Richtlinieninformationen

19.03.2009 12:24:41 Dynamische Client-Konfiguration: Richtlinienlesezeichen werden angewandt

19.03.2009 12:24:41 Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung

19.03.2009 12:24:41 Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie

19.03.2009 12:24:41,44 [0C94:0002-0C1C] DynConfig> Found $DPLocked field: $DPLocked on policy note. Copying to location doc

19.03.2009 12:24:41,44 [0C94:0002-0C1C] DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note. Copying to location doc

19.03.2009 12:24:41 Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie

19.03.2009 12:24:41,44 [0C94:0002-0C1C] DynConfig> Found $DPLocked field: $DPLocked on policy note. Copying to location doc

19.03.2009 12:24:41,44 [0C94:0002-0C1C] DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note. Copying to location doc

19.03.2009 12:24:41 Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie

19.03.2009 12:24:41,44 [0C94:0002-0C1C] DynConfig> Found $DPLocked field: $DPLocked on policy note. Copying to location doc

19.03.2009 12:24:41,44 [0C94:0002-0C1C] DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note. Copying to location doc

19.03.2009 12:24:41 Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie

19.03.2009 12:24:41,44 [0C94:0002-0C1C] DynConfig> Found $DPLocked field: $DPLocked on policy note. Copying to location doc

19.03.2009 12:24:41,44 [0C94:0002-0C1C] DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note. Copying to location doc

19.03.2009 12:24:41 Dynamische Client-Konfiguration heruntergefahren

(41-24 [66]) POLL_DEL_SEQNUM: (Connect to notes2/ILB/DE: 113 ms) (OPEN_SESSION: 1 ms)

0 ms. [110+28=138]

(42-60 [67]) SERVER_TIME: 1 ms. [10+20=30]

(43-135 [68]) CLOSE_COLLECTION(REPC12574E9:0038D874-NT000013BE): 1 ms. [12+0=12]

(44-136 [69]) CLOSE_DB(REPC12574E9:0038D874): 0 ms. [14+0=14]

(45-139 [70]) OPEN_DB(CN=notes2/O=ILB/C=DE!!mail\sso4.nsf): 1 ms. [134+290=424]

(46-139 [71]) ISDB2_RQST: 1 ms. [14+16=30]

(47-139 [72]) GET_UNREAD_NOTE_TABLE: 0 ms. [290+74=364]

(48-139 [73]) OPEN_NOTE(REPC12574E9:0038D874-NTFFFF0010,03000400): 2 ms. [48+6176=6224]

(49-139 [74]) GET_NAMED_OBJECT_ID($profile_015calendarprofile_): 1 ms. [54+24=78]

(50-139 [75]) OPEN_NOTE(REPC12574E9:0038D874-NT000008FA,00400020): 4 ms. [48+17416=17464]

(51-139 [76]) GET_NAMED_OBJECT_ID($profile_024archive database profile_): 1 ms. [64+24=88]

(52-139 [77]) OPEN_NOTE(REPC12574E9:0038D874-NT000008FE,00400020): 1 ms. [48+218=266]

(53-139 [78]) OPEN_COLLECTION(REPC12574E9:0038D874-NT000013BE,0040,4000): 1 ms. [130+52=182]

(54-139 [79]) GET_COLLATION: 0 ms. [12+14=26]

(55-139 [80]) READ_ENTRIES(REPC12574E9:0038D874-NT000013BE): 12 ms. [76+3566=3642]

(56-139 [81]) GET_ALLFOLDERCHANGES_RQST: 0 ms. [62+64=126]

(57-139 [82]) GET LAST INDEX TIME: 1 ms. [14+68=82]

(58-139 [83]) GET_DBOPTIONS: 0 ms. [46+60=106]

(59-139 [84]) SET_DBOPTIONS: 0 ms. [78+44=122]

(60-140 [85]) POLL_DEL_SEQNUM: (Connect to notes2/ILB/DE: 2 ms) (OPEN_SESSION: 0 ms)

0 ms. [110+28=138]

(61-440 [86]) CLOSE_COLLECTION(REPC12574E9:0038D874-NT000013BE): 0 ms. [12+0=12]

(62-440 [87]) CLOSE_DB(REPC12574E9:0038D874): 1 ms. [14+0=14]

StopReconnecting - timer doesn’t exist (12:31:38,63)

Exiting IM Thread. (0x00000D4C) (12:31:42,02)

1 ms. [-8926±505318=-514244] (Sitzung beendet)

2

Subject: Spell check

I solved this issue disabling spell check.

Bye

Domenico Pozza

3

Subject: it seems to be fixed in 8.5.1

it seems to be fixed in 8.5.1

Uwe

–

4

Subject: Some comments to help gather diagnostics to identify root cause…

In a quick review, none of the individual transactions take more than ~1/5 of a second.

It would be useful to know what was the 1st transaction number and last transaction for a specific operation. If you showed the console window, it would make it easier for you to provide this info. It can be shown by adding DEBUG_CONSOLE=1 into the Notes.INI.

Alternatively, if the delay is somewhere else, the following data collection during the 40 second operation could also help isolate the problem:

For the long delays, here is some information on how to collect data to help troubleshoot a specific delay:

Collecting data for a hang or long delay

If the Notes Client is unresponsive, and remains unresponsive, it may be hung.

If the Notes Client is unresponsive, and eventually becomes responsive, it is not hung, but may have had a long delay.

To determine the root cause of the hang or long delay, an NSD and Java core file need to be generated.

To do so, perform the following steps:

Open a DOS command prompt

Switch to the Notes data directory

Run …\NSD (no args, assuming that the program dir is “above” the data directory

Run …\NSD again for a long delay. Having 2 NSDs back to back will allow a comparison of the 2 states to ensure that it is in the same area of code at both points.

After the NSDs completes, supply these 2 sets of files, or do an ISA data collection

data\IBM_TECHNICAL_SUPPORT\nsd*.txt file

data\workspace\logs\java*.txt file “Java core”

Thanks.

5

Subject: Can you plesae supply the javacore file as well for the new memo case?

Can you also include as attachments to ease working with the data? Thanks.

Doug Conmy
Lotus Notes Lead Architect
IBM Senior Technical Staff Member

6

Subject: Log-File Download-Links

Downlod-Links for the log files (from external site).

fresh started client:

nsd: File-Upload.net - Datei nicht gefunden

Java: File-Upload.net - Datei nicht gefunden

with delayed client:

nsd: File-Upload.net - Datei nicht gefunden

Java: File-Upload.net - Datei nicht gefunden

=============================================

Sorry, but how can I do an attachment inside the forum (is it possible) - in Firefox I see only an text-input field

Thanks Uwe