Closed Bug 1675003 Opened 4 years ago Closed 3 years ago

Intermittent toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Delta has the right sign: NaN vs 1028096 - false == true - JS frame :: chrome://mochitests/content/browser/toolkit/components/aboutpro

Categories

(Toolkit :: Performance Monitoring, defect, P5)

defect

Tracking

()

RESOLVED FIXED
87 Branch
Tracking Status
firefox86 --- fixed
firefox87 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: florian)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Attachments

(1 file)

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=320560724&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IyvCHsJURruK9T1eRCL4Dg/runs/0/artifacts/public/logs/live_backing.log


[task 2020-11-03T10:26:20.742Z] 10:26:20     INFO - TEST-START | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js
[task 2020-11-03T10:26:20.745Z] 10:26:20     INFO - GECKO(1510) | [Child 1597: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f2ed631ec00 == 4 [pid = 1597] [id = 7] [url = about:blank]
[task 2020-11-03T10:26:20.746Z] 10:26:20     INFO - GECKO(1510) | [Child 1597: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f2ed631c000 == 3 [pid = 1597] [id = 6] [url = moz-extension://e30bcee9-3ef5-4434-8669-549817171100/_generated_background_page.html]
[task 2020-11-03T10:26:20.823Z] 10:26:20     INFO - GECKO(1510) | [Child 1597: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f2ed631a400 == 4 [pid = 1597] [id = 8]
[task 2020-11-03T10:26:20.824Z] 10:26:20     INFO - GECKO(1510) | [Child 1597: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (0x7f2ed7df33c0) [pid = 1597] [serial = 23] [outer = (nil)]
[task 2020-11-03T10:26:20.824Z] 10:26:20     INFO - GECKO(1510) | [Child 1597: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 12 (0x7f2ed631bc00) [pid = 1597] [serial = 24] [outer = 0x7f2ed7df33c0]
[task 2020-11-03T10:26:20.831Z] 10:26:20     INFO - GECKO(1510) | [Parent 1510, Main Thread] WARNING: Need BrowserChild to get the nativeWindow from!: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:1040
[task 2020-11-03T10:26:20.847Z] 10:26:20     INFO - GECKO(1510) | [Child 1597, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2710
[task 2020-11-03T10:26:20.854Z] 10:26:20     INFO - GECKO(1510) | [Child 1597: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 13 (0x7f2ed6322800) [pid = 1597] [serial = 25] [outer = 0x7f2ed7df33c0]
[task 2020-11-03T10:26:20.890Z] 10:26:20     INFO - GECKO(1510) | [Child 1597: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f2ed6324c00 == 5 [pid = 1597] [id = 9]
[task 2020-11-03T10:26:20.890Z] 10:26:20     INFO - GECKO(1510) | [Child 1597: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 14 (0x7f2ed7df3900) [pid = 1597] [serial = 26] [outer = (nil)]
[task 2020-11-03T10:26:20.906Z] 10:26:20     INFO - GECKO(1510) | [Child 1597: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 15 (0x7f2ed631dc00) [pid = 1597] [serial = 27] [outer = 0x7f2ed7df3900]
[task 2020-11-03T10:26:20.921Z] 10:26:20     INFO - GECKO(1510) | [Parent 1510: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f15d7877c00 == 9 [pid = 1510] [id = 13]
[task 2020-11-03T10:26:20.922Z] 10:26:20     INFO - GECKO(1510) | [Parent 1510: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 19 (0x7f15d78c7040) [pid = 1510] [serial = 34] [outer = (nil)]
[task 2020-11-03T10:26:20.922Z] 10:26:20     INFO - GECKO(1510) | [Parent 1510: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 20 (0x7f15d98d4c00) [pid = 1510] [serial = 35] [outer = 0x7f15d78c7040]
[task 2020-11-03T10:26:20.961Z] 10:26:20     INFO - GECKO(1510) | [Child 1576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f992230e400 == 2 [pid = 1576] [id = 4]
[task 2020-11-03T10:26:20.962Z] 10:26:20     INFO - GECKO(1510) | [Child 1576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f99234e43c0) [pid = 1576] [serial = 12] [outer = (nil)]
[task 2020-11-03T10:26:20.981Z] 10:26:20     INFO - GECKO(1510) | [Child 1576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7f992230f400) [pid = 1576] [serial = 13] [outer = 0x7f99234e43c0]
[task 2020-11-03T10:26:20.985Z] 10:26:20     INFO - GECKO(1510) | [Child 1730: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f6a61250800 == 2 [pid = 1730] [id = 2]
[task 2020-11-03T10:26:20.986Z] 10:26:20     INFO - GECKO(1510) | [Child 1730: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f6a612cc040) [pid = 1730] [serial = 7] [outer = (nil)]
[task 2020-11-03T10:26:20.986Z] 10:26:20     INFO - GECKO(1510) | [Child 1730: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7f6a61254800) [pid = 1730] [serial = 8] [outer = 0x7f6a612cc040]
[task 2020-11-03T10:26:21.010Z] 10:26:21     INFO - GECKO(1510) | [Child 1751: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f58ed64ac00 == 2 [pid = 1751] [id = 2]
[task 2020-11-03T10:26:21.013Z] 10:26:21     INFO - GECKO(1510) | [Child 1751: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f58ed6cf040) [pid = 1751] [serial = 7] [outer = (nil)]
[task 2020-11-03T10:26:21.014Z] 10:26:21     INFO - GECKO(1510) | [Child 1751: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7f58ed652400) [pid = 1751] [serial = 8] [outer = 0x7f58ed6cf040]
[task 2020-11-03T10:26:21.041Z] 10:26:21     INFO - GECKO(1510) | [Child 1755: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fbefd04d400 == 2 [pid = 1755] [id = 2]
[task 2020-11-03T10:26:21.042Z] 10:26:21     INFO - GECKO(1510) | [Child 1755: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fbefd0d0040) [pid = 1755] [serial = 7] [outer = (nil)]
[task 2020-11-03T10:26:21.042Z] 10:26:21     INFO - GECKO(1510) | [Child 1755: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7fbefd054400) [pid = 1755] [serial = 8] [outer = 0x7fbefd0d0040]
[task 2020-11-03T10:26:21.078Z] 10:26:21     INFO - GECKO(1510) | [Child 1760: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fda4e24ac00 == 2 [pid = 1760] [id = 2]
[task 2020-11-03T10:26:21.078Z] 10:26:21     INFO - GECKO(1510) | [Child 1760: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fda4e2cf040) [pid = 1760] [serial = 7] [outer = (nil)]
[task 2020-11-03T10:26:21.079Z] 10:26:21     INFO - GECKO(1510) | [Child 1760: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7fda4e252400) [pid = 1760] [serial = 8] [outer = 0x7fda4e2cf040]
[task 2020-11-03T10:26:21.094Z] 10:26:21     INFO - GECKO(1510) | [Child 1766: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f3f97d4bc00 == 2 [pid = 1766] [id = 2]
[task 2020-11-03T10:26:21.095Z] 10:26:21     INFO - GECKO(1510) | [Child 1766: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f3f97dd0040) [pid = 1766] [serial = 7] [outer = (nil)]
[task 2020-11-03T10:26:21.095Z] 10:26:21     INFO - GECKO(1510) | [Child 1766: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7f3f97d53400) [pid = 1766] [serial = 8] [outer = 0x7f3f97dd0040]
[task 2020-11-03T10:26:21.131Z] 10:26:21     INFO - GECKO(1510) | [Child 1781: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f8ebba4c400 == 2 [pid = 1781] [id = 2]
[task 2020-11-03T10:26:21.131Z] 10:26:21     INFO - GECKO(1510) | [Child 1781: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f8ebbad0040) [pid = 1781] [serial = 7] [outer = (nil)]
[task 2020-11-03T10:26:21.132Z] 10:26:21     INFO - GECKO(1510) | [Child 1781: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 4 (0x7f8ebba53400) [pid = 1781] [serial = 8] [outer = 0x7f8ebbad0040]
[task 2020-11-03T10:26:21.293Z] 10:26:21     INFO - GECKO(1510) | [Parent 1510: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 21 (0x7f15dc89d000) [pid = 1510] [serial = 36] [outer = 0x7f15d78c7040]
[task 2020-11-03T10:26:21.321Z] 10:26:21     INFO - GECKO(1510) | [Parent 1510, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1132
[task 2020-11-03T10:26:21.602Z] 10:26:21     INFO - GECKO(1510) | [Child 1576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7f992343bc00) [pid = 1576] [serial = 14] [outer = 0x7f99234e43c0]
[task 2020-11-03T10:26:21.853Z] 10:26:21     INFO - GECKO(1510) | [Child 1751: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7f58ed6a7800) [pid = 1751] [serial = 9] [outer = 0x7f58ed6cf040]
[task 2020-11-03T10:26:21.857Z] 10:26:21     INFO - GECKO(1510) | [Child 1730: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7f6a612a6000) [pid = 1730] [serial = 9] [outer = 0x7f6a612cc040]
[task 2020-11-03T10:26:21.938Z] 10:26:21     INFO - GECKO(1510) | [Child 1755: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7fbefd0ad800) [pid = 1755] [serial = 9] [outer = 0x7fbefd0d0040]
[task 2020-11-03T10:26:21.939Z] 10:26:21     INFO - GECKO(1510) | [Parent 1510, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileChunk.cpp:403
[task 2020-11-03T10:26:21.940Z] 10:26:21     INFO - GECKO(1510) | [Child 1766: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7f3f97da8c00) [pid = 1766] [serial = 9] [outer = 0x7f3f97dd0040]
[task 2020-11-03T10:26:21.967Z] 10:26:21     INFO - GECKO(1510) | [Parent 1510, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileChunk.cpp:403
[task 2020-11-03T10:26:21.967Z] 10:26:21     INFO - GECKO(1510) | [Parent 1510, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileChunk.cpp:403
[task 2020-11-03T10:26:21.968Z] 10:26:21     INFO - GECKO(1510) | [Child 1760: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7fda4e2a6400) [pid = 1760] [serial = 9] [outer = 0x7fda4e2cf040]
[task 2020-11-03T10:26:21.976Z] 10:26:21     INFO - GECKO(1510) | [Child 1781: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 5 (0x7f8ebbaa8c00) [pid = 1781] [serial = 9] [outer = 0x7f8ebbad0040]
[task 2020-11-03T10:26:21.992Z] 10:26:21     INFO - GECKO(1510) | [Child 1576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f9922310c00 == 3 [pid = 1576] [id = 5]
[task 2020-11-03T10:26:21.992Z] 10:26:21     INFO - GECKO(1510) | [Child 1576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 6 (0x7f99234e4900) [pid = 1576] [serial = 15] [outer = (nil)]
[task 2020-11-03T10:26:22.040Z] 10:26:22     INFO - GECKO(1510) | [Child 1576: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 7 (0x7f9923491400) [pid = 1576] [serial = 16] [outer = 0x7f99234e4900]
[task 2020-11-03T10:26:23.811Z] 10:26:23     INFO - TEST-INFO | started process screentopng
[task 2020-11-03T10:26:24.034Z] 10:26:24     INFO - TEST-INFO | screentopng: exit 0
[task 2020-11-03T10:26:24.035Z] 10:26:24     INFO - Buffered messages logged at 10:26:20
[task 2020-11-03T10:26:24.035Z] 10:26:24     INFO - Entering test bound testShowFramesWithoutThreads
[task 2020-11-03T10:26:24.036Z] 10:26:24     INFO - Extension loaded
[task 2020-11-03T10:26:24.037Z] 10:26:24     INFO - Setting up about:processes
[task 2020-11-03T10:26:24.037Z] 10:26:24     INFO - Setting up example.com
[task 2020-11-03T10:26:24.038Z] 10:26:24     INFO - Setting up tabs we intend to close
[task 2020-11-03T10:26:24.038Z] 10:26:24     INFO - Buffered messages logged at 10:26:22
[task 2020-11-03T10:26:24.038Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | {"location":{"href":"about:processes","origin":"null","protocol":"about:","host":"","hostname":"","port":"","pathname":"processes","search":"","hash":"","assign":"function assign() {\n    [native code]\n}","replace":"function replace() {\n    [native code]\n}","reload":"function reload() {\n    [native code]\n}","toString":"function toString() {\n    [native code]\n}"}} == true - 
[task 2020-11-03T10:26:24.039Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | {} == true - 
[task 2020-11-03T10:26:24.039Z] 10:26:24     INFO - Setting up fake process hang detector
[task 2020-11-03T10:26:24.039Z] 10:26:24     INFO - Buffered messages logged at 10:26:23
[task 2020-11-03T10:26:24.040Z] 10:26:24     INFO - Looking at the contents of about:processes
[task 2020-11-03T10:26:24.040Z] 10:26:24     INFO - Running sanity tests on browser
[task 2020-11-03T10:26:24.041Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | found a table row for browser - true == true - 
[task 2020-11-03T10:26:24.041Z] 10:26:24     INFO - Sanity checks: type
[task 2020-11-03T10:26:24.048Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Type browser should be one of browser - true == true - 
[task 2020-11-03T10:26:24.048Z] 10:26:24     INFO - Sanity checks: pid
[task 2020-11-03T10:26:24.049Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Checking pid 1510 - true == true - 
[task 2020-11-03T10:26:24.054Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | 1510 == 1510 - 
[task 2020-11-03T10:26:24.054Z] 10:26:24     INFO - Sanity checks: memory resident
[task 2020-11-03T10:26:24.054Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Testing memory display 406MB (+9MB) vs total 425426944, delta 9629696 - true == true - 
[task 2020-11-03T10:26:24.055Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Can we parse 406MB (+9MB) with /([0-9.,]+)(TB|GB|MB|KB|B)( \(([-+]?)([0-9.,]+)(GB|MB|KB|B)\))?/? - ["406MB (+9MB)","406","MB"," (+9MB)","+","9","MB"] != null - 
[task 2020-11-03T10:26:24.055Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Unitless total memory use is greater than 0: 406 - true == true - 
[task 2020-11-03T10:26:24.056Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Unitless total memory use is less than 1024: 406 - true == true - 
[task 2020-11-03T10:26:24.057Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The displayed approximation of the total amount of memory is reasonable: 425721856 vs 425426944 - true == true - 
[task 2020-11-03T10:26:24.057Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The total amount amount of memory is reasonable: 425721856 in [1048576, 1099511627776] - true == true - 
[task 2020-11-03T10:26:24.058Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Unitless delta memory use is in (0, 1024): 9 - true == true - 
[task 2020-11-03T10:26:24.058Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Delta unit is reasonable: MB - true == true - 
[task 2020-11-03T10:26:24.058Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Delta has the right sign: 9437184 vs 9629696 - true == true - 
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - Sanity checks: CPU (Total)
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - Testing CPU display 85% (26s) vs total 25820000000, slope 0.8470186314853888
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The displayed approximation of the slope is reasonable: 85 vs 84.70186314853888 - true == true - 
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Not too little: 0.85 >=? 0  - true == true - 
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Not too much: 0.85 <=? 1000  - true == true - 
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The displayed approximation of the total duration is reasonable: 26000 vs 25820 - true == true - 
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The total number of MS is reasonable 25820: [10, 10000000] - true == true - 
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - In this mode, we shouldn't display any threads
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | In hidden threads mode, we shouldn't have any thread summary - null == null - 
[task 2020-11-03T10:26:24.059Z] 10:26:24     INFO - Testing subframes
[task 2020-11-03T10:26:24.060Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | "about:blank" == "about:blank" - 
[task 2020-11-03T10:26:24.060Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Found at least one about:blank in-process subframe - true == true - 
[task 2020-11-03T10:26:24.060Z] 10:26:24     INFO - Running sanity tests on hung
[task 2020-11-03T10:26:24.060Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | found a table row for hung - true == true - 
[task 2020-11-03T10:26:24.062Z] 10:26:24     INFO - Sanity checks: type
[task 2020-11-03T10:26:24.063Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Type web should be one of web,webIsolated - true == true - 
[task 2020-11-03T10:26:24.063Z] 10:26:24     INFO - Sanity checks: pid
[task 2020-11-03T10:26:24.064Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Checking pid 1576 - true == true - 
[task 2020-11-03T10:26:24.064Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | 1576 == 1576 - 
[task 2020-11-03T10:26:24.065Z] 10:26:24     INFO - Sanity checks: memory resident
[task 2020-11-03T10:26:24.065Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Testing memory display 21MB (+2MB) vs total 21774336, delta 1650688 - true == true - 
[task 2020-11-03T10:26:24.067Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Can we parse 21MB (+2MB) with /([0-9.,]+)(TB|GB|MB|KB|B)( \(([-+]?)([0-9.,]+)(GB|MB|KB|B)\))?/? - ["21MB (+2MB)","21","MB"," (+2MB)","+","2","MB"] != null - 
[task 2020-11-03T10:26:24.067Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Unitless total memory use is greater than 0: 21 - true == true - 
[task 2020-11-03T10:26:24.068Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Unitless total memory use is less than 1024: 21 - true == true - 
[task 2020-11-03T10:26:24.068Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The displayed approximation of the total amount of memory is reasonable: 22020096 vs 21774336 - true == true - 
[task 2020-11-03T10:26:24.069Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The total amount amount of memory is reasonable: 22020096 in [1048576, 1099511627776] - true == true - 
[task 2020-11-03T10:26:24.070Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Unitless delta memory use is in (0, 1024): 2 - true == true - 
[task 2020-11-03T10:26:24.071Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Delta unit is reasonable: MB - true == true - 
[task 2020-11-03T10:26:24.072Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Delta has the right sign: 2097152 vs 1650688 - true == true - 
[task 2020-11-03T10:26:24.072Z] 10:26:24     INFO - Sanity checks: CPU (Total)
[task 2020-11-03T10:26:24.073Z] 10:26:24     INFO - Testing CPU display 8% (1s) vs total 1390000000, slope 0.08012338405942868
[task 2020-11-03T10:26:24.073Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The displayed approximation of the slope is reasonable: 8 vs 8.012338405942868 - true == true - 
[task 2020-11-03T10:26:24.074Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Not too little: 0.08 >=? 0  - true == true - 
[task 2020-11-03T10:26:24.075Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Not too much: 0.08 <=? 1000  - true == true - 
[task 2020-11-03T10:26:24.076Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The displayed approximation of the total duration is reasonable: 1000 vs 1390 - true == true - 
[task 2020-11-03T10:26:24.076Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The total number of MS is reasonable 1390: [10, 10000000] - true == true - 
[task 2020-11-03T10:26:24.077Z] 10:26:24     INFO - In this mode, we shouldn't display any threads
[task 2020-11-03T10:26:24.077Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | In hidden threads mode, we shouldn't have any thread summary - null == null - 
[task 2020-11-03T10:26:24.078Z] 10:26:24     INFO - Testing subframes
[task 2020-11-03T10:26:24.079Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | "about:blank" == "about:blank" - 
[task 2020-11-03T10:26:24.080Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Found at least one about:blank in-process subframe - true == true - 
[task 2020-11-03T10:26:24.081Z] 10:26:24     INFO - Running sanity tests on non-hung
[task 2020-11-03T10:26:24.082Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | found a table row for non-hung - true == true - 
[task 2020-11-03T10:26:24.088Z] 10:26:24     INFO - Sanity checks: type
[task 2020-11-03T10:26:24.088Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Type web should be one of web,webIsolated - true == true - 
[task 2020-11-03T10:26:24.089Z] 10:26:24     INFO - Sanity checks: pid
[task 2020-11-03T10:26:24.089Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Checking pid 1751 - true == true - 
[task 2020-11-03T10:26:24.090Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | 1751 == 1751 - 
[task 2020-11-03T10:26:24.090Z] 10:26:24     INFO - Sanity checks: memory resident
[task 2020-11-03T10:26:24.091Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Testing memory display 20MB (+1,004KB) vs total 21397504, delta 1028096 - true == true - 
[task 2020-11-03T10:26:24.091Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Can we parse 20MB (+1,004KB) with /([0-9.,]+)(TB|GB|MB|KB|B)( \(([-+]?)([0-9.,]+)(GB|MB|KB|B)\))?/? - ["20MB (+1,004KB)","20","MB"," (+1,004KB)","+","1,004","KB"] != null - 
[task 2020-11-03T10:26:24.092Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Unitless total memory use is greater than 0: 20 - true == true - 
[task 2020-11-03T10:26:24.092Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Unitless total memory use is less than 1024: 20 - true == true - 
[task 2020-11-03T10:26:24.093Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The displayed approximation of the total amount of memory is reasonable: 20971520 vs 21397504 - true == true - 
[task 2020-11-03T10:26:24.093Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The total amount amount of memory is reasonable: 20971520 in [1048576, 1099511627776] - true == true - 
[task 2020-11-03T10:26:24.094Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Unitless delta memory use is in (0, 1024): 1,004 - true == true - 
[task 2020-11-03T10:26:24.094Z] 10:26:24     INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Delta unit is reasonable: KB - true == true - 
[task 2020-11-03T10:26:24.095Z] 10:26:24     INFO - Buffered messages finished
[task 2020-11-03T10:26:24.095Z] 10:26:24     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Delta has the right sign: NaN vs 1028096 - false == true - JS frame :: chrome://mochitests/content/browser/toolkit/components/aboutprocesses/tests/browser/head.js :: testMemory :: line 275```

Update:

There have been 50 failures within the last 7 days:

  • 1 failure on Windows 10 x64 WebRender Shippable opt
  • 2 failures on Windows 10 x64 WebRender debug & opt
  • 47 failures on Linux 18.04 x64 debug & opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=327564040&repo=autoland&lineNumber=17759

[task 2021-01-23T09:23:53.578Z] 09:23:53 INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Delta unit is reasonable: KB - true == true -
[task 2021-01-23T09:23:53.578Z] 09:23:53 INFO - Buffered messages finished
[task 2021-01-23T09:23:53.579Z] 09:23:53 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | Delta has the right sign: NaN vs 1024000 - false == true - JS frame :: chrome://mochitests/content/browser/toolkit/components/aboutprocesses/tests/browser/head.js :: testMemory :: line 275
[task 2021-01-23T09:23:53.579Z] 09:23:53 INFO - Stack trace:
[task 2021-01-23T09:23:53.579Z] 09:23:53 INFO - chrome://mochitests/content/browser/toolkit/components/aboutprocesses/tests/browser/head.js:testMemory:275
[task 2021-01-23T09:23:53.580Z] 09:23:53 INFO - chrome://mochitests/content/browser/toolkit/components/aboutprocesses/tests/browser/head.js:testAboutProcessesWithConfig:543
[task 2021-01-23T09:23:53.580Z] 09:23:53 INFO - Sanity checks: CPU (Total)
[task 2021-01-23T09:23:53.581Z] 09:23:53 INFO - Testing CPU display 8% (1s) vs total 1060000000, slope 0.07721055428693831
[task 2021-01-23T09:23:53.581Z] 09:23:53 INFO - TEST-PASS | toolkit/components/aboutprocesses/tests/browser/browser_aboutprocesses_show_frames_without_threads.js | The displayed approximation of the slope is reasonable: 8 vs 7.72105542869383 - true == true -

Whiteboard: [stockwell needswork]

Update:

There have been 52 failures within the last 7 days:

  • 45 failures on Linux 18.04 x64 debug & opt
  • 6 failures on Windows 10 x64 WebRender debug & opt
  • 1 failure on Windows 10 x64 WebRender Shippable opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=328263576&repo=autoland&lineNumber=12745

:florian , can you please take a look?
Thank you

Flags: needinfo?(florian)

The problem is at https://searchfox.org/mozilla-central/rev/4e290edc65023e16f5fe7d12f84d3b629433c7ab/toolkit/components/aboutprocesses/tests/browser/head.js#260,272,274

The test passes when the value of extractedDeltaTotal is < 1000, but the value is only guaranteed to be < 1024.

I think replacing extractedDeltaTotal at line 274 with deltaTotalNumber should fix the problem. I'll need to verify that this works.

And it looks a lot like bug 1674582, bug 1675336 and bug 1676046 are duplicates. I'll close them once a fix lands here.

Assignee: nobody → florian
Status: NEW → ASSIGNED
Pushed by fqueze@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/8be8227f2709
fix intermittent failure in toolkit/components/aboutprocesses tests, 'Delta has the right sign: NaN', r=Gijs
Status: ASSIGNED → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 87 Branch
Flags: needinfo?(florian)
Whiteboard: [stockwell needswork] → [stockwell needswork][checkin-needed-beta]
Whiteboard: [stockwell needswork][checkin-needed-beta] → [stockwell needswork]
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: