1486692 - Windows "Not Responding" for 5min 30 sec after start with Lightning if unifinder is enabled and "All Events" is selected

Status: RESOLVED WORKSFORME

(Calendar :: General, defect)

Description

[Craig Arno]

User Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:61.0) Gecko/20100101 Firefox/61.0
Build ID: 20180807170231

Steps to reproduce:

Start Thunderbird 60.0 in Windows 10 Professional.  My current config:

account1	(imap) arno.com:993	SSL/TLS	Normal password	smtp.frontier.com:465	SSL/TLS	Normal password	true
account2	(none) Local Folders	None	Normal password
account3	(imap) imap.gmail.com:993	SSL/TLS	OAuth2	smtp.gmail.com:465	SSL/TLS	OAuth2	true
account4	(imap) imap.gmail.com:993	SSL/TLS	OAuth2	smtp.gmail.com:465	SSL/TLS	OAuth2	true
account5	(nntp) news.cadsoft.de:119	None	Normal password	smtp.frontier.com:465	SSL/TLS	Normal password	true
account6	(imap) imap.gmail.com:993	SSL/TLS	OAuth2	smtp.gmail.com:465	SSL/TLS	OAuth2	true
account7	(imap) koczarski.com:143	None	Normal password	smtp.gmail.com:465	SSL/TLS	OAuth2	true
account8	(imap) imap.mail.yahoo.com:993	SSL/TLS	Normal password	smtp.mail.yahoo.com:465	SSL/TLS	Normal password	true
account10	(imap) imap.gmail.com:993	SSL/TLS	OAuth2	smtp.gmail.com:465	SSL/TLS	OAuth2	true
account11	(rss) Feeds	None	Normal password

Extensions
Name 	Version 	Enabled 	ID
CardBook	30.5	true	[e-mail]
Classic Password Editor	1.0.3	true	[e-mail]
Enigmail	2.0.8	true	{847b3a00-7ab1-11d4-8f02-006008948af5}
HabuL	1.21.0	true	{41e52bd9-defc-4a0f-a542-cf643a0776d1}
ImportExportTools	3.3.0	true	{3ed8cc52-86fc-4613-9026-c1ef969da4c3}
Lightning	6.2	true	{e2fda1a4-762b-4020-b5ad-a41df1933103}
LookOut (fix version)	1.2.22	true	lookout@s3_fix_version
Provider for Google Calendar	4.4.1	true	{a62ef8ec-5fdc-40c2-873c-223b8a6925cc}

Security Software
Type 	Name
Antivirus 	Avira Antivirus
Antispyware 	Avira Antivirus
Firewall 	Windows Firewall

System Hardware i7 4-core CPU, Win 10 Pro, 16GB RAM, 1TB SSD, connected to local Dovecot server over gigabit wired Ethernet.


Actual results:

For 5 minutes 30 seconds kept trying to get Thunderbird->Help-> About Mozilla Thunderbird.  Thunderbird unresponsive.  Windows Task manager most of the time displays "Not Responding" next to Thunderbird (x86) task during 5min 30 sec interval.

After startup Thunderbird is nice and responsive, and I just noticed isn't showing up in Task Manager anymore, except under the details tab. TB is only using 269MB of memory, which is great.


Expected results:

It's okay if Thunderbird is busy in the background, but the UI should remain responsive during startup and the application not significantly impact other tasks.  Almost 6 minutes during startup is a bit excessive for an app to remain unresponsive.  Thunderbird v60.0 under Ubuntu Linux doesn't display these startup issues.

Comment 1

[Wayne Mery (:wsmwk)]

> Thunderbird v60.0 under Ubuntu Linux doesn't display these startup issues.

Knowing it only happens on Windows is helpful, but needs further definition which is why I previously mentioned https://wiki.mozilla.org/Thunderbird:Testing:Memory_Usage_Problems#Diagnosis_Steps  This testing is necessary for us to make progress on your bug report.

(we assume here that Thunderbird is not being started from Thunderbird being in your Windows startup folder)

A shortcut for steps 2 and 3 is doing them together in one startup. Safe mode is a necessary and short test.

Start *Windows'* safe mode with networking enabled
- win10 https://support.microsoft.com/en-us/help/12376/windows-10-start-your-pc-in-safe-mode
Still in Windows safe mode, start thunderbird in safe mode
- https://support.mozilla.org/kb/safe-mode-thunderbird

Does problem go away?

- If no, then cause is either: bug in Thunderbird, something (eg a setting, file or folder) in your Thunderbird profile, your mail provider. Please post into topic the contents of Help | Troubleshooting | copy text to clipboard

- If yes, then (still in Windows safe mode) ... start Thunderbird normally
-- If problem is still gone, then cause is a program loaded during Windows startup. Possibilities include: antivirus SW, virus/malware, background downloads such as program updates, Windows Search Indexer, etc
-- If problem is came back, then cause is likely a Thunderbird addon - eliminate them by disabling each one at a time in Tools | addons | extensions and restarting

- If results are unclear ... possibilities include temporary conditions such as contention from other running programs, downloads related to Windows update, ...

