Beta2 client freezes on start of chat from integrated contact list

hcl-bot · October 23, 2008, 1:50pm

Every once in a while when I double click a contact in my sidebar sametime contact list the whole of notes will freeze. It does open the window, but I see my system utilization just drastically and i ran an strace and it seems to be in some kind of resource grabbing loop.

Here is from top:

PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND

22311 swiftg 20 0 597m 210m 86m R 89 10.5 15:16.79 notes2

695 root 20 0 427m 113m 16m S 20 5.6 583:50.71 X

Here is from my strace of that process: (I included the one bit that had something besides just the get time, poll and read)

gettimeofday({1224787540, 608588}, NULL) = 0

read(125, 0xa2a127f4, 4096) = -1 EAGAIN (Resource temporarily unavailable)

gettimeofday({1224787540, 608678}, NULL) = 0

poll([{fd=126, events=POLLIN}, {fd=125, events=POLLIN}, {fd=151, events=POLLIN|POLLPRI}, {fd=153, events=POLLIN|POLLPRI}, {fd=154, events=POLLIN|POLLPRI}, {fd=155, events=POLLIN|POLLPRI}, {fd=167, events=POLLIN}, {fd=145, events=POLLIN}], 8, 0) = 0

gettimeofday({1224787540, 700758}, NULL) = 0

read(125, 0xa2a127f4, 4096) = -1 EAGAIN (Resource temporarily unavailable)

gettimeofday({1224787540, 700898}, NULL) = 0

poll([{fd=126, events=POLLIN}, {fd=125, events=POLLIN}, {fd=151, events=POLLIN|POLLPRI}, {fd=153, events=POLLIN|POLLPRI}, {fd=154, events=POLLIN|POLLPRI}, {fd=155, events=POLLIN|POLLPRI}, {fd=167, events=POLLIN}, {fd=145, events=POLLIN, revents=POLLIN}], 8, 0) = 1

gettimeofday({1224787540, 701016}, NULL) = 0

gettimeofday({1224787540, 701063}, NULL) = 0

write(149, “8”, 1) = 1

gettimeofday({1224787540, 701162}, NULL) = 0

gettimeofday({1224787540, 701202}, NULL) = 0

futex(0x9a021aa0, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x9a021a9c, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_EQ, 0}) = 1

read(145, “\372”, 1) = 1

read(125, 0xa2a127f4, 4096) = -1 EAGAIN (Resource temporarily unavailable)

gettimeofday({1224787540, 701395}, NULL) = 0

poll([{fd=126, events=POLLIN}, {fd=125, events=POLLIN}, {fd=151, events=POLLIN|POLLPRI}, {fd=153, events=POLLIN|POLLPRI}, {fd=154, events=POLLIN|POLLPRI}, {fd=155, events=POLLIN|POLLPRI}, {fd=167, events=POLLIN}, {fd=145, events=POLLIN}], 8, 0) = 0

gettimeofday({1224787540, 701508}, NULL) = 0

read(125, 0xa2a127f4, 4096) = -1 EAGAIN (Resource temporarily unavailable)

gettimeofday({1224787540, 701599}, NULL) = 0

poll([{fd=126, events=POLLIN}, {fd=125, events=POLLIN}, {fd=151, events=POLLIN|POLLPRI}, {fd=153, events=POLLIN|POLLPRI}, {fd=154, events=POLLIN|POLLPRI}, {fd=155, events=POLLIN|POLLPRI}, {fd=167, events=POLLIN}, {fd=145, events=POLLIN}], 8, 0) = 0

gettimeofday({1224787540, 701716}, NULL) = 0

read(125, 0xa2a127f4, 4096) = -1 EAGAIN (Resource temporarily unavailable)

gettimeofday({1224787540, 701806}, NULL) = 0

poll([{fd=126, events=POLLIN}, {fd=125, events=POLLIN}, {fd=151, events=POLLIN|POLLPRI}, {fd=153, events=POLLIN|POLLPRI}, {fd=154, events=POLLIN|POLLPRI}, {fd=155, events=POLLIN|POLLPRI}, {fd=167, events=POLLIN}, {fd=145, events=POLLIN}], 8, 0) = 0

gettimeofday({1224787540, 701917}, NULL) = 0

read(125, 0xa2a127f4, 4096) = -1 EAGAIN (Resource temporarily unavailable)

gettimeofday({1224787540, 702006}, NULL) = 0

