Closed Bug 1716721 Opened 3 years ago Closed 3 years ago

Fission PROCESS-CRASH | Perma [tier 2] dom/system/tests/test_location_services_telemetry.html | application crashed [None] | after application timed out after 370 seconds with no output

Categories

(Core :: DOM: Core & HTML, defect)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1689263
Fission Milestone M8
Tracking Status
firefox-esr78 --- unaffected
firefox89 --- unaffected
firefox90 --- unaffected
firefox91 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

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

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


[task 2021-06-16T05:20:15.582Z] 05:20:15     INFO - TEST-START | dom/system/tests/test_location_services_telemetry.html
[task 2021-06-16T05:20:15.660Z] 05:20:15     INFO - GECKO(8620) | JavaScript error: , line 0: uncaught exception: Object
[task 2021-06-16T05:26:25.667Z] 05:26:25     INFO - Buffered messages finished
[task 2021-06-16T05:26:25.668Z] 05:26:25    ERROR - TEST-UNEXPECTED-TIMEOUT | dom/system/tests/test_location_services_telemetry.html | application timed out after 370 seconds with no output
[task 2021-06-16T05:26:25.668Z] 05:26:25    ERROR - Force-terminating active process(es).
[task 2021-06-16T05:26:25.669Z] 05:26:25     INFO - Determining child pids from psutil...
[task 2021-06-16T05:26:25.670Z] 05:26:25     INFO - [9732]
[task 2021-06-16T05:26:25.671Z] 05:26:25     INFO - ==> process 9732 launched child process 4264 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.0.341022575\1204924947" -parentBuildID 20210616033643 -prefsHandle 2328 -prefMapHandle 2320 -prefsLen 1 -prefMapSize 250179 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 2400 2df266aa100 gpu)
[task 2021-06-16T05:26:25.673Z] 05:26:25     INFO - ==> process 9732 launched child process 1676 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.6.50907045\597883085" -childID 1 -isForBrowser -prefsHandle 3020 -prefMapHandle 3012 -prefsLen 1790 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3044 2df2a55e140 tab)
[task 2021-06-16T05:26:25.674Z] 05:26:25     INFO - ==> process 9732 launched child process 7484 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.13.1484604719\1493250513" -childID 2 -isForBrowser -prefsHandle 3336 -prefMapHandle 3332 -prefsLen 1933 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3348 2df2a55e4c0 tab)
[task 2021-06-16T05:26:25.675Z] 05:26:25     INFO - ==> process 9732 launched child process 8964 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.20.2099096115\203777546" -childID 3 -isForBrowser -prefsHandle 3476 -prefMapHandle 3480 -prefsLen 1973 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3348 2df2a55ebc0 tab)
[task 2021-06-16T05:26:25.676Z] 05:26:25     INFO - ==> process 9732 launched child process 4868 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.27.484192731\757897453" -childID 4 -isForBrowser -prefsHandle 4216 -prefMapHandle 4248 -prefsLen 10124 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3864 2df2f2e2f40 tab)
[task 2021-06-16T05:26:25.677Z] 05:26:25     INFO - ==> process 9732 launched child process 9996 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.34.575065056\2054957699" -childID 5 -isForBrowser -prefsHandle 4360 -prefMapHandle 4364 -prefsLen 10152 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 4424 2df2f2e2d80 tab)
[task 2021-06-16T05:26:25.679Z] 05:26:25     INFO - ==> process 9732 launched child process 4728 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.41.1043405473\315628975" -childID 6 -isForBrowser -prefsHandle 4600 -prefMapHandle 4596 -prefsLen 10152 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 4564 2df2581abc0 tab)
[task 2021-06-16T05:26:25.680Z] 05:26:25     INFO - ==> process 9732 launched child process 9096 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.48.1913317025\1203733114" -childID 7 -isForBrowser -prefsHandle 4804 -prefMapHandle 4800 -prefsLen 10152 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 4812 2df2d77c300 tab)
[task 2021-06-16T05:26:25.682Z] 05:26:25     INFO - ==> process 9732 launched child process 2532 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.55.1111950679\1497251414" -childID 8 -isForBrowser -prefsHandle 3416 -prefMapHandle 3432 -prefsLen 10455 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3356 2df2d77c140 tab)
[task 2021-06-16T05:26:25.683Z] 05:26:25     INFO - ==> process 9732 launched child process 4508 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.62.1605799681\682872511" -childID 9 -isForBrowser -prefsHandle 5412 -prefMapHandle 5408 -prefsLen 10492 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 5364 2df2a55e4c0 tab)
[task 2021-06-16T05:26:25.684Z] 05:26:25     INFO - ==> process 9732 launched child process 6432 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.69.747321600\1595213211" -childID 10 -isForBrowser -prefsHandle 5328 -prefMapHandle 5356 -prefsLen 11033 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 4812 2df31606300 tab)
[task 2021-06-16T05:26:25.685Z] 05:26:25     INFO - Found child pids: {6432, 9732, 8964, 4868, 2532, 4264, 9096, 1676, 9996, 4728, 4508, 7484}
[task 2021-06-16T05:26:25.686Z] 05:26:25     INFO - Failed to get child procs
[task 2021-06-16T05:26:25.686Z] 05:26:25     INFO - Killing process: 6432
[task 2021-06-16T05:26:25.687Z] 05:26:25     INFO - TEST-INFO | started process screenshot
[task 2021-06-16T05:26:25.746Z] 05:26:25     INFO - TEST-INFO | screenshot: exit 0
[task 2021-06-16T05:26:25.747Z] 05:26:25     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\b8df634f-cee7-444d-a80b-7140177c410c.dmp for [6432]
[task 2021-06-16T05:26:25.824Z] 05:26:25     INFO - Killing process: 9732
[task 2021-06-16T05:26:25.826Z] 05:26:25     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:25.827Z] 05:26:25     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\ab98fbfa-c728-480d-a221-ad07766b65cf.dmp for [9732]
[task 2021-06-16T05:26:25.976Z] 05:26:25     INFO - Killing process: 8964
[task 2021-06-16T05:26:25.977Z] 05:26:25     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:25.978Z] 05:26:25     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\55fcf5ac-9b6c-493d-b03c-719721fc80a2.dmp for [8964]
[task 2021-06-16T05:26:25.980Z] 05:26:25  WARNING - mozcrash unable to dump minidump file for pid 8964: -2147024597
[task 2021-06-16T05:26:25.980Z] 05:26:25  WARNING - mozcrash kill_pid(): unable to terminate pid 8964: 5
[task 2021-06-16T05:26:25.981Z] 05:26:25     INFO - Killing process: 4868
[task 2021-06-16T05:26:25.981Z] 05:26:25     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:25.982Z] 05:26:25     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\a153422d-76b0-4245-b773-de84dded42cc.dmp for [4868]
[task 2021-06-16T05:26:25.983Z] 05:26:25  WARNING - mozcrash unable to dump minidump file for pid 4868: -2147024597
[task 2021-06-16T05:26:25.984Z] 05:26:25  WARNING - mozcrash kill_pid(): unable to get handle for pid 4868: 87
[task 2021-06-16T05:26:25.985Z] 05:26:25     INFO - Killing process: 2532
[task 2021-06-16T05:26:25.985Z] 05:26:25     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:25.987Z] 05:26:25     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\b81ab586-25ab-42e2-ab88-3d6a8b98ddc4.dmp for [2532]
[task 2021-06-16T05:26:25.987Z] 05:26:25  WARNING - mozcrash unable to get handle for pid 2532: 87
[task 2021-06-16T05:26:25.987Z] 05:26:25  WARNING - mozcrash kill_pid(): unable to get handle for pid 2532: 87
[task 2021-06-16T05:26:25.987Z] 05:26:25     INFO - Killing process: 4264
[task 2021-06-16T05:26:25.988Z] 05:26:25     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:25.989Z] 05:26:25     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\0251b0fe-99eb-40cb-920f-0aa03e87ba02.dmp for [4264]
[task 2021-06-16T05:26:25.989Z] 05:26:25  WARNING - mozcrash unable to dump minidump file for pid 4264: -2147024597
[task 2021-06-16T05:26:25.990Z] 05:26:25  WARNING - mozcrash kill_pid(): unable to terminate pid 4264: 5
[task 2021-06-16T05:26:25.991Z] 05:26:25     INFO - Killing process: 9096
[task 2021-06-16T05:26:25.991Z] 05:26:25     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:25.992Z] 05:26:25     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\0c69fb14-e3f2-4968-9f36-a730c1cbc374.dmp for [9096]
[task 2021-06-16T05:26:25.993Z] 05:26:25  WARNING - mozcrash unable to get handle for pid 9096: 87
[task 2021-06-16T05:26:25.993Z] 05:26:25  WARNING - mozcrash kill_pid(): unable to get handle for pid 9096: 87
[task 2021-06-16T05:26:25.993Z] 05:26:25     INFO - Killing process: 1676
[task 2021-06-16T05:26:25.994Z] 05:26:25     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:25.995Z] 05:26:25     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\79252067-0ba9-4041-8db8-0c9f1a3e408c.dmp for [1676]
[task 2021-06-16T05:26:25.995Z] 05:26:25  WARNING - mozcrash unable to get handle for pid 1676: 87
[task 2021-06-16T05:26:25.996Z] 05:26:25  WARNING - mozcrash kill_pid(): unable to get handle for pid 1676: 87
[task 2021-06-16T05:26:25.996Z] 05:26:25     INFO - Killing process: 9996
[task 2021-06-16T05:26:25.997Z] 05:26:25     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:25.997Z] 05:26:25     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\7ffe9130-4322-418e-a05f-d26e21f90c8c.dmp for [9996]
[task 2021-06-16T05:26:25.998Z] 05:26:25  WARNING - mozcrash unable to get handle for pid 9996: 87
[task 2021-06-16T05:26:25.998Z] 05:26:25  WARNING - mozcrash kill_pid(): unable to get handle for pid 9996: 87
[task 2021-06-16T05:26:25.999Z] 05:26:25     INFO - Killing process: 4728
[task 2021-06-16T05:26:26.000Z] 05:26:25     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:26.000Z] 05:26:26     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\c4e443ae-52c0-4bbb-80ea-e802c3d7a63f.dmp for [4728]
[task 2021-06-16T05:26:26.002Z] 05:26:26  WARNING - mozcrash unable to get handle for pid 4728: 87
[task 2021-06-16T05:26:26.002Z] 05:26:26  WARNING - mozcrash kill_pid(): unable to get handle for pid 4728: 87
[task 2021-06-16T05:26:26.002Z] 05:26:26     INFO - Killing process: 4508
[task 2021-06-16T05:26:26.003Z] 05:26:26     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:26.004Z] 05:26:26     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\cb95a49f-bc64-4820-b15f-4f3b2695d4d7.dmp for [4508]
[task 2021-06-16T05:26:26.004Z] 05:26:26  WARNING - mozcrash unable to get handle for pid 4508: 87
[task 2021-06-16T05:26:26.005Z] 05:26:26  WARNING - mozcrash kill_pid(): unable to get handle for pid 4508: 87
[task 2021-06-16T05:26:26.005Z] 05:26:26     INFO - Killing process: 7484
[task 2021-06-16T05:26:26.005Z] 05:26:26     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:26.006Z] 05:26:26     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\d11d5e69-b356-41f4-a9db-3772242ccbc7.dmp for [7484]
[task 2021-06-16T05:26:26.007Z] 05:26:26  WARNING - mozcrash unable to get handle for pid 7484: 87
[task 2021-06-16T05:26:26.007Z] 05:26:26  WARNING - mozcrash kill_pid(): unable to get handle for pid 7484: 87
[task 2021-06-16T05:26:26.007Z] 05:26:26     INFO - Killing process: 8620
[task 2021-06-16T05:26:26.008Z] 05:26:26     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-16T05:26:26.008Z] 05:26:26     INFO - mozcrash Writing a dump to C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\c9157e28-8ee4-4841-8026-0d7f60028e8b.dmp for [8620]
[task 2021-06-16T05:26:26.009Z] 05:26:26  WARNING - mozcrash unable to dump minidump file for pid 8620: -2147024597
[task 2021-06-16T05:26:26.010Z] 05:26:26  WARNING - mozcrash kill_pid(): unable to terminate pid 8620: 5
[task 2021-06-16T05:26:26.010Z] 05:26:26     INFO - psutil found pid 8620 dead
[task 2021-06-16T05:26:26.010Z] 05:26:26     INFO - TEST-INFO | Main app process: exit 1
[task 2021-06-16T05:26:26.011Z] 05:26:26     INFO - Buffered messages finished
[task 2021-06-16T05:26:26.011Z] 05:26:26    ERROR - TEST-UNEXPECTED-FAIL | dom/system/tests/test_location_services_telemetry.html | application terminated with exit code 1
[task 2021-06-16T05:26:26.012Z] 05:26:26     INFO - runtests.py | Application ran for: 0:06:15.665498
[task 2021-06-16T05:26:26.012Z] 05:26:26     INFO - zombiecheck | Reading PID log: C:\Users\task_1623818489\AppData\Local\Temp\tmpnx3i3u_ppidlog
[task 2021-06-16T05:26:26.013Z] 05:26:26     INFO - ==> process 9732 launched child process 4264 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.0.341022575\1204924947" -parentBuildID 20210616033643 -prefsHandle 2328 -prefMapHandle 2320 -prefsLen 1 -prefMapSize 250179 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 2400 2df266aa100 gpu)
[task 2021-06-16T05:26:26.014Z] 05:26:26     INFO - ==> process 9732 launched child process 1676 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.6.50907045\597883085" -childID 1 -isForBrowser -prefsHandle 3020 -prefMapHandle 3012 -prefsLen 1790 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3044 2df2a55e140 tab)
[task 2021-06-16T05:26:26.015Z] 05:26:26     INFO - ==> process 9732 launched child process 7484 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.13.1484604719\1493250513" -childID 2 -isForBrowser -prefsHandle 3336 -prefMapHandle 3332 -prefsLen 1933 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3348 2df2a55e4c0 tab)
[task 2021-06-16T05:26:26.016Z] 05:26:26     INFO - ==> process 9732 launched child process 8964 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.20.2099096115\203777546" -childID 3 -isForBrowser -prefsHandle 3476 -prefMapHandle 3480 -prefsLen 1973 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3348 2df2a55ebc0 tab)
[task 2021-06-16T05:26:26.017Z] 05:26:26     INFO - ==> process 9732 launched child process 4868 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.27.484192731\757897453" -childID 4 -isForBrowser -prefsHandle 4216 -prefMapHandle 4248 -prefsLen 10124 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3864 2df2f2e2f40 tab)
[task 2021-06-16T05:26:26.018Z] 05:26:26     INFO - ==> process 9732 launched child process 9996 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.34.575065056\2054957699" -childID 5 -isForBrowser -prefsHandle 4360 -prefMapHandle 4364 -prefsLen 10152 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 4424 2df2f2e2d80 tab)
[task 2021-06-16T05:26:26.019Z] 05:26:26     INFO - ==> process 9732 launched child process 4728 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.41.1043405473\315628975" -childID 6 -isForBrowser -prefsHandle 4600 -prefMapHandle 4596 -prefsLen 10152 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 4564 2df2581abc0 tab)
[task 2021-06-16T05:26:26.020Z] 05:26:26     INFO - ==> process 9732 launched child process 9096 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.48.1913317025\1203733114" -childID 7 -isForBrowser -prefsHandle 4804 -prefMapHandle 4800 -prefsLen 10152 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 4812 2df2d77c300 tab)
[task 2021-06-16T05:26:26.021Z] 05:26:26     INFO - ==> process 9732 launched child process 2532 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.55.1111950679\1497251414" -childID 8 -isForBrowser -prefsHandle 3416 -prefMapHandle 3432 -prefsLen 10455 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 3356 2df2d77c140 tab)
[task 2021-06-16T05:26:26.023Z] 05:26:26     INFO - ==> process 9732 launched child process 4508 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.62.1605799681\682872511" -childID 9 -isForBrowser -prefsHandle 5412 -prefMapHandle 5408 -prefsLen 10492 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 5364 2df2a55e4c0 tab)
[task 2021-06-16T05:26:26.024Z] 05:26:26     INFO - ==> process 9732 launched child process 6432 ("C:\Users\task_1623818489\build\application\firefox\firefox.exe" -contentproc --channel="9732.69.747321600\1595213211" -childID 10 -isForBrowser -prefsHandle 5328 -prefMapHandle 5356 -prefsLen 11033 -prefMapSize 250179 -jsInit 1260 286184 -parentBuildID 20210616033643 -appdir "C:\Users\task_1623818489\build\application\firefox\browser" - 9732 "\\.\pipe\gecko-crash-server-pipe.9732" 4812 2df31606300 tab)
[task 2021-06-16T05:26:26.025Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 6432
[task 2021-06-16T05:26:26.025Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 8964
[task 2021-06-16T05:26:26.026Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 4868
[task 2021-06-16T05:26:26.026Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 2532
[task 2021-06-16T05:26:26.027Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 4264
[task 2021-06-16T05:26:26.027Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 9096
[task 2021-06-16T05:26:26.028Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 1676
[task 2021-06-16T05:26:26.028Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 9996
[task 2021-06-16T05:26:26.028Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 4728
[task 2021-06-16T05:26:26.029Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 4508
[task 2021-06-16T05:26:26.029Z] 05:26:26     INFO - zombiecheck | Checking for orphan process with PID: 7484
[task 2021-06-16T05:26:26.030Z] 05:26:26     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bcfJ-AjHTzyq8TIG9judSw/artifacts/public/build/target.crashreporter-symbols.zip
[task 2021-06-16T05:26:29.631Z] 05:26:29     INFO - mozcrash Copy/paste: C:/Users/task_1623818489/fetches\minidump_stackwalk\minidump_stackwalk.exe C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\c9157e28-8ee4-4841-8026-0d7f60028e8b.dmp C:\Users\task_1623818489\AppData\Local\Temp\tmp7notvt5z
[task 2021-06-16T05:26:29.666Z] 05:26:29     INFO - mozcrash Saved minidump as C:\Users\task_1623818489\build\blobber_upload_dir\c9157e28-8ee4-4841-8026-0d7f60028e8b.dmp
[task 2021-06-16T05:26:29.668Z] 05:26:29     INFO - PROCESS-CRASH | dom/system/tests/test_location_services_telemetry.html | application crashed [None]
[task 2021-06-16T05:26:29.668Z] 05:26:29     INFO - Crash dump filename: C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\c9157e28-8ee4-4841-8026-0d7f60028e8b.dmp
[task 2021-06-16T05:26:29.668Z] 05:26:29     INFO - stderr from minidump_stackwalk:
[task 2021-06-16T05:26:29.668Z] 05:26:29     INFO - 2021-06-16 05:26:29: minidump_stackwalk.cc:135: ERROR: Minidump C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\c9157e28-8ee4-4841-8026-0d7f60028e8b.dmp could not be read
[task 2021-06-16T05:26:29.669Z] 05:26:29     INFO - mozcrash Copy/paste: C:/Users/task_1623818489/fetches\minidump_stackwalk\minidump_stackwalk.exe C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\b8df634f-cee7-444d-a80b-7140177c410c.dmp C:\Users\task_1623818489\AppData\Local\Temp\tmp7notvt5z
[task 2021-06-16T05:26:35.603Z] 05:26:35     INFO - mozcrash Saved minidump as C:\Users\task_1623818489\build\blobber_upload_dir\b8df634f-cee7-444d-a80b-7140177c410c.dmp
[task 2021-06-16T05:26:35.665Z] 05:26:35     INFO - PROCESS-CRASH | dom/system/tests/test_location_services_telemetry.html | application crashed [None]
[task 2021-06-16T05:26:35.665Z] 05:26:35     INFO - Crash dump filename: C:\Users\task_1623818489\AppData\Local\Temp\tmpm6wxwuqo.mozrunner\minidumps\b8df634f-cee7-444d-a80b-7140177c410c.dmp
[task 2021-06-16T05:26:35.665Z] 05:26:35     INFO - Operating system: Windows NT
[task 2021-06-16T05:26:35.666Z] 05:26:35     INFO -                   10.0.17134 
[task 2021-06-16T05:26:35.666Z] 05:26:35     INFO - CPU: amd64
[task 2021-06-16T05:26:35.666Z] 05:26:35     INFO -      family 6 model 94 stepping 3
[task 2021-06-16T05:26:35.666Z] 05:26:35     INFO -      8 CPUs
[task 2021-06-16T05:26:35.666Z] 05:26:35     INFO - 
[task 2021-06-16T05:26:35.666Z] 05:26:35     INFO - GPU: UNKNOWN
[task 2021-06-16T05:26:35.666Z] 05:26:35     INFO - 
[task 2021-06-16T05:26:35.666Z] 05:26:35     INFO - No crash
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO - Process uptime: 369 seconds
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO - 
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO - Thread 0
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO -  0  ntdll.dll!NtWaitForAlertByThreadId + 0x14
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO -     rax = 0x00000000000001c8   rdx = 0x0000000000000000
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO -     rcx = 0x000001eb4d66f060   rbx = 0x0000000000000000
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO -     rsi = 0x0000000000000000   rdi = 0x00000068493ff401
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493feb38
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO -      r8 = 0x0000000000000000    r9 = 0x0000000000000000
[task 2021-06-16T05:26:35.667Z] 05:26:35     INFO -     r10 = 0x000001eb4d66f0f0   r11 = 0x0000006848a7b800
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -     r12 = 0x00007ffd154317fc   r13 = 0x000001eb4d66f0f0
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -     r14 = 0x000001eb4d66f0f0   r15 = 0x0000000000000000
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -     rip = 0x00007ffd51cdd7e4
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -     Found by: given as instruction pointer in context
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -  1  ntdll.dll!RtlSleepConditionVariableSRW + 0x109
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -     rbx = 0x0000000000000000   rbp = 0x000001eb4d66f060
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -     rsp = 0x00000068493feb40   r12 = 0x00007ffd154317fc
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -     r13 = 0x000001eb4d66f0f0   r14 = 0x000001eb4d66f0f0
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -     r15 = 0x0000000000000000   rip = 0x00007ffd51c5ef19
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.668Z] 05:26:35     INFO -  2  KERNELBASE.dll!LdrpLoadDependentModule + 0xe32
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     rbx = 0x0000000000000000   rbp = 0x000001eb4d66f060
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     rsp = 0x00000068493febb0   r12 = 0x00007ffd154317fc
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     r13 = 0x000001eb4d66f0f0   r14 = 0x000001eb4d66f0f0
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     r15 = 0x0000000000000000   rip = 0x00007ffd4ed52c72
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -  3  0x68493ff470
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     rbx = 0x0000000000000000   rbp = 0x000001eb4d66f060
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     rsp = 0x00000068493ff160   r12 = 0x00007ffd154317fc
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     r13 = 0x000001eb4d66f0f0   r14 = 0x000001eb4d66f0f0
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     r15 = 0x0000000000000000   rip = 0x00000068493ff470
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.669Z] 05:26:35     INFO -  4  xul.dll!nsBaseAppShell::Run() [nsBaseAppShell.cpp:fe4caa58b249cda112cb2d595cdb00bd79c11445 : 137 + 0xd]
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493ff180
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -     rip = 0x00007ffd0f8f4328
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -     Found by: stack scanning
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -  5  xul.dll!nsAppShell::Run() [nsAppShell.cpp:fe4caa58b249cda112cb2d595cdb00bd79c11445 : 603 + 0x8]
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493ff1c0
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -     rip = 0x00007ffd0f8f315f
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -  6  xul.dll!XRE_RunAppShell() [nsEmbedFunctions.cpp:fe4caa58b249cda112cb2d595cdb00bd79c11445 : 910 + 0xd]
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493ff330
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -     rip = 0x00007ffd109dd32b
[task 2021-06-16T05:26:35.670Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.671Z] 05:26:35     INFO -  7  xul.dll!MessageLoop::RunHandler() [message_loop.cc:fe4caa58b249cda112cb2d595cdb00bd79c11445 : 328 + 0x16]
[task 2021-06-16T05:26:35.671Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493ff370
[task 2021-06-16T05:26:35.671Z] 05:26:35     INFO -     rip = 0x00007ffd1017170f
[task 2021-06-16T05:26:35.671Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.671Z] 05:26:35     INFO -  8  xul.dll!MessageLoop::Run() [message_loop.cc:fe4caa58b249cda112cb2d595cdb00bd79c11445 : 310 + 0x5]
[task 2021-06-16T05:26:35.671Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493ff3c0
[task 2021-06-16T05:26:35.671Z] 05:26:35     INFO -     rip = 0x00007ffd0f768f6e
[task 2021-06-16T05:26:35.671Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.671Z] 05:26:35     INFO -  9  xul.dll!XRE_InitChildProcess(int, char**, XREChildData const*) [nsEmbedFunctions.cpp:fe4caa58b249cda112cb2d595cdb00bd79c11445 : 742 + 0xa]
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493ff420
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     rip = 0x00007ffd109dce11
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO - 10  firefox.exe!wmain(int, wchar_t**) [nsWindowsWMain.cpp:fe4caa58b249cda112cb2d595cdb00bd79c11445 : 131 + 0x169]
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493ff670
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     rip = 0x00007ff71b759eff
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO - 11  firefox.exe!__scrt_common_main_seh() [exe_common.inl : 288 + 0x22]
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493ff890
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     rip = 0x00007ff71b76d668
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     Found by: call frame info
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO - 12  kernel32.dll!RtlpLowFragHeapAllocFromContext + 0x204
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     rbp = 0x000001eb4d66f060   rsp = 0x00000068493ff8d0
[task 2021-06-16T05:26:35.672Z] 05:26:35     INFO -     rip = 0x00007ffd51a93034
[task 2021-06-16T05:26:35.673Z] 05:26:35     INFO -     Found by: call frame info
Summary: PROCESS-CRASH | Perma [tier2] dom/system/tests/test_location_services_telemetry.html | application crashed [None] | after application timed out after 370 seconds with no output → PROCESS-CRASH | Perma [tier 2] dom/system/tests/test_location_services_telemetry.html | application crashed [None] | after application timed out after 370 seconds with no output

This started failing with this merge to central: https://hg.mozilla.org/mozilla-central/pushloghtml?changeset=fe4caa58b249cda112cb2d595cdb00bd79c11445 and might be related to the changes in Bug 1713201 https://hg.mozilla.org/mozilla-central/rev/ed02a07131713e7e48bc063b44f582b68f410482
Until then these kind of tests were not run.
Mitchell, could you have a look over these failures? Thank you.

Flags: needinfo?(mhentges)

I'm seeing a GECKO(8620) | JavaScript error: , line 0: uncaught exception: Object, which smells like a JS-y issue.

Until then these kind of tests were not run.

Hmm, the tests that my patch affected were for python-tests, not for mochitests.
Perhaps these tests are now run because of this revision?
I'm going to pass this NI to :ahal, but feel free to send it back to me if I can help further :)

Flags: needinfo?(mhentges) → needinfo?(ahal)

Yeah, this almost certainly started happening with bug 1700781. Interestingly this only fails on central, so must be failing exclusively on shippable builds.. The fission team will want to investigate.

Edit: fixed bug number

Flags: needinfo?(ahal) → needinfo?(cpeterson)
Regressed by: 1713201

In the meantime if this is causing too much work starring, I'd recommend skipping the test as per normal process (seeing as it is a newly added configuration).

Sorry, meant to link to bug 1700781

Regressed by: 1700781
No longer regressed by: 1713201
Has Regression Range: --- → yes

(In reply to Andrew Halberstadt [:ahal] from comment #5)

Yeah, this almost certainly started happening with bug 1713201. Interestingly this only fails on central, so must be failing exclusively on shippable builds.. The fission team will want to investigate.

John, the test_location_services_telemetry.html mochitest seems to be failing intermittently when Fission is enabled. Can you please investigate why the test is timing out? Or suggest another engineer who can investigate? Does this bug belong in a different Bugzilla component than "DOM: Core & HTML"?

If these intermittent failures are exclusively "shippable" builds on mozilla-central, maybe there is something about different about testing with the GLS API key compared to the MLS API key?

Fission Milestone: --- → ?
Flags: needinfo?(cpeterson) → needinfo?(jwhitlock)
Summary: PROCESS-CRASH | Perma [tier 2] dom/system/tests/test_location_services_telemetry.html | application crashed [None] | after application timed out after 370 seconds with no output → Fission PROCESS-CRASH | Perma [tier 2] dom/system/tests/test_location_services_telemetry.html | application crashed [None] | after application timed out after 370 seconds with no output

Quick clarification, these appear to be failing permanently, not intermittently. They weren't caught earlier because we don't run shippable on autoland / try too frequently.

Set release status flags based on info from the regressing bug 1700781

I'm on PTO until July 6, when I can look into this deeper. Until then, you get a brain dump and some more needinfos.

Looking at the "Orange factor" link, it appears this issue started around 2012-06-15. Is that when this test began running? Was it ever successful on *-shippable-qr?

I believe the Google Location Service / Maps API fallback is used regularly on Linux, while on Windows and MacOS the OS-level geolocation APIs are used. However, it appears the test is failing on all three systems, so it seems this is not an OS-specific issue.

The test calls navigator.geolocation.getCurrentPosition. Normally that opens a dialog asking if you want to share your position with the page. The pref geo.prompt.testing.allow enables calling that API without user confirmation. Is there something in *-shippable-qr that would cause the user confirmation dialog to still appear? There are a few other tests that set this pref, if they run in *-shippable-qr than it is probably OK.

https://searchfox.org/mozilla-central/source/dom/system/tests/test_location_services_telemetry.html

The test page mentions bug 1637402, where this GLS / MLS comparison experiment was added. dharvey recently asked about removing this code, so it is possible some required code or config has been removed already. I think this test is on the path to removal.

On the Mozilla Location Service side, cvalaas and I updated production on 2021-06-09 (library code updates) and 2021-06-10 (background task feature). On 2021-06-16, we rebooted the database for a configuration change (less than 5 minutes).

It should be possible to use telemetry to investigate if there is a general MLS slowdown. SEARCH_SERVICE_COUNTRY_FETCH_TIME_MS gives the time to call the MLS region service, and SEARCH_SERVICE_COUNTRY_TIMEOUT is a boolean for the same measurement. The 95% percentile on fetch time is 5 seconds in the measurement dashboard, and a query would be needed to check production. 5 seconds (region service) is a lot more than the 370 second timeout (location service), so I'm doubtful that MLS slowness is the root cause.

Another possibility is daily MLS API key limits. This should not be an issue with the production key (looks like a UUID), but the test key (literally "test") has low daily limits for the geolocate API. A misconfiguration of geo.provider.network.url or GOOGLE_LOCATION_SERVICE_API_KEY could cause an issue.

Flags: needinfo?(dharvey)
Flags: needinfo?(cvalaas)

Looking at the "Orange factor" link, it appears this issue started around 2012-06-15. Is that when this test began running? Was it ever successful on *-shippable-qr?

Correct, this is when it started running. Afaik, it's never passed on shippable-qr.

Assuming the telemetry expiring is whats causing this test to fail, its to be removed in https://phabricator.services.mozilla.com/D118061

As a note @jwhitlock, we would not hit MLS as part of standard browser tests, they arent allowed to hit the network specifically to avoid changes to services having an impact on the tests, we mock the MLS service here

Flags: needinfo?(jwhitlock)
Flags: needinfo?(dharvey)
Flags: needinfo?(cvalaas)

(In reply to Dale Harvey (:daleharvey) from comment #13)

Assuming the telemetry expiring is whats causing this test to fail, its to be removed in https://phabricator.services.mozilla.com/D118061

If that was the case, would a warning message be logged?

Also, this test is not failing for mochitest-plain in e10s or Fission modes, only in Fission "xorigin" test mode, where the mochitest runner loads the test case html in a cross-origin iframe. So presumably the failure is related to the test not working correctly in a cross-origin iframe.

Since this bug is a perma failure and related to xorigin test mode, it's pretty important. I will track this bug for Fission's upcoming M8 milestone (Release channel experiment).

Fission Milestone: ? → M8

That does seem strange, the telemetry is the only thing I know that has changed around this code recently and although permissions can be fiddly when called within a cross origin iframe, I would think this isnt the only test to have hit that issue, however the test is now removed (the patch to remove was already planned before this bug came up, I just forgot to land), so close as a dupe?

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE

Removal is right for this test, but there may be other geolocation tests that are broken in Fission "xorigin" test mode.

The iframe that loads the test may need an allow="geolocation" attribute. The MDN Geolocation API page has a working example. You'd also need a secure context (https) to load the iframe, and a compatible Feature Policy header, if used.

If you get this wrong, I'd expect a PERMISSION_DENIED error, which I think the test captures, so this may be a dead end as well.

You need to log in before you can comment on or make changes to this bug.