Comment 2

[Craig Arno]

Did all the Safe Mode things in Win10pro and Thunderbird 60.0...

Bottom line: Lightning 6.2 is what's impeding Thunderbird 60.0 startup.

With Lightning 6.2 disabled and all other add-ons enabled, Thunderbird 60.0 pops right up starting almost instantly, and is usable almost instantly, barely any perceptible startup delay under Windows 10.  In fact Thunderbird starts so fast it's as if it never shut down.  I checked, no Thunderbird processes display in Task Manager prior to this "instant startup" behavior.  And on my system, Lightning disabled, + 10 accounts and some large folders, Thunderbird is still only using ~250MB of memory with everything except Lightning loaded.

My Lightning add-on is connected to an ownCloud 9.1.8 (stable) CalDAV server, connected by gigabit wired Ethernet.  So I suspect the issue is in the Thunderbird/Lightning interface, only under Windows 10 x64.  Given that 6 minutes after startup Lightning and everything else runs as it's supposed to, I suspect the issue is in Lightning start-up code.  Probably a dependency on a service which is no longer present in the TB 60.0 add-on API.  Linux x64 is not displaying these issues with Lightning 6.2.  So if there is conditional Windows code in Lightning startup, I'd look there first.  Or maybe there is an Add-On API call available in TB 60 Linux which isn't available/working in TB 60 Windows which is used during Lightning startup.

With Lightning enabled, these warnings pop up during startup:

A script on this page may be busy, or it may have stopped responding. You can stop the script now, or you can continue to see if the script will complete.

Script: chrome://calendar/content/calendar-unifinder.js:548

Script: resource://calendar/modules/utils/calUnifinderUtils.jsm:111

Script: resource://calendar/modules/utils/calUnifinderUtils.jsm:111

Lightning Calendar functionality is a "must have" in my setup, so I hope this can be tracked down and fixed soon.

Addressbook functionality is also a must have which is working well for me with the ownCloud 9.1.8 (stable) CardDAV server at this time.  I use the Cardbook add-on for this functionality.

Comment 3

[Wayne Mery (:wsmwk)]

Did this also happen in version 52 on the same machine, same setup?

Comment 4

[Craig Arno]

No, when I was running v52, startup wasn't instant, and in fact took a while, but also wasn't creating any script timeout errors during startup on the same Windows 10 machine.  Since Windows 10 is a moving target with forced OS updates I can't claim "same setup".  With this disclaimer, I will say ~yes, same setup.

Comment 5

[Wayne Mery (:wsmwk)]

Appreciate the nuanced information :)

To cover all bases, (sorry, gotta ask) please also test with Windows started in safe mode - win10 https://support.microsoft.com/en-us/help/12376/windows-10-start-your-pc-in-safe-mode

Does it make a difference?

Comment 6

[Craig Arno]

I did that first, prior to my report.  In other words followed all your suggestions, no shortcuts.  Yes, same long startup/warning-timeout behavior in Windows Safe Mode with Networking.  I didn't mention this earlier because I was able to isolate the behavior down to the Thunderbird Lightning 6.2 Add-On and wanted to keep the summary report concise and pithy.  But can see your point of some other loaded piece of software potentially tripping everything up.  Not the case this time.  So my report was missing essential data.

Comment 7

[Wayne Mery (:wsmwk)]

I saw something like this today on laptop.  I had been running 60.0b11 32bit.  Installed 64.0a1 nightly 64bit, startup, got prompted to enable lighning 6.6a1, clicked continue - TB totally went out to lunch for dozens of minutes

Comment 8

[Craig Arno]

Yup, that's the one!  If I'm going somewhere where I may need to quickly check email I disable Lightning so Thunderbird will start quickly.  Without Lightning Thunderbird 60 starts faster than any previous version.  Many earlier problems just aren't there, feels like you did an overhaul on the code.  Now if we could sort out this Windows 10 x64 Lightning thing... which doesn't show up under Ubuntu 16.04 Linux x64.  Otherwise, at a Windows x64 workstation, start TB, enable lightning, go get breakfast because you won't be doing anything with TB for a while.  Glad you were finally able to repro this one.

Comment 9

[[:MakeMyDay]]