poll([{fd=126, events=POLLIN}, {fd=125, events=POLLIN}, {fd=151, events=POLLIN|POLLPRI}, {fd=153, events=POLLIN|POLLPRI}, {fd=154, events=POLLIN|POLLPRI}, {fd=155, events=POLLIN|POLLPRI}, {fd=167, events=POLLIN}, {fd=145, events=POLLIN}], 8, 0) = 0

gettimeofday({1224787540, 702116}, NULL) = 0

hcl-bot · October 29, 2008, 9:43am

Subject: do you need more info?

I’ll gladly provide any other additional information that I can, it happens almost once a day. I do tend to leave my client open all the time, but it will do this a day later, or even shortly after start.

thanks

hcl-bot · October 29, 2008, 10:18am

Subject: broke my bookmark.nsf (but we fixed it)

I killed notes (xkill) when it locked up again this last time. When Notes started back up I clicked on Mail and got the attached screen shot.

I can still get to my Calendar. My Notes guru had me try File → Open → LN Application → Server → mail → my mailbox, but that didn’t work.

then, we stoped notes, and deleted Cache.NDK. Starting Notes backup put it into a look, here is an exert from an strace:

gettimeofday({1225293147, 950578}, NULL) = 0

poll([{fd=5, events=POLLIN}, {fd=4, events=POLLIN}, {fd=10, events=POLLIN}, {fd=14, events=POLLIN|POLLPRI}, {fd=16, events=POLLIN|POLLPRI}, {fd=17, events=POLLIN|POLLPRI}, {fd=18, events=POLLIN|POLLPRI}], 7, 50) = 0

gettimeofday({1225293148, 1051}, NULL) = 0

gettimeofday({1225293148, 1115}, NULL) = 0

read(4, 0x98e497c, 4096) = -1 EAGAIN (Resource temporarily unavailable)

Then I killed -9 it (-15 didnt work).

Next I started the app back up from a terminal so I could watch any terminal output. Once the application started, I told it to log into same time and then before I did anything else I looked at the terminal. This is the last output:

ReflectorsResponse.load: reading 2reflectors

ReflectorsWithIDResponse.load: reading 2 reflectorsWithID

10/29/2008 10:14:21 AM Dynamic Client Configuration started

10/29/2008 10:14:21 AM Initializing Dynamic Client Configuration

10/29/2008 10:14:21 AM Dynamic Client Configuration updating policy information

10/29/2008 10:14:21 AM Dynamic Client Configuration: Returned an error adding policy information to the address book: Note item not found

10/29/2008 10:14:21 AM Dynamic Client Configuration: Applying policy bookmarks

10/29/2008 10:14:21 AM Dynamic Client Configuration updating location information

10/29/2008 10:14:21 AM Dynamic Client Configuration updating location information using policy

10/29/2008 10:14:21.47 AM [01419:00002–1245854000] DynConfig> Found $DPLocked field: $DPLocked on policy note. Copying to location doc

10/29/2008 10:14:21.47 AM [01419:00002–1245854000] DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note. Copying to location doc

10/29/2008 10:14:21 AM Dynamic Client Configuration updating location information using policy

10/29/2008 10:14:21.47 AM [01419:00002–1245854000] DynConfig> Found $DPLocked field: $DPLocked on policy note. Copying to location doc

10/29/2008 10:14:21.47 AM [01419:00002–1245854000] DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note. Copying to location doc

10/29/2008 10:14:21 AM Dynamic Client Configuration updating location information using policy

10/29/2008 10:14:21.48 AM [01419:00002–1245854000] DynConfig> Found $DPLocked field: $DPLocked on policy note. Copying to location doc

10/29/2008 10:14:21.48 AM [01419:00002–1245854000] DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note. Copying to location doc

10/29/2008 10:14:21 AM Dynamic Client Configuration updating location information using policy

10/29/2008 10:14:21.48 AM [01419:00002–1245854000] DynConfig> Found $DPLocked field: $DPLocked on policy note. Copying to location doc

10/29/2008 10:14:21.48 AM [01419:00002–1245854000] DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note. Copying to location doc

10/29/2008 10:14:21 AM Dynamic Client Configuration shutdown

Then I clicked on the mail icon. Here is the output I received:

2008/10/29 10:15:55.905 SEVERE err.quotaservice.getDocumentStoreProperties.serviceexception ::class.method=com.ibm.notes.mail.quota.services.QuotaService.getDocumentStoreProperties() ::thread=Worker-11 ::loggername=com.ibm.notes.mail.quota.widget.Thermometer

    com.ibm.csi.types.ServiceException: 

    at com.ibm.csi.notes.internal.xml.parser.sax.ErrorReader.newServiceException(Unknown Source)

    at com.ibm.csi.notes.internal.NotesRuntimeDelegate.getDocumentStoreProperties(Unknown Source)

    at com.ibm.notes.mail.quota.services.QuotaService.getDocumentStoreProperties(Unknown Source)

    at com.ibm.notes.mail.quota.services.QuotaService.hasQuota(Unknown Source)

    at com.ibm.notes.mail.quota.widget.Thermometer$9.run(Unknown Source)

    at org.eclipse.core.internal.jobs.Worker.run(Unknown Source)

2008/10/29 10:15:55.908 SEVERE err.csinavviewpart.retrievelocationname.expetion ::class.method=com.ibm.rcp.csiviews.viewpart.CSINavViewPart$27.run() ::thread=Worker-5 ::loggername=com.ibm.rcp.csiviews.viewpart.CSINavViewPart

    com.ibm.csi.types.ServiceException: 

    at com.ibm.csi.notes.internal.xml.parser.sax.ErrorReader.newServiceException(Unknown Source)

    at com.ibm.csi.notes.internal.NotesRuntimeDelegate.getDocumentStoreProperties(Unknown Source)

    at com.ibm.rcp.csiviews.viewpart.CSINavViewPart$27.run(Unknown Source)

    at org.eclipse.core.internal.jobs.Worker.run(Unknown Source)

2008/10/29 10:15:55.906 SEVERE An error occurred during fetching user name. ::class.method=com.ibm.rcp.csiviews.viewpart.CSINavViewPart$23.run() ::thread=Worker-12 ::loggername=com.ibm.rcp.csiviews.viewpart.CSINavViewPart

    com.ibm.csi.types.ServiceException: 

    at com.ibm.csi.notes.internal.xml.parser.sax.ErrorReader.newServiceException(Unknown Source)

    at com.ibm.csi.notes.internal.NotesRuntimeDelegate.getDocumentStoreProperties(Unknown Source)

    at com.ibm.rcp.csiviews.viewpart.CSINavViewPart$23.run(Unknown Source)

    at org.eclipse.core.internal.jobs.Worker.run(Unknown Source)

2008/10/29 10:15:55.927 SEVERE CLFNM0003E: Error getting outline actions for navigator ::class.method=com.ibm.rcp.csiactions.providers.CSIActionProvider.getCSIActions() ::thread=Worker-19 ::loggername=com.ibm.rcp.csiactions.providers.CSIActionProvider

    com.ibm.csi.types.ServiceException: 

    at com.ibm.csi.notes.internal.xml.parser.sax.ErrorReader.newServiceException(Unknown Source)

    at com.ibm.csi.notes.internal.NotesDesignDelegate.getOutlineActions(Unknown Source)

    at com.ibm.rcp.csiactions.providers.CSIActionProvider.getCSIActions(Unknown Source)

    at com.ibm.rcp.csiactions.providers.CSIActionProvider.getCSIOutlineActions(Unknown Source)

    at com.ibm.rcp.csiactions.providers.CSIActionProvider.setRuntimeOutline(Unknown Source)

    at com.ibm.rcp.csiviews.viewpart.CSINavViewPart$25.run(Unknown Source)

    at org.eclipse.core.internal.jobs.Worker.run(Unknown Source)

2008/10/29 10:15:55.928 WARNING CLFNM0254W: CSI Returned null or No Actions for com.ibm.csi.notes.runtime.internal.types.NotesRuntimeDocumentStore@76b476b4:com.ibm.csi.notes.runtime.internal.types.NotesRuntimeOutline@13dc13dc ::class.method=com.ibm.rcp.csiactions.providers.CSIActionProvider.getCSIActions() ::thread=Worker-19 ::loggername=com.ibm.rcp.csiactions.providers.CSIActionProvider

2008/10/29 10:15:55.929 SEVERE CLFNM0052E: Getting the outline design failed while populating a model with the elements of this document store com.ibm.csi.notes.runtime.internal.types.NotesRuntimeDocumentStore@76b476b4 ::class.method=com.ibm.rcp.csiviews.providers.CSINavigatorModel.fetchCsiInfo() ::thread=Worker-13 ::loggername=com.ibm.rcp.csiviews.providers.CSINavigatorModel

Then we exited the application, and removed my ~/lotus/data/notes/bookmark.nsf, started up Notes, selected to use default bookmark.nsf page, and voila… working

Thanks.

-Greg