search

Ericsson / exchangecalendar

Exchange 2007/2010/2013 Calendar, Tasks, Contacts and GAL Provider.
GNU General Public License v3.0
925 stars 112 forks source link

Long delay when creating new event or task #275

Open logological opened 9 years ago

logological commented 9 years ago

When creating a new event or task from the "Events and Tasks" pull-down menu or from the context menu, I experience a very long delay (about 20 seconds) before the "New Event" or "New Task" dialog appears. During this time the mouse pointer changes to a busy throbber and Thunderbird becomes unresponsive. This behaviour is very inconvenient, especially when one has to create a large number of events or tasks.

I believe the problem may lie with Exchange EWS Provider rather than Lightning, because the delay occurs only when Exchange EWS Provider is enabled. When the extension is disabled, the "New Event"/"New Task" dialog appears almost instantaneously. Also, this delay does not occur when creating events in proprietary calendar clients such as Microsoft Outlook.

I am using Exchange EWS Provider 3.4.0~beta4 and Lightning 4.0.1 on Thunderbird 38.0.1 on 64-bit openSUSE 13.2.

bavincen commented 9 years ago

should be network bandwidth issue..i haven’t faced any

logological commented 9 years ago

If it's a bandwidth issue, why does it happen on the same machine with Lightning+Exchange EWS Provider but not with Microsoft Outlook?

logological commented 9 years ago

Just did some further testing—top shows that CPU usage shoots up to 100% during the "New Event" delay, whereas iotop doesn't report any increase in disk usage, and my network bandwidth monitor doesn't show any increase in network activity.

bavincen commented 9 years ago

i guess you can diagnose and report the problem'' thanks

logological commented 9 years ago

Isn't that what I just did?

macolu commented 9 years ago

I experience the same issue (3.4.0~beta4).

bavincen commented 9 years ago

https://github.com/Ericsson/exchangecalendar/wiki/How-to-Install-latest-addon

please use. let us know if bug still there

macolu commented 9 years ago

As for me, I no longer reproduce the issue.

logological commented 9 years ago

Still reproducible for me with 3.4.0-beta5. The delay may be a bit shorter but it's still about 10 seconds long, with 100% CPU usage.

bavincen commented 9 years ago

@logological will this work for you https://github.com/Ericsson/exchangecalendar/wiki/How-to-Install-latest-addon

logological commented 9 years ago

@bavincen OK, I did as you suggested and installed the master version. I still see the same behaviour as with 3.4.0-beta5.

bavincen commented 9 years ago

https://github.com/Ericsson/exchangecalendar/wiki/How-to-Install-latest-addon

please try this @logological

logological commented 9 years ago

Why should I try that again when there haven't been any commits since the last time?

bavincen commented 9 years ago

You said u r using beta5 I just wanted you to give a try on our master branch .. Couple of related fixed there

logological commented 9 years ago

Yes, three days ago I was using 3.4.0-beta5. But in the comment before last i said I tried the master branch and that the problem was still present:

@bavincen OK, I did as you suggested and installed the master version. I still see the same behaviour as with 3.4.0-beta5.

bavincen commented 9 years ago

if you can give/post log snippet we can take a look.. you can repeat the activity twice copy past here. thanks

logological commented 9 years ago

OK. How do you want me to produce the log?

bavincen commented 9 years ago

beta7?

muthusuba commented 9 years ago

@logological https://github.com/Ericsson/exchangecalendar/wiki https://github.com/Ericsson/exchangecalendar/wiki/Logging-Calendar-with-options

logological commented 9 years ago

OK, here's a log of me doing the following:

  1. Right-click in the Tasks pane and select "New Task…"
  2. Wait about 11 seconds for the "New Task" dialog to appear.
  3. Click on "Save and Close", which creates a task in my default (non-Exchange) calendar.
  4. Repeat steps 1–3.

I note that no logging output appeared in the file until Step 3.