When opening the calendar view, which option do you have selected in the dropdown of the item finder (if you don't see a section to list item above the calendar view, press the |Find Events| button in the calendar toolbar? (based on comment 2, this might be the culprit - if you change whatever you have to |Today's Events| and restart TB, does the issue still reproduce?

If the issue persists, can you please provide the folowwing information regarding you calendar setup:

- How many calendars do you have?
- Which type are these (local, ics, caldav, wcap, Google via Provider for Google (in contrast to Google via caldav) and which server product is used, if applicalble and possible? 
- Do you have offline support enabled for (some of) these (see the respectivce calendar properties)?
- Where is you TB profile stored (local or on a network location)?

Comment 10

[Craig Arno]

I'm not familiar with Calendar terminology, but I'll answer what I think you want.  First, Thunderbird 60.0, Lightning 6.2.

After setting Calendar Finder to "Today's Events" (which is empty, no events today) and restarting Thunderbird, Thunderbird starts with only a short delay and is immediately usable.  This is useful information for me.  With this answer I suspect the rest isn't needed, but I'll provide it anyway for completeness.

I have 5 Calendars setup; 2 point to my "ownCloud 9.1.8 (stable)" installation, the other 3 point to Google API accounts.

Under "Events and Tasks" two items are selected; "Calendar" and "Find Events".

The Calendar view is set to "Multiweek".

The Find Events view is set to "All Events".

Offline support is not enabled.

TB profile is stored locally (I wasn't aware I could store this anywhere else, maybe to sync all my TB installations?).

Comment 11

[Wayne Mery (:wsmwk)]

My daily is hanging *every* time I change the view - 3-4 minutes at same CPU usage 13% as happend on long startup - and I have no calendars (local.sqlite is 416kb), and no external calendars.

Comment 12

[Craig Arno]

Other interesting points after a little experimenting:

If I start TB then immediately (seconds) switch Calendar Finder "Today's Events" mode to Calendar Finder "All Events", the same "TB Not Responding" behavior exhibits.

If I start TB, wait 1 minute (a whole minute, not what passes for "a minute" these days) then Switch Calendar Finder to "All Events" then everything behaves as it should and TB/Lightning remain responsive.

If I leave the Finder setting at "All Events" but close (remove) the Calendar Finder view, then TB again starts quickly and is responsive.

==
The Win10pro machine TB60/Lightning6.2 I'm using is fairly fast (for a "laptop") Intel i7-6600U 2.8GHz 4-core 16GB RAM 1TB SSD.  This machine supports engineering design activities.

The Ubuntu 16.04 LTS Linux Server running TB60.0/Lightning6.2 is an AMD FX-8120 4GHz 8-core 16GB RAM 256GB SSD 24TB RAID, also supporting engineering design (well stocked, large number of services running to support all Office-app/CAD/Development work).

As Wayne knows I also run a Linux workstation with specs similar to the server except a Phenom II 1100t Black x6 3.3-3.7GHz processor for double the math co-processor units (1 math co-processor/cpu) FX has 4 MCU, Phenom has 6 MCU which speeds up some compute intensive CAD work.  All machines are connected with gigabit Ethernet.  The Linux Workstation is in the middle of being rebuilt.  FX is better for moving data, Phenom is better for computing.
==
Wayne's "still hanging" note is interesting.  Wonder if I'm missing some state related behavior?  I'll keep my eyes open.

Comment 13

[Wayne Mery (:wsmwk)]

In console, after first extremely long wait (minutes), I see

JavaScript error: jar:file:///C:/Program%20Files/mozilla.org/TB%2064.0a1%202018-09-20/extensions/%7Be2fda1a4-762b-4020-b
5ad-a41df1933103%7D.xpi!/components/calItemModule.js -> resource://calendar/calendar-js/calRecurrenceInfo.js, line 480:
uncaught exception: out of memory
JavaScript error: jar:file:///C:/Program%20Files/mozilla.org/TB%2064.0a1%202018-09-20/extensions/%7Be2fda1a4-762b-4020-b
5ad-a41df1933103%7D.xpi!/components/calItemModule.js -> resource://calendar/calendar-js/calRecurrenceInfo.js, line 480:
NS_ERROR_XPC_JS_THREW_STRING: out of memory'out of memory' when calling method: [calIRecurrenceRule::getOccurrences]
JavaScript error: jar:file:///C:/Program%20Files/mozilla.org/TB%2064.0a1%202018-09-20/extensions/%7Be2fda1a4-762b-4020-b
5ad-a41df1933103%7D.xpi!/components/calItemModule.js -> resource://calendar/calendar-js/calRecurrenceInfo.js, line 480:
NS_ERROR_XPC_JS_THREW_STRING: out of memory'out of memory' when calling method: [calIRecurrenceRule::getOccurrences]
JavaScript error: jar:file:///C:/Program%20Files/mozilla.org/TB%2064.0a1%202018-09-20/extensions/%7Be2fda1a4-762b-4020-b
5ad-a41df1933103%7D.xpi!/components/calItemModule.js -> resource://calendar/calendar-js/calRecurrenceInfo.js, line 480:
uncaught exception: out of memory

Comment 14

[Wayne Mery (:wsmwk)]

comment 13 was obtained with   thunderbird.exe -console

And thunderbird is using 2.3gb memory

And I was mistaken, turns out my calendar is not empty.  I'll send it to makemyday

Comment 15

[[:MakeMyDay]]

Since the problem of Wayne is different and he filed bug 1493763 for that parsing issue, we can close this wfm as per comment 10.

Comment 16

[Wayne Mery (:wsmwk)]

> If I start TB then immediately (seconds) switch Calendar Finder "Today's Events" mode to Calendar Finder "All Events", the same "TB Not Responding" behavior exhibits.

Is this covered then in some other bug?

Comment 17

[Richard Leger]

FYI, this bug may be related to Bug 1502923