Closed
Bug 763868
Opened 12 years ago
Closed 11 years ago
Periodic hangs - CC > 3000 ms (due to zombie compartments?)
Categories
(Firefox :: General, defect)
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: karl156, Assigned: mccr8)
References
(Depends on 1 open bug)
Details
User Agent: Mozilla/5.0 (Windows NT 5.1; rv:13.0) Gecko/20100101 Firefox/13.0
Build ID: 20120601045813
Steps to reproduce:
As advised in bug 490122#c343 here my bug report about periodic hangs in Firefox.
The last 8 Log entries (from error console):
CC(T+5.4) duration: 3344ms, suspected: 782, visited: 358456 RCed and 2296053 GCed, collected: 530 RCed and 490 GCed (1020 waiting for GC)
ForgetSkippable 7 times before CC, min: 0 ms, max: 94 ms, avg: 15 ms, total: 109 ms, removed: 1350
----------
GC(T+10.1) TotalTime: 626,4ms, Type: global, MMU(20ms): 0%, MMU(50ms): 0%, Reason: CC_WAITING, NonIncrementalReason: GC mode, +chunks: 0, -chunks: 0
mark: 525,2, mark-roots: 31,7, mark-other: 382,2, sweep: 98,1, sweep-obj: 8,4, sweep-string: 0,4, sweep-script: 2,8, sweep-shape: 12,6, discard-code: 13,5, discard-analysis: 17,8, xpconnect: 14,7, deallocate: 0,1
----------
GC(T+12.8) TotalTime: 616,6ms, Type: global, MMU(20ms): 0%, MMU(50ms): 0%, Reason: CC_FORCED, NonIncrementalReason: requested, +chunks: 0, -chunks: 0
mark: 519,3, mark-roots: 31,0, mark-other: 376,2, sweep: 94,9, sweep-obj: 8,2, sweep-string: 0,4, sweep-script: 2,8, sweep-shape: 12,5, discard-code: 13,7, discard-analysis: 18,2, xpconnect: 14,6, deallocate: 0,2
----------
CC(T+15.5) duration: 3328ms, suspected: 264, visited: 357214 RCed and 2295596 GCed, collected: 146 RCed and 63 GCed (209 waiting for GC)
ForgetSkippable 2 times before CC, min: 0 ms, max: 78 ms, avg: 39 ms, total: 78 ms, removed: 328
----------
CC(T+46.6) duration: 2641ms, suspected: 403, visited: 350535 RCed and 2262682 GCed, collected: 30 RCed and 0 GCed (239 waiting for GC)
ForgetSkippable 21 times before CC, min: 0 ms, max: 16 ms, avg: 2 ms, total: 47 ms, removed: 3058
----------
CC(T+94.6) duration: 2656ms, suspected: 359, visited: 350335 RCed and 2262661 GCed, collected: 0 RCed and 0 GCed (239 waiting for GC)
ForgetSkippable 26 times before CC, min: 0 ms, max: 16 ms, avg: 0 ms, total: 16 ms, removed: 2713
----------
CC(T+103.3) duration: 2656ms, suspected: 428, visited: 350361 RCed and 2262641 GCed, collected: 0 RCed and 0 GCed (239 waiting for GC)
ForgetSkippable 5 times before CC, min: 0 ms, max: 0 ms, avg: 0 ms, total: 0 ms, removed: 459
----------
CC(T+129.0) duration: 2719ms, suspected: 400, visited: 350215 RCed and 2262138 GCed, collected: 8 RCed and 0 GCed (247 waiting for GC)
ForgetSkippable 40 times before CC, min: 0 ms, max: 16 ms, avg: 1 ms, total: 62 ms, removed: 7873
Other Measurements
13,462,324 B ── canvas-2d-pixel-bytes
1,062,083,442 B ── explicit
0 B ── gfx-d2d-surfacecache
0 B ── gfx-d2d-surfacevram
4,928 B ── gfx-surface-image
15,452,472 B ── gfx-surface-win32
758,538,436 B ── heap-allocated
787,902,464 B ── heap-committed
3.72% ── heap-committed-fragmentation
3,125,248 B ── heap-dirty
52,009,818 B ── heap-unallocated
2,191,228 B ── images-content-used-uncompressed
7 ── js-compartments-system
55 ── js-compartments-user
292,552,704 B ── js-gc-heap
7,574,256 B ── js-main-runtime-analysis-temporary
32,243,240 B ── js-main-runtime-gc-heap-arena-unused
0 B ── js-main-runtime-gc-heap-chunk-clean-unused
0 B ── js-main-runtime-gc-heap-chunk-dirty-unused
16,961,536 B ── js-main-runtime-gc-heap-decommitted
0.08% ── js-main-runtime-gc-heap-unused-fraction
7,321,728 B ── js-main-runtime-mjit
163,822,072 B ── js-main-runtime-objects
181,396,592 B ── js-main-runtime-scripts
118,469,864 B ── js-main-runtime-shapes
25,664,114 B ── js-main-runtime-strings
7,755,440 B ── js-main-runtime-type-inference
0 ── low-commit-space-events
0 ── low-memory-events-physical
0 ── low-memory-events-virtual
1,148,592,128 B ── private
1,158,930,432 B ── resident
19,188,064 B ── storage-sqlite
1,309,847,552 B ── vsize
69,603,242 B ── window-objects-dom
69,791,600 B ── window-objects-layout-arenas
19,982,832 B ── window-objects-layout-style-sets
114,688 B ── window-objects-layout-text-runs
29,208,736 B ── window-objects-style-sheets
Comment 1•12 years ago
|
||
Do you have any addons? Can you reproduce the problem if you disabled all the addons?
Have you tried Nightly. Does it behave better?
Thanks for your fast answer. I am sure the hangs will be much less a problem with all addons disabled. But the main reason for using Firefox is the availability of such addons.
At the moment (and the last months) I have to restart Firefox approximately twice a day. The snapshot above is taken after almost 11 hours uptime.
After starting Firefox it uses ~600 MB RAM and is much more responsive. It feels like its getting slower with every new tab/window opened (and closed), but this feeling is more a guess.
I have not tried nightly with my main browsing workspace yet (addon compatibility etc). I am also not sure if this will help, because this problem (slow responsiveness, high memory consumption) exists at least since Firefox 4. But if you think that there is any noteworthy improvement since Firefox 13, I will give it a try.
See also bug 646941, where I'm waiting for another occurrence to provide further debug info. Perhaps mark as duplicate of that?
Status: UNCONFIRMED → NEW
Ever confirmed: true
Comment 4•12 years ago
|
||
(In reply to karl155 from comment #2)
> Thanks for your fast answer. I am sure the hangs will be much less a problem
> with all addons disabled. But the main reason for using Firefox is the
> availability of such addons.
It would be helpful to know which addon causes the problems. Hopefully the addon
developer can fix the issue.
> At the moment (and the last months) I have to restart Firefox approximately
> twice a day.
That is not good. I can keep Firefox open for weeks are median CC time is around 10ms.
> The snapshot above is taken after almost 11 hours uptime.
> After starting Firefox it uses ~600 MB RAM and is much more responsive. It
> feels like its getting slower with every new tab/window opened (and closed),
> but this feeling is more a guess.
That is very possible, especially if you have some addon which does something to each
tab.
>
> I have not tried nightly with my main browsing workspace yet (addon
> compatibility etc). I am also not sure if this will help, because this
> problem (slow responsiveness, high memory consumption) exists at least since
> Firefox 4. But if you think that there is any noteworthy improvement since
> Firefox 13, I will give it a try.
There is a major change in Nightly, well, actually even in Aurora related to addons handling.
See Bug 695480. https://bugzilla.mozilla.org/show_bug.cgi?id=695480#c50 lists few addons which
leak without that patch.
Comment 5•12 years ago
|
||
(In reply to Olli Pettay [:smaug] from comment #4)
> That is not good. I can keep Firefox open for weeks are median CC time is
> around 10ms.
s/are/and/
Comment 6•12 years ago
|
||
If you can reproduce the problem easily,
could you perhaps create a cc log.
See https://bugzilla.mozilla.org/show_bug.cgi?id=646941#c18
(Note, cc log may contain some visited URLs)
Assignee | ||
Comment 7•12 years ago
|
||
What does your about:compartments page say? Odds are that one of your addons is leaking pages.
Assignee | ||
Comment 8•12 years ago
|
||
(when you are experiencing the problem, of course)
(In reply to Olli Pettay [:smaug] from comment #4)
> There is a major change in Nightly, well, actually even in Aurora related to
> addons handling.
I just switched to Nightly (BuildID 20120612030527). Lets see what will happen the next few hours.
(In reply to Andrew McCreight [:mccr8] from comment #7)
> What does your about:compartments page say? Odds are that one of your
> addons is leaking pages.
I went to about:compartments in Firefox 13 yesterday or so. Nothing unusual. Nightly also looks good for now. When I open a new tab, a new compartment is created. When I close the tab, the compartment disappears a few seconds later.
I will give you an update tomorrow. (Now: Resident 609 MB, GC 390ms, CC 463ms)
Reporter | ||
Comment 10•12 years ago
|
||
Something weired happened. Without restarting Nightly (see last comment) CC times fell to 16ms. (Resident 1069 MB, GC 424ms, CC 16ms)
The browser still feels "fresh" (like just restarted).
Only the about:blank usage looks a little odd to me.
Does it really mean, that a blank page consumes 300 MB RAM?
about:compartments
System Compartments
[System Principal], about:blank [78]
about:memory
1,007,514,657 B (100.0%) -- explicit
├────586,763,026 B (58.24%) -- js
│ ├──303,591,016 B (30.13%) -- compartment([System Principal], about:blank)
│ │ ├──165,494,784 B (16.43%) -- gc-heap
│ │ │ ├───57,136,760 B (05.67%) -- arena
│ │ │ │ ├──56,282,632 B (05.59%) ── unused [78]
│ │ │ │ ├─────478,912 B (00.05%) ── headers [38]
│ │ │ │ └─────375,216 B (00.04%) ── padding [37]
│ │ │ ├───48,258,752 B (04.79%) -- objects
│ │ │ │ ├──29,397,488 B (02.92%) ── non-function [75]
│ │ │ │ └──18,861,264 B (01.87%) ── function [76]
│ │ │ ├───31,692,872 B (03.15%) -- shapes
│ │ │ │ ├──23,439,984 B (02.33%) ── tree [76]
│ │ │ │ ├───4,410,072 B (00.44%) ── dict [75]
│ │ │ │ └───3,842,816 B (00.38%) ── base [75]
│ │ │ ├───25,710,624 B (02.55%) ── scripts [75]
│ │ │ ├────1,984,656 B (00.20%) ── strings [38]
│ │ │ ├──────668,784 B (00.07%) ── sundries [77]
│ │ │ └───────42,336 B (00.00%) ── type-objects [2]
│ │ ├───92,651,256 B (09.20%) ── script-data [75]
│ │ ├───22,817,248 B (02.26%) -- shapes-extra
│ │ │ ├──13,618,432 B (01.35%) ── tree-tables [75]
│ │ │ ├───4,530,368 B (00.45%) ── tree-shape-kids [75]
│ │ │ ├───3,055,616 B (00.30%) ── compartment-tables [75]
│ │ │ └───1,612,832 B (00.16%) ── dict-tables [74]
│ │ ├───17,651,488 B (01.75%) -- objects
│ │ │ ├──15,542,976 B (01.54%) ── slots [76]
│ │ │ └───2,108,512 B (00.21%) ── elements [37]
│ │ ├────1,664,904 B (00.17%) ── string-chars [39]
│ │ ├────1,441,792 B (00.14%) ── analysis-temporary [2]
│ │ ├──────730,320 B (00.07%) ── type-inference/script-main [74]
│ │ ├──────704,512 B (00.07%) ── cross-compartment-wrappers [50]
│ │ ├──────334,520 B (00.03%) ── other-sundries [78]
│ │ └──────100,192 B (00.01%) ── mjit-data [2]
16,138,324 B ── canvas-2d-pixel-bytes
1,007,522,949 B ── explicit
0 B ── gfx-d2d-surfacecache
0 B ── gfx-d2d-surfacevram
4,880 B ── gfx-surface-image
18,655,843 B ── gfx-surface-win32
0 ── ghost-windows
660,178,772 B ── heap-allocated
696,168,448 B ── heap-committed
35,974,030 B ── heap-committed-unused
5.44% ── heap-committed-unused-ratio
3,641,344 B ── heap-dirty
66,477,178 B ── heap-unused
2,200,479 B ── images-content-used-uncompressed
475 ── js-compartments-system
425 ── js-compartments-user
342,884,352 B ── js-gc-heap
17,638,544 B ── js-main-runtime-analysis-temporary
185,020,256 B ── js-main-runtime-gc-heap-allocated
106,107,040 B ── js-main-runtime-gc-heap-arena-unused
5,242,880 B ── js-main-runtime-gc-heap-chunk-clean-unused
22,134,784 B ── js-main-runtime-gc-heap-chunk-dirty-unused
318,504,960 B ── js-main-runtime-gc-heap-committed
133,484,704 B ── js-main-runtime-gc-heap-committed-unused
72.14% ── js-main-runtime-gc-heap-committed-unused-ratio
24,379,392 B ── js-main-runtime-gc-heap-decommitted
494,424 B ── js-main-runtime-mjit
116,278,592 B ── js-main-runtime-objects
158,921,320 B ── js-main-runtime-scripts
82,686,784 B ── js-main-runtime-shapes
16,250,418 B ── js-main-runtime-strings
6,803,264 B ── js-main-runtime-type-inference
0 ── low-commit-space-events
0 ── low-memory-events-physical
0 ── low-memory-events-virtual
1,103,884,288 B ── private
1,107,111,936 B ── resident
33,988,584 B ── storage-sqlite
1,252,732,928 B ── vsize
0 B ── window-objects-dom-cdata-nodes
143,512 B ── window-objects-dom-comment-nodes
52,869,788 B ── window-objects-dom-element-nodes
2,193,712 B ── window-objects-dom-other
17,693,974 B ── window-objects-dom-text-nodes
66,959,552 B ── window-objects-layout-arenas
3,712,080 B ── window-objects-layout-pres-contexts
19,597,776 B ── window-objects-layout-style-sets
118,880 B ── window-objects-layout-text-runs
29,451,256 B ── window-objects-style-sheets
Reporter | ||
Comment 11•12 years ago
|
||
Looks like something is leaking system compartments. The number of about:blank compartments even raised after my last comment.
(Resident: 1122 MB, GC 456ms, CC 31ms)
Following tabs are really opened (among others)
about:addons (once)
about:memory (once)
about:memory?verbose (once)
about:compartments?verbose (once)
Output of about:compartments
Main Process
User Compartments
about:blank
(...)
System Compartments
[System Principal], about:addons [2]
[System Principal], about:blank [86]
[System Principal], about:compartments [4]
[System Principal], about:compartments?verbose
[System Principal], about:memory
[System Principal], about:memory?verbose
(...)
Ghost Windows
(None)
Assignee | ||
Comment 12•12 years ago
|
||
> Something weired happened. Without restarting Nightly (see last comment) CC times
> fell to 16ms. (Resident 1069 MB, GC 424ms, CC 16ms)
> The browser still feels "fresh" (like just restarted).
Probably what happened there is that there was some page that was leaking, slowing down the CC, that eventually was freed up. In the future if you get slow CCs again, you could take a CC dump and post it here or email it to me. It will contain URLs of pages you have open, or possibly those you have opened earlier in the session, if those are leaking:
https://wiki.mozilla.org/Performance:Leak_Tools#Cycle_collector_heap_dump
The about:blank problem is probably something different. See bug 759581. If you could post what you are seeing there, that would be useful, along with addons.
Reporter | ||
Comment 13•12 years ago
|
||
(In reply to Andrew McCreight [:mccr8] from comment #12)
> Probably what happened there is that there was some page that was leaking,
> slowing down the CC, that eventually was freed up.
I went through my history of the last days and I saw some CC spikes (>1000ms) on this page: http://v6launch.ripe.net/cgi-bin/index.cgi
After closing it again, it remains as zombie compartment even after minutes.
about:compartments
User Compartments
http://v6launch.ripe.net/cgi-bin/index.cgi
http://v6launch.ripe.net/cgi-bin/index.cgi, about:blank [3]
about:memory
1,262,084,630 B (100.0%) -- explicit
├────758,434,792 B (60.09%) -- js
│ ├──410,241,596 B (32.51%) -- compartment([System Principal], about:blank)
│ ├───28,869,944 B (02.29%) -- compartment(http://v6launch.ripe.net/cgi-bin/index.cgi, about:blank)
│ │ ├──23,486,464 B (01.86%) -- gc-heap
│ │ │ ├──11,115,256 B (00.88%) -- arena
│ │ │ │ ├──11,000,696 B (00.87%) ── unused [3]
│ │ │ │ ├──────91,744 B (00.01%) ── headers [3]
│ │ │ │ └──────22,816 B (00.00%) ── padding
│ │ │ ├──10,879,648 B (00.86%) -- objects
│ │ │ │ ├──10,225,056 B (00.81%) ── non-function [3]
│ │ │ │ └─────654,592 B (00.05%) ── function [3]
│ │ │ ├─────824,040 B (00.07%) -- shapes
│ │ │ │ ├──367,656 B (00.03%) ── tree [3]
│ │ │ │ ├──344,736 B (00.03%) ── dict [3]
│ │ │ │ └──111,648 B (00.01%) ── base [3]
│ │ │ ├─────465,024 B (00.04%) ── scripts [3]
│ │ │ ├─────159,808 B (00.01%) ── type-objects [3]
│ │ │ ├──────22,848 B (00.00%) ── sundries [3]
│ │ │ └──────19,840 B (00.00%) ── strings
│ │ ├───2,827,840 B (00.22%) -- objects
│ │ │ ├──1,480,256 B (00.12%) ── elements [2]
│ │ │ └──1,347,584 B (00.11%) ── slots [3]
│ │ ├───1,548,952 B (00.12%) ── script-data [3]
│ │ ├─────534,528 B (00.04%) -- shapes-extra
│ │ │ ├──214,368 B (00.02%) ── tree-tables [3]
│ │ │ ├──167,552 B (00.01%) ── dict-tables [3]
│ │ │ ├───79,872 B (00.01%) ── compartment-tables [3]
│ │ │ └───72,736 B (00.01%) ── tree-shape-kids [3]
│ │ ├─────299,680 B (00.02%) ── analysis-temporary [3]
│ │ ├─────136,096 B (00.01%) -- type-inference
│ │ │ ├───96,800 B (00.01%) ── object-main [3]
│ │ │ └───39,296 B (00.00%) ── tables [3]
│ │ ├──────25,840 B (00.00%) ── string-chars
│ │ └──────10,544 B (00.00%) ── other-sundries [3]
Reporter | ||
Comment 14•12 years ago
|
||
Now CC times went to >1000 ms again. I made a CC dump as requested in comment 12.
https://hotfile.com/dl/159783939/6e78ea5/cc-edges.zip.html (9,4 MB unpacked)
(not uploaded as attachment because it may contain private informations)
If you need to know whats behind a masked URL, just let me know the line number.
Something interesting from the dump: Counts how often an URL was found in the log
5923 http://v6launch.ripe.net/cgi-bin/http.cgi?embedded=1
5249 chrome://browser/content/browser.xul
1135 chrome://console2/content/console2.xul
360 https://developer.mozilla.org/en/CSS/-moz-image-rect
315 chrome://firebug/content/firefox/firebugFrame.xul
256 https://developer.mozilla.org/En/CSS/Text-overflow
195 https://developer.mozilla.org/en/css/-moz-border-image
(...)
from error console:
Warning: Error: Failed to preserve wrapper of wrapped native weak map key.
Source file: chrome://browser/content/places/treeView.js
Line: 1215
----------
Error: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIDOMWindow.content]
----------
CC(T+154963.9) duration: 32ms, suspected: 1029, visited: 10363 RCed and 13455 GCed, collected: 13 RCed and 0 GCed (36 waiting for GC)
ForgetSkippable 16 times before CC, min: 0 ms, max: 0 ms, avg: 0 ms, total: 0 ms, removed: 11543
----------
GC(T+154967.0) Total Time: 632,6ms, Compartments Collected: 745, Total Compartments: 745, MMU (20ms): 0%, MMU (50ms): 0%, Reason: SET_NEW_DOCUMENT, Nonincremental Reason: requested, Allocated: 387MB, +Chunks: 0, -Chunks: 0
Totals: Purge: 0,3ms, Mark: 471,5ms, Mark Roots: 32,7ms, Mark Other: 23,1ms, Finalize Start Callback: 2,2ms, Sweep: 150,5ms, Sweep Compartments: 68,5ms, Sweep Object: 17,6ms, Sweep String: 5,7ms, Sweep Script: 4,8ms, Sweep Shape: 25,7ms, Discard Code: 16,2ms, Discard Analysis: 23,9ms, Discard TI: 1,9ms, Sweep Types: 10,6ms, Clear Script Analysis: 4,4ms, Finalize End Callback: 19,9ms, Deallocate: 0,2ms, End Callback: 9,8ms
----------
GC(T+154971.3) Total Time: 585,7ms, Compartments Collected: 746, Total Compartments: 746, MMU (20ms): 0%, MMU (50ms): 0%, Reason: CC_FORCED, Nonincremental Reason: requested, Allocated: 379MB, +Chunks: 0, -Chunks: 0
Totals: Mark: 472,5ms, Mark Roots: 31,5ms, Mark Other: 23,2ms, Finalize Start Callback: 2,2ms, Sweep: 112,5ms, Sweep Compartments: 55,7ms, Sweep Object: 3,8ms, Sweep String: 0,4ms, Sweep Script: 4,3ms, Sweep Shape: 22,5ms, Discard Code: 15,0ms, Discard Analysis: 18,9ms, Discard TI: 0,9ms, Sweep Types: 9,4ms, Clear Script Analysis: 4,3ms, Finalize End Callback: 17,5ms, Deallocate: 0,4ms, End Callback: 0,4ms
----------
CC(T+154971.4) duration: 719ms, suspected: 664, visited: 35146 RCed and 76113 GCed, collected: 129 RCed and 9 GCed (138 waiting for GC)
ForgetSkippable 8 times before CC, min: 0 ms, max: 46 ms, avg: 9 ms, total: 77 ms, removed: 2344
----------
GC(T+154977.8) Total Time: 1197,8ms, Compartments Collected: 686, Total Compartments: 686, MMU (20ms): 0%, MMU (50ms): 0%, Reason: CC_FORCED, Nonincremental Reason: requested, Allocated: 379MB, +Chunks: 0, -Chunks: 0
Totals: Begin Callback: 0,1ms, Wait Background Thread: 32,0ms, Purge: 0,1ms, Mark: 942,7ms, Mark Roots: 64,2ms, Mark Other: 46,4ms, Finalize Start Callback: 4,3ms, Sweep: 221,7ms, Sweep Compartments: 109,0ms, Sweep Object: 7,6ms, Sweep String: 0,9ms, Sweep Script: 8,7ms, Sweep Shape: 45,1ms, Discard Code: 29,3ms, Discard Analysis: 36,7ms, Discard TI: 1,8ms, Sweep Types: 18,6ms, Clear Script Analysis: 8,5ms, Finalize End Callback: 34,4ms, Deallocate: 0,5ms, End Callback: 0,6ms
----------
....cc-edges-2.3904.log
----------
CC(T+154978.4) duration: 1812ms, suspected: 1399, visited: 35521 RCed and 76434 GCed, collected: 51 RCed and 316 GCed (367 waiting for GC)
ForgetSkippable 6 times before CC, min: 0 ms, max: 31 ms, avg: 10 ms, total: 62 ms, removed: 451
----------
Warning: Error: A long running script was terminated
Source file: chrome://browser/content/places/treeView.js
Line: 1279
----------
GC(T+154983.0) Total Time: 591,0ms, Compartments Collected: 682, Total Compartments: 682, MMU (20ms): 0%, MMU (50ms): 0%, Reason: CC_WAITING, Nonincremental Reason: requested, Allocated: 379MB, +Chunks: 0, -Chunks: 0
Totals: Mark: 476,7ms, Mark Roots: 31,7ms, Mark Other: 23,2ms, Finalize Start Callback: 2,1ms, Sweep: 113,3ms, Sweep Compartments: 54,6ms, Sweep Object: 4,2ms, Sweep String: 0,8ms, Sweep Script: 4,3ms, Sweep Shape: 22,8ms, Discard Code: 15,0ms, Discard Analysis: 18,3ms, Discard TI: 0,9ms, Sweep Types: 9,3ms, Clear Script Analysis: 4,2ms, Finalize End Callback: 18,5ms, Deallocate: 0,2ms, End Callback: 0,5ms
----------
GC(T+154985.9) Total Time: 1206,3ms, Compartments Collected: 684, Total Compartments: 684, MMU (20ms): 0%, MMU (50ms): 0%, Reason: CC_FORCED, Nonincremental Reason: requested, Allocated: 379MB, +Chunks: 0, -Chunks: 0
Totals: Begin Callback: 0,1ms, Wait Background Thread: 32,2ms, Purge: 0,1ms, Mark: 950,4ms, Mark Roots: 64,2ms, Mark Other: 46,7ms, Finalize Start Callback: 4,3ms, Sweep: 222,6ms, Sweep Compartments: 109,6ms, Sweep Object: 7,3ms, Sweep String: 1,2ms, Sweep Script: 8,5ms, Sweep Shape: 44,8ms, Discard Code: 30,4ms, Discard Analysis: 36,3ms, Discard TI: 1,7ms, Sweep Types: 18,4ms, Clear Script Analysis: 8,5ms, Finalize End Callback: 35,1ms, Deallocate: 0,4ms, End Callback: 0,4ms
----------
CC(T+154986.1) duration: 1360ms, suspected: 317, visited: 34863 RCed and 76174 GCed, collected: 0 RCed and 0 GCed (0 waiting for GC)
ForgetSkippable 1 times before CC, min: 31 ms, max: 31 ms, avg: 31 ms, total: 31 ms, removed: 424
Comment 15•12 years ago
|
||
Ah, you have firebug. I think it is still leaking.
Comment 16•12 years ago
|
||
As far as performance is concerned, don't Firebug's leaks have the exact same effect as simply having a lot more tabs open? In other words, does it matter that it's Firebug, or could it just as well have been a bunch of open tabs causing slow CC?
Comment 17•12 years ago
|
||
(In reply to Roman from comment #16)
> As far as performance is concerned, don't Firebug's leaks have the exact
> same effect as simply having a lot more tabs open?
No.
Normally one tabs adds very tiny bit of stuff to the cycle collection graph.
We're pretty good at optimizing stuff out from the graph.
> In other words, does it
> matter that it's Firebug, or could it just as well have been a bunch of open
> tabs causing slow CC?
IIRC, Firebug (and some other addons) does still cause runtime leaks, at least in FF13. I.e. keeps
some documents alive too long in a way which is hard for optimize out in cycle collector.
That causes bad CC times.
Reporter | ||
Comment 18•12 years ago
|
||
(In reply to Olli Pettay [:smaug] from comment #17)
> (In reply to Roman from comment #16)
> IIRC, Firebug (and some other addons) does still cause runtime leaks, at
> least in FF13. I.e. keeps
> some documents alive too long in a way which is hard for optimize out in
> cycle collector.
> That causes bad CC times.
The dump was posted from Nightly. (I am running Nightly since comment 9)
User Agent: Mozilla/5.0 (Windows NT 5.1; rv:16.0) Gecko/16.0 Firefox/16.0a1
Build ID: 20120612030527
Maybe interesting:
I did not have Firebug open in this browsing session. Before starting Nightly, I deleted the Firebug folder from my profile, so Firebug is disabled for all sites.
Do you think Firebug is responsible for the lots of about:blank compartments?
(see comment 11)
Now:
about:compartments
[System Principal], about:blank [121]
about:memory
1,318.86 MB (100.0%) -- explicit
├────802.89 MB (60.88%) -- js
│ ├──475.25 MB (36.04%) -- compartment([System Principal], about:blank)
Assignee | ||
Comment 19•12 years ago
|
||
It looks like there are a couple of problems here. First off, that IPv6 page seems to have something we don't optimize well. I'm getting CC times like this, which just that page open:
CC(T+232.7) duration: 131ms, suspected: 215, visited: 10102 RCed and 47470 GCed, collected: 461 RCed and 2010 GCed (2471 waiting for GC)
ForgetSkippable 18 times before CC, min: 0 ms, max: 12 ms, avg: 2 ms, total: 37 ms, removed: 2449
Initially, my CC times were okay, but at a certain point they started getting long.
The second problem you are seeing is that the page isn't being cleaned up properly. I'm not seeing that locally. Maybe it is an addon? If you are using Nightly, it shouldn't be possible for an addon to do that, but maybe not. The IPv6 page is really huge, so I'm not surprised that you are seeing long CC times when it ends up as a zombie.
I don't think Firebug is causing the about:blank, as we've seen other people having that problem.
Assignee | ||
Comment 20•12 years ago
|
||
I'll file a new bug for the http://v6launch.ripe.net/cgi-bin/index.cgi problem, as that seems like a nicely contained one. We can leave this bug for figuring out the source of your zombie compartments.
Assignee | ||
Updated•12 years ago
|
Summary: Periodic hangs - CC > 3000 ms → Periodic hangs - CC > 3000 ms (due to zombie compartments?)
Assignee | ||
Updated•12 years ago
|
Assignee: nobody → continuation
Assignee | ||
Comment 21•11 years ago
|
||
I don't think there's anything actionable here, aside from bug 764899.
Status: NEW → RESOLVED
Closed: 11 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•