(I've anonymized the e-mail addresses in the log.)

1st-setup:2015-8-1 11:11:11.593:[UKP] getItems 0:   aItemFilter, 196636  aCount, 0 aRangeStart,  2015/09/21 00:00:00 Europe/Berlin isDate=0 nativeTime=1442786400000000 aRangeEnd, 2016/09/21 00:00:00 Europe/Berlin isDate=0 nativeTime=1474408800000000 aListener , [object Object] (_getItems in mivExchangeCalendar.js:2196)
1st-setup:2015-8-1 11:11:11.593:[UKP] getItems 2: aRangeStart:2015/09/21 00:00:00 Europe/Berlin isDate=0 nativeTime=1442786400000000 (_getItems in mivExchangeCalendar.js:2204)
1st-setup:2015-8-1 11:11:11.594:[UKP] getItems 3: aRangeEnd:2016/09/21 00:00:00 Europe/Berlin isDate=0 nativeTime=1474408800000000 (_getItems in mivExchangeCalendar.js:2212)
1st-setup:2015-8-1 11:11:11.594:[UKP] We received a getItems from repeatingInvitationsTimer function. Because this request if always for a full year it will consume a lot of memory when we have a lot of recurring events with no end date. So for now we only request the period we have in memory cache. startDate:2015/08/17 09:38:06 Europe/Berlin isDate=0 nativeTime=1439797086000000, endDate:2015/10/26 11:03:32 Europe/Berlin isDate=0 nativeTime=1445853812000000 (_getItems in mivExchangeCalendar.js:2223)
1st-setup:2015-8-1 11:11:11.594:[UKP] Events are requested by calendar. (_getItems in mivExchangeCalendar.js:2248)
1st-setup:2015-8-1 11:11:11.595:[UKP] Tasks are requested by calendar. (_getItems in mivExchangeCalendar.js:2249)
1st-setup:2015-8-1 11:11:11.595:[UKP] Invitations are requested by calendar. (_getItems in mivExchangeCalendar.js:2361)
1st-setup:2015-8-1 11:11:11.595:[UKP] Events are requested and this is possible for this folder (_getItems in mivExchangeCalendar.js:2383)
1st-setup:2015-8-1 11:11:11.596:[UKP] getItems 5a: aRangeStart:2015/08/17 09:38:06 Europe/Berlin isDate=0 nativeTime=1439797086000000 (_getItems in mivExchangeCalendar.js:2476)
1st-setup:2015-8-1 11:11:11.596:[UKP] getItems 5b: aRangeEnd:2015/10/26 11:03:32 Europe/Berlin isDate=0 nativeTime=1445853812000000 (_getItems in mivExchangeCalendar.js:2477)
1st-setup:2015-8-1 11:11:11.596:[UKP] getItemsFromMemoryCache startDate:2015/08/17 09:38:06 Europe/Berlin isDate=0 nativeTime=1439797086000000, endDate:2015/10/26 11:03:32 Europe/Berlin isDate=0 nativeTime=1445853812000000, aListener:[object Object], aExporting:false (_getItemsFromMemoryCache in mivExchangeCalendar.js:2640)
1st-setup:2015-8-1 11:11:11.599:[UKP] We got '22' events and  '0'  tasks from memory cache. (_getItemsFromMemoryCache in mivExchangeCalendar.js:2704)
1st-setup:2015-8-1 11:11:11.600:[UKP] We have a listener so going to inform it.(2) (_getItemsFromMemoryCache in mivExchangeCalendar.js:2706)
1st-setup:2015-8-1 11:11:11.602:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.602:[UKP] getInvitedAttendee FOUND someonelse:UKP Meeting, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.602:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.603:[UKP] getInvitedAttendee FOUND someonelse:UKP Meeting, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.603:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.604:[UKP] getInvitedAttendee FOUND someonelse:UKP Meeting, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.604:[UKP] getInvitedAttendee  X-IsInvitation = true (_getInvitedAttendee in mivExchangeCalendar.js:2955)
1st-setup:2015-8-1 11:11:11.605:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.606:[UKP] getInvitedAttendee FOUND someonelse:UKP Meeting, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.606:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.606:[UKP] getInvitedAttendee FOUND someonelse:UKP Meeting, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.607:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.607:[UKP] getInvitedAttendee FOUND someonelse:UKP Meeting, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.607:[UKP] getInvitedAttendee  X-IsInvitation = true (_getInvitedAttendee in mivExchangeCalendar.js:2955)
1st-setup:2015-8-1 11:11:11.610:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.611:[UKP] getInvitedAttendee FOUND someonelse:UBY weekly Meeting, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.611:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.611:[UKP] getInvitedAttendee FOUND myself:UBY weekly Meeting, attendee.participationStatus:TENTATIVE, aItem.myResponseType:Tentative (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.612:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.612:[UKP] getInvitedAttendee FOUND someonelse:UBY weekly Meeting, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.613:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.613:[UKP] getInvitedAttendee FOUND myself:UBY weekly Meeting, attendee.participationStatus:TENTATIVE, aItem.myResponseType:Tentative (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.614:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.615:[UKP] getInvitedAttendee FOUND someonelse:MEating, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.615:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.615:[UKP] getInvitedAttendee FOUND myself:MEating, attendee.participationStatus:ACCEPTED, aItem.myResponseType:Organizer (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.617:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.617:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Research Seminar , attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.618:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.618:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Research Seminar , attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.618:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.619:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Research Seminar , attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.619:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.619:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Research Seminar , attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.620:[UKP] getInvitedAttendee  X-IsInvitation = true (_getInvitedAttendee in mivExchangeCalendar.js:2955)
1st-setup:2015-8-1 11:11:11.621:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.621:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Research Seminar , attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.621:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.622:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Research Seminar , attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.622:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.622:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Research Seminar , attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.623:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.623:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Research Seminar , attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.623:[UKP] getInvitedAttendee  X-IsInvitation = true (_getInvitedAttendee in mivExchangeCalendar.js:2955)
1st-setup:2015-8-1 11:11:11.625:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.625:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reserved slot, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.626:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.626:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reserved slot, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.626:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.627:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reserved slot, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.627:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.627:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reserved slot, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.628:[UKP] getInvitedAttendee  X-IsInvitation = true (_getInvitedAttendee in mivExchangeCalendar.js:2955)
1st-setup:2015-8-1 11:11:11.629:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.629:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reserved slot, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.629:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.630:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reserved slot, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.630:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.630:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reserved slot, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.631:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.631:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reserved slot, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.631:[UKP] getInvitedAttendee  X-IsInvitation = true (_getInvitedAttendee in mivExchangeCalendar.js:2955)
1st-setup:2015-8-1 11:11:11.633:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.634:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reading group, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.634:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.634:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reading group, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.635:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.635:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reading group, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.635:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.636:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reading group, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.636:[UKP] getInvitedAttendee  X-IsInvitation = true (_getInvitedAttendee in mivExchangeCalendar.js:2955)
1st-setup:2015-8-1 11:11:11.637:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.638:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reading group, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.638:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.639:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reading group, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.639:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.639:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reading group, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.640:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.640:[UKP] getInvitedAttendee FOUND someonelse:UKP Advanced Seminar / Reading group, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.640:[UKP] getInvitedAttendee  X-IsInvitation = true (_getInvitedAttendee in mivExchangeCalendar.js:2955)
1st-setup:2015-8-1 11:11:11.643:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.644:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.644:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.644:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.645:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.645:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.645:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.646:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.646:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.646:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.647:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.647:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.647:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.648:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.648:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.649:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.649:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.649:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.650:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.650:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.650:[UKP] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.651:[UKP] getInvitedAttendee FOUND someonelse:Invited Talk by Steffen Eger; Meetings, attendee.id:mailto:example@example.com attendee.participationStatus:NEEDS-ACTION (_getInvitedAttendee in mivExchangeCalendar.js:2950)
1st-setup:2015-8-1 11:11:11.651:[UKP] getInvitedAttendee  X-IsInvitation = true (_getInvitedAttendee in mivExchangeCalendar.js:2955)
1st-setup:2015-8-1 11:11:11.654:[UKP] setProperty. aName:currentStatus, aValue:0 (setProperty in mivExchangeCalendar.js:828)
1st-setup:2015-8-1 11:11:11.655:[UKP] No dateChanged. Not going to request items from server. (_getItems in mivExchangeCalendar.js:2567)
1st-setup:2015-8-1 11:11:11.655:[absence] getItems 0:   aItemFilter, 196636  aCount, 0 aRangeStart,  2015/09/21 00:00:00 Europe/Berlin isDate=0 nativeTime=1442786400000000 aRangeEnd, 2016/09/21 00:00:00 Europe/Berlin isDate=0 nativeTime=1474408800000000 aListener , [object Object] (_getItems in mivExchangeCalendar.js:2196)
1st-setup:2015-8-1 11:11:11.656:[absence] getItems 2: aRangeStart:2015/09/21 00:00:00 Europe/Berlin isDate=0 nativeTime=1442786400000000 (_getItems in mivExchangeCalendar.js:2204)
1st-setup:2015-8-1 11:11:11.656:[absence] getItems 3: aRangeEnd:2016/09/21 00:00:00 Europe/Berlin isDate=0 nativeTime=1474408800000000 (_getItems in mivExchangeCalendar.js:2212)
1st-setup:2015-8-1 11:11:11.656:[absence] We received a getItems from repeatingInvitationsTimer function. Because this request if always for a full year it will consume a lot of memory when we have a lot of recurring events with no end date. So for now we only request the period we have in memory cache. startDate:2015/08/17 09:38:07 Europe/Berlin isDate=0 nativeTime=1439797087000000, endDate:2015/10/26 09:38:07 Europe/Berlin isDate=0 nativeTime=1445848687000000 (_getItems in mivExchangeCalendar.js:2223)
1st-setup:2015-8-1 11:11:11.656:[absence] Events are requested by calendar. (_getItems in mivExchangeCalendar.js:2248)
1st-setup:2015-8-1 11:11:11.657:[absence] Tasks are requested by calendar. (_getItems in mivExchangeCalendar.js:2249)
1st-setup:2015-8-1 11:11:11.657:[absence] Invitations are requested by calendar. (_getItems in mivExchangeCalendar.js:2361)
1st-setup:2015-8-1 11:11:11.657:[absence] Events are requested and this is possible for this folder (_getItems in mivExchangeCalendar.js:2383)
1st-setup:2015-8-1 11:11:11.657:[absence] getItems 5a: aRangeStart:2015/08/17 09:38:07 Europe/Berlin isDate=0 nativeTime=1439797087000000 (_getItems in mivExchangeCalendar.js:2476)
1st-setup:2015-8-1 11:11:11.658:[absence] getItems 5b: aRangeEnd:2015/10/26 09:38:07 Europe/Berlin isDate=0 nativeTime=1445848687000000 (_getItems in mivExchangeCalendar.js:2477)
1st-setup:2015-8-1 11:11:11.658:[absence] getItemsFromMemoryCache startDate:2015/08/17 09:38:07 Europe/Berlin isDate=0 nativeTime=1439797087000000, endDate:2015/10/26 09:38:07 Europe/Berlin isDate=0 nativeTime=1445848687000000, aListener:[object Object], aExporting:false (_getItemsFromMemoryCache in mivExchangeCalendar.js:2640)
1st-setup:2015-8-1 11:11:11.669:[absence] We got '142' events and  '0'  tasks from memory cache. (_getItemsFromMemoryCache in mivExchangeCalendar.js:2704)
1st-setup:2015-8-1 11:11:11.669:[absence] We have a listener so going to inform it.(2) (_getItemsFromMemoryCache in mivExchangeCalendar.js:2706)
1st-setup:2015-8-1 11:11:11.671:[absence] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.672:[absence] getInvitedAttendee FOUND myself:SH - HO, attendee.participationStatus:NEEDS-ACTION, aItem.myResponseType:Unknown (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.673:[absence] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.674:[absence] getInvitedAttendee FOUND myself:FH - TK, attendee.participationStatus:NEEDS-ACTION, aItem.myResponseType:Unknown (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.676:[absence] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.676:[absence] getInvitedAttendee FOUND myself:REC HO, attendee.participationStatus:NEEDS-ACTION, aItem.myResponseType:Unknown (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.678:[absence] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.678:[absence] getInvitedAttendee FOUND myself:JD - HO, attendee.participationStatus:NEEDS-ACTION, aItem.myResponseType:Unknown (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.681:[absence] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.681:[absence] getInvitedAttendee FOUND myself:ED - HO, attendee.participationStatus:NEEDS-ACTION, aItem.myResponseType:Unknown (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.688:[absence] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.688:[absence] getInvitedAttendee FOUND myself:FH - TK, attendee.participationStatus:NEEDS-ACTION, aItem.myResponseType:Unknown (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.693:[absence] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.693:[absence] getInvitedAttendee FOUND myself:FH - TK, attendee.participationStatus:NEEDS-ACTION, aItem.myResponseType:Unknown (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.702:[absence] getInvitedAttendee 2:mailto:example@example.com (_getInvitedAttendee in mivExchangeCalendar.js:2942)
1st-setup:2015-8-1 11:11:11.702:[absence] getInvitedAttendee FOUND myself:JEK - HO, attendee.participationStatus:NEEDS-ACTION, aItem.myResponseType:Unknown (_getInvitedAttendee in mivExchangeCalendar.js:2945)
1st-setup:2015-8-1 11:11:11.722:[absence] setProperty. aName:currentStatus, aValue:0 (setProperty in mivExchangeCalendar.js:828)
1st-setup:2015-8-1 11:11:11.722:[absence] No dateChanged. Not going to request items from server. (_getItems in mivExchangeCalendar.js:2567)
1st-setup:2015-8-1 11:11:24.332:exchWebService.attachments.onSelectTask
1st-setup:2015-8-1 11:11:35.887:exchWebService.attachments.onSelectTask
markusstinner commented 9 years ago

Hi, I experience the same issue as well as 6 other people at my institute (we all use this addon). CPU load is at 100%. The version used is 3.4.0-rc2

bavincen commented 9 years ago

Hi @markusstinner Please check recently closed issues.There are some issues with other addon.

You can also provide memory statistics with and without using addon..

Thanks

bonanza123 commented 8 years ago

I'm also noticing this issue (also in the latest release). But when I restart thunderbird its responding very fast again. After a couple of hours it is again slowing down. Its also happening when exchange-calendar is the only activated plugin.

What kind of logs should I provide? Run thunderbird in valgrind or something similar?

jannesverstichel commented 7 years ago

Hi, I've noticed the slow event creation issue as well, and I think I might have narrowed it down a little bit: I think the culprit is the direct syncing with the Exchange server. A delay on the sync or batch-syncing might solve the problem if I'm right.

Examples:

When you create a new event for the exchange calendar with right-click, it takes several seconds before the event created appears in the calendar. During this period, you can see in the lower right corner that Thunderbird is executing a task (syncing with the Exchange server).

When you create a new event for the exchange calendar by dragging over the calendar, it takes several seconds before the event appears and only than can you start typing the name of the event (during those few seconds, again the sync with the Exchange server). When you entered the name, again delay for the sync, before you can edit other aspects of the event, and so on and so forth.

Same with moving events around.

Please let me know if this information suffices for you, or if I should provide more data.

kind regards,

jannesverstichel

PS: I use v3.9.0 (also had the issue on v3.8.0, had not used the plugin before that)