Closed
Bug 1726328
Opened 4 years ago
Closed 2 years ago
Intermittent TEST-UNEXPECTED-TIMEOUT | browser/base/content/test/tabPrompts/browser_contentOrigins.js | application timed out after 370 seconds with no output
Categories
(Firefox :: Tabbed Browser, defect, P5)
Firefox
Tabbed Browser
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=348783332&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/GD5Bl5BxT9C_AShnRoqHhQ/runs/0/artifacts/public/logs/live_backing.log
[task 2021-08-18T09:03:46.686Z] 09:03:46 INFO - TEST-PASS | browser/base/content/test/tabPrompts/browser_contentOrigins.js | The domain should overflow. -
[task 2021-08-18T09:03:46.687Z] 09:03:46 INFO - Console message: [JavaScript Error: "Prompter: internal dialogs not available in this context. Falling back to window prompt." {file: "resource://gre/modules/Prompter.jsm" line: 1084}]
[task 2021-08-18T09:03:46.687Z] 09:03:46 INFO - set modalType@resource://gre/modules/Prompter.jsm:1084:10
[task 2021-08-18T09:03:46.687Z] 09:03:46 INFO - ModalPrompter@resource://gre/modules/Prompter.jsm:1040:5
[task 2021-08-18T09:03:46.687Z] 09:03:46 INFO - pickPrompter@resource://gre/modules/Prompter.jsm:44:12
[task 2021-08-18T09:03:46.688Z] 09:03:46 INFO - asyncPromptAuth@resource://gre/modules/Prompter.jsm:720:18
[task 2021-08-18T09:03:46.688Z] 09:03:46 INFO - promptAuthInternal@resource://gre/modules/LoginManagerAuthPrompter.jsm:732:34
[task 2021-08-18T09:03:46.688Z] 09:03:46 INFO - _doAsyncPrompt@resource://gre/modules/LoginManagerAuthPrompter.jsm:212:34
[task 2021-08-18T09:03:46.688Z] 09:03:46 INFO -
[task 2021-08-18T09:03:46.689Z] 09:03:46 INFO - Buffered messages logged at 08:47:52
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - Console message: [JavaScript Error: "Unknown Collection "main/partitioning-exempt-urls"" {file: "resource://services-settings/RemoteSettingsClient.jsm" line: 160}]
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - UnknownCollectionError@resource://services-settings/RemoteSettingsClient.jsm:160:5
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - sync@resource://services-settings/RemoteSettingsClient.jsm:503:13
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - openWindowPrompt@resource://gre/modules/Prompter.jsm:1290:17
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - openPrompt@resource://gre/modules/Prompter.jsm:1126:12
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - openPromptAsync@resource://gre/modules/Prompter.jsm:1324:29
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - nsIPrompt_promptUsernameAndPassword@resource://gre/modules/Prompter.jsm:1598:19
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - promptAuth@resource://gre/modules/Prompter.jsm:1754:21
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - asyncPromptAuth@resource://gre/modules/Prompter.jsm:721:14
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - promptAuthInternal@resource://gre/modules/LoginManagerAuthPrompter.jsm:732:34
[task 2021-08-18T09:03:46.691Z] 09:03:46 INFO - _doAsyncPrompt@resource://gre/modules/LoginManagerAuthPrompter.jsm:212:34
[task 2021-08-18T09:03:46.692Z] 09:03:46 INFO -
[task 2021-08-18T09:03:46.693Z] 09:03:46 INFO - Buffered messages logged at 08:54:03
[task 2021-08-18T09:03:46.695Z] 09:03:46 INFO - Console message: [JavaScript Warning: "telemetry.state_file_read_errors - Unknown scalar."]
[task 2021-08-18T09:03:46.696Z] 09:03:46 INFO - Console message: [JavaScript Warning: "telemetry.generated_new_client_id - Unknown scalar."]
[task 2021-08-18T09:03:46.697Z] 09:03:46 INFO - Buffered messages logged at 08:56:03
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - Console message: [JavaScript Error: "1629276963606 addons.xpi ERROR System addon update list error Error: got node name: html, expected: updates" {file: "resource://gre/modules/Log.jsm" line: 723}]
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - append@resource://gre/modules/Log.jsm:723:12
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - log@resource://gre/modules/Log.jsm:379:16
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - error@resource://gre/modules/Log.jsm:387:10
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - updateSystemAddons/res<@resource://gre/modules/addons/XPIInstall.jsm:4049:25
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - openWindowPrompt@resource://gre/modules/Prompter.jsm:1290:17
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - openPrompt@resource://gre/modules/Prompter.jsm:1126:12
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - openPromptAsync@resource://gre/modules/Prompter.jsm:1324:29
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - nsIPrompt_promptUsernameAndPassword@resource://gre/modules/Prompter.jsm:1598:19
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - promptAuth@resource://gre/modules/Prompter.jsm:1754:21
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - asyncPromptAuth@resource://gre/modules/Prompter.jsm:721:14
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - promptAuthInternal@resource://gre/modules/LoginManagerAuthPrompter.jsm:732:34
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO - _doAsyncPrompt@resource://gre/modules/LoginManagerAuthPrompter.jsm:212:34
[task 2021-08-18T09:03:46.699Z] 09:03:46 INFO -
[task 2021-08-18T09:03:46.700Z] 09:03:46 INFO - Buffered messages finished
[task 2021-08-18T09:03:46.701Z] 09:03:46 ERROR - TEST-UNEXPECTED-TIMEOUT | browser/base/content/test/tabPrompts/browser_contentOrigins.js | application timed out after 370 seconds with no output
[task 2021-08-18T09:03:46.701Z] 09:03:46 ERROR - Force-terminating active process(es).
[task 2021-08-18T09:03:46.702Z] 09:03:46 INFO - Determining child pids from psutil...
[task 2021-08-18T09:03:46.703Z] 09:03:46 INFO - [2591, 2610, 2653, 2696, 2719, 2756, 2781]
[task 2021-08-18T09:03:46.704Z] 09:03:46 INFO - ==> process 2516 launched child process 2533
[task 2021-08-18T09:03:46.704Z] 09:03:46 INFO - ==> process 2516 launched child process 2591
[task 2021-08-18T09:03:46.705Z] 09:03:46 INFO - ==> process 2516 launched child process 2610
[task 2021-08-18T09:03:46.706Z] 09:03:46 INFO - ==> process 2516 launched child process 2653
[task 2021-08-18T09:03:46.706Z] 09:03:46 INFO - ==> process 2516 launched child process 2696
[task 2021-08-18T09:03:46.707Z] 09:03:46 INFO - ==> process 2516 launched child process 2719
[task 2021-08-18T09:03:46.708Z] 09:03:46 INFO - ==> process 2516 launched child process 2756
[task 2021-08-18T09:03:46.708Z] 09:03:46 INFO - ==> process 2516 launched child process 2781
[task 2021-08-18T09:03:46.709Z] 09:03:46 INFO - Found child pids: {2756, 2533, 2696, 2781, 2591, 2610, 2653, 2719}
[task 2021-08-18T09:03:46.710Z] 09:03:46 INFO - Failed to get child procs
[task 2021-08-18T09:03:46.711Z] 09:03:46 INFO - Killing process: 2756
[task 2021-08-18T09:03:46.712Z] 09:03:46 INFO - TEST-INFO | started process screentopng
[task 2021-08-18T09:03:46.830Z] 09:03:46 INFO - TEST-INFO | screentopng: exit 0
[task 2021-08-18T09:03:46.832Z] 09:03:46 INFO - Killing process: 2533
[task 2021-08-18T09:03:46.833Z] 09:03:46 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-18T09:03:46.838Z] 09:03:46 INFO - Can't trigger Breakpad, process no longer exists
[task 2021-08-18T09:03:46.839Z] 09:03:46 INFO - Killing process: 2696
[task 2021-08-18T09:03:46.840Z] 09:03:46 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-18T09:03:46.841Z] 09:03:46 INFO - Killing process: 2781
[task 2021-08-18T09:03:46.842Z] 09:03:46 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-18T09:03:46.842Z] 09:03:46 INFO - Killing process: 2591
[task 2021-08-18T09:03:46.843Z] 09:03:46 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-18T09:03:46.844Z] 09:03:46 INFO - Killing process: 2610
[task 2021-08-18T09:03:46.844Z] 09:03:46 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-18T09:03:46.845Z] 09:03:46 INFO - Killing process: 2653
[task 2021-08-18T09:03:46.846Z] 09:03:46 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-18T09:03:46.846Z] 09:03:46 INFO - Killing process: 2719
[task 2021-08-18T09:03:46.847Z] 09:03:46 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-18T09:03:46.848Z] 09:03:46 INFO - Killing process: 2516
[task 2021-08-18T09:03:46.848Z] 09:03:46 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-08-18T09:03:47.002Z] 09:03:47 INFO - psutil found pid 2516 dead
[task 2021-08-18T09:03:47.012Z] 09:03:47 INFO - TEST-INFO | Main app process: exit 0
[task 2021-08-18T09:03:47.013Z] 09:03:47 ERROR - TEST-UNEXPECTED-FAIL | ShutdownLeaks | process() called before end of test suite
[task 2021-08-18T09:03:47.014Z] 09:03:47 INFO - TEST-INFO | Confirming we saw 77 DOCSHELL created and 63 destroyed log strings.
[task 2021-08-18T09:03:47.015Z] 09:03:47 INFO - TEST-INFO | Confirming we saw 183 DOMWINDOW created and 156 destroyed log strings.
[task 2021-08-18T09:03:47.015Z] 09:03:47 INFO - runtests.py | Application ran for: 0:16:14.617929
[task 2021-08-18T09:03:47.016Z] 09:03:47 INFO - zombiecheck | Reading PID log: /tmp/tmpa9drbtunpidlog
[task 2021-08-18T09:03:47.017Z] 09:03:47 INFO - ==> process 2516 launched child process 2533
[task 2021-08-18T09:03:47.017Z] 09:03:47 INFO - ==> process 2516 launched child process 2591
[task 2021-08-18T09:03:47.017Z] 09:03:47 INFO - ==> process 2516 launched child process 2610
[task 2021-08-18T09:03:47.018Z] 09:03:47 INFO - ==> process 2516 launched child process 2653
[task 2021-08-18T09:03:47.018Z] 09:03:47 INFO - ==> process 2516 launched child process 2696
[task 2021-08-18T09:03:47.018Z] 09:03:47 INFO - ==> process 2516 launched child process 2719
[task 2021-08-18T09:03:47.019Z] 09:03:47 INFO - ==> process 2516 launched child process 2756
[task 2021-08-18T09:03:47.019Z] 09:03:47 INFO - ==> process 2516 launched child process 2781
[task 2021-08-18T09:03:47.019Z] 09:03:47 INFO - zombiecheck | Checking for orphan process with PID: 2756
[task 2021-08-18T09:03:47.020Z] 09:03:47 INFO - zombiecheck | Checking for orphan process with PID: 2533
[task 2021-08-18T09:03:47.020Z] 09:03:47 INFO - zombiecheck | Checking for orphan process with PID: 2696
[task 2021-08-18T09:03:47.022Z] 09:03:47 INFO - zombiecheck | Checking for orphan process with PID: 2781
[task 2021-08-18T09:03:47.022Z] 09:03:47 INFO - zombiecheck | Checking for orphan process with PID: 2719
[task 2021-08-18T09:03:47.023Z] 09:03:47 INFO - zombiecheck | Checking for orphan process with PID: 2610
[task 2021-08-18T09:03:47.023Z] 09:03:47 INFO - zombiecheck | Checking for orphan process with PID: 2653
[task 2021-08-18T09:03:47.024Z] 09:03:47 INFO - zombiecheck | Checking for orphan process with PID: 2591
[task 2021-08-18T09:03:47.024Z] 09:03:47 INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump_stackwalk/minidump_stackwalk /tmp/tmpl1qh3c48.mozrunner/minidumps/697d74b9-3b5a-c23c-57f4-9b3d85db700c.dmp /builds/worker/workspace/build/symbols
[task 2021-08-18T09:03:47.064Z] 09:03:47 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/697d74b9-3b5a-c23c-57f4-9b3d85db700c.dmp
[task 2021-08-18T09:03:47.064Z] 09:03:47 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [None]
[task 2021-08-18T09:03:47.064Z] 09:03:47 INFO - Crash dump filename: /tmp/tmpl1qh3c48.mozrunner/minidumps/697d74b9-3b5a-c23c-57f4-9b3d85db700c.dmp
[task 2021-08-18T09:03:47.064Z] 09:03:47 INFO - stderr from minidump_stackwalk:
[task 2021-08-18T09:03:47.064Z] 09:03:47 INFO - 2021-08-18 09:03:47: minidump_stackwalk.cc:141: ERROR: MinidumpProcessor::Process failed
[task 2021-08-18T09:03:47.064Z] 09:03:47 INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump_stackwalk/minidump_stackwalk /tmp/tmpl1qh3c48.mozrunner/minidumps/3223e60b-fb6e-1dfe-c149-c3135c7d7661.dmp /builds/worker/workspace/build/symbols
[task 2021-08-18T09:03:52.201Z] 09:03:52 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/3223e60b-fb6e-1dfe-c149-c3135c7d7661.dmp
[task 2021-08-18T09:03:52.201Z] 09:03:52 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/3223e60b-fb6e-1dfe-c149-c3135c7d7661.extra
[task 2021-08-18T09:03:52.396Z] 09:03:52 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ profiler_thread_sleep()]
[task 2021-08-18T09:03:52.397Z] 09:03:52 INFO - Crash dump filename: /tmp/tmpl1qh3c48.mozrunner/minidumps/3223e60b-fb6e-1dfe-c149-c3135c7d7661.dmp
[task 2021-08-18T09:03:52.397Z] 09:03:52 INFO - Operating system: Linux
[task 2021-08-18T09:03:52.397Z] 09:03:52 INFO - 0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO - CPU: amd64
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO - family 6 model 85 stepping 4
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO - 2 CPUs
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO -
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO - GPU: UNKNOWN
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO -
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO - Crash reason: SIGABRT
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO - Crash address: 0x3e8000005c5
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO - Process uptime: not available
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO -
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO - Thread 0 (crashed) 0 libc.so.6 + 0x114cf9
[task 2021-08-18T09:03:52.398Z] 09:03:52 INFO - rax = 0xfffffffffffffffc rdx = 0x00000000ffffffff
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - rcx = 0x00007febdb4a5cf9 rbx = 0x00007febbdeb4d40
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - rsi = 0x0000000000000003 rdi = 0x00007febbdeb4d40
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - rbp = 0x0000000000000003 rsp = 0x00007ffd95b1be40
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - r8 = 0x0000000000000000 r9 = 0x0000000000000000
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - r10 = 0x00007febbeeb7420 r11 = 0x0000000000000293
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - r12 = 0x00000000ffffffff r13 = 0x00007ffd95b1be78
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - r14 = 0x00000000ffffffff r15 = 0x0000000000000003
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - rip = 0x00007febdb4a5cf9
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - Found by: given as instruction pointer in context
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - 1 libxul.so!profiler_thread_sleep() [platform.cpp:0a2ea4ddc8d51c21798c1f64ceaa00b87d112836 : 5688 + 0x13]
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - rsp = 0x00007ffd95b1be50 rip = 0x00007febce7b633b
[task 2021-08-18T09:03:52.399Z] 09:03:52 INFO - Found by: stack scanning
[task 2021-08-18T09:03:52.400Z] 09:03:52 INFO - 2 libxul.so!PollWrapper(_GPollFD*, unsigned int, int) [nsAppShell.cpp:0a2ea4ddc8d51c21798c1f64ceaa00b87d112836 : 58 + 0xf]
[task 2021-08-18T09:03:52.400Z] 09:03:52 INFO - rsp = 0x00007ffd95b1be70 rip = 0x00007febcd808cb0
[task 2021-08-18T09:03:52.400Z] 09:03:52 INFO - Found by: stack scanning
[task 2021-08-18T09:03:52.400Z] 09:03:52 INFO - 3 libxul.so!nsCOMPtr<nsIPowerManagerService>::~nsCOMPtr() [nsCOMPtr.h:0a2ea4ddc8d51c21798c1f64ceaa00b87d112836 : 453 + 0x7]
[task 2021-08-18T09:03:52.400Z] 09:03:52 INFO - rsp = 0x00007ffd95b1bea8 rip = 0x00007febcd808c32
[task 2021-08-18T09:03:52.400Z] 09:03:52 INFO - Found by: stack scanning
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 3•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•