Closed Bug 1914817 Opened 1 year ago Closed 1 year ago

Intermittent editor/spellchecker/tests/test_bug1200533.html | single tracking bug

Categories

(Core :: DOM: Editor, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2024-08-25T22:57:31.694Z] 22:57:31     INFO - TEST-START | editor/spellchecker/tests/test_bug1200533.html
[task 2024-08-25T22:57:48.522Z] 22:57:48     INFO - GECKO(27776) | [Parent 27776, Main Thread] WARNING: Failed to create DBus proxy for org.a11y.Bus: Error calling StartServiceByName for org.a11y.Bus: Timeout was reached
[task 2024-08-25T22:57:48.522Z] 22:57:48     INFO - GECKO(27776) | : 'glib warning', file /builds/worker/checkouts/gecko/toolkit/xre/nsSigHandlers.cpp:187
[task 2024-08-25T22:57:48.523Z] 22:57:48     INFO - GECKO(27776) | ** (firefox-default:27776): WARNING **: 22:57:48.521: Failed to create DBus proxy for org.a11y.Bus: Error calling StartServiceByName for org.a11y.Bus: Timeout was reached
[task 2024-08-25T23:02:59.280Z] 23:02:59     INFO - TEST-INFO | started process screentopng
[task 2024-08-25T23:02:59.400Z] 23:02:59     INFO - TEST-INFO | screentopng: exit 0
[task 2024-08-25T23:02:59.401Z] 23:02:59     INFO - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1200533.html | Test timed out. - 
[task 2024-08-25T23:03:29.282Z] 23:03:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:03:29.282Z] 23:03:29     INFO - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1200533.html | Test timed out. - 
[task 2024-08-25T23:03:59.283Z] 23:03:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:03:59.283Z] 23:03:59     INFO - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1200533.html | Test timed out. - 
[task 2024-08-25T23:04:29.285Z] 23:04:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:04:29.286Z] 23:04:29     INFO - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1200533.html | Test timed out. - 
[task 2024-08-25T23:04:29.287Z] 23:04:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:04:29.287Z] 23:04:29     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2024-08-25T23:04:29.313Z] 23:04:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:04:29.313Z] 23:04:29     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 26 remaining tests. - 
[task 2024-08-25T23:10:39.327Z] 23:10:39     INFO - TEST-UNEXPECTED-TIMEOUT | editor/spellchecker/tests/test_bug1200533.html | application timed out after 370 seconds with no output
[task 2024-08-25T23:10:39.327Z] 23:10:39     INFO - TEST-INFO took 787307ms
[task 2024-08-25T23:10:39.328Z] 23:10:39     INFO - Buffered messages finished
[task 2024-08-25T23:10:39.328Z] 23:10:39  WARNING - Force-terminating active process(es).
[task 2024-08-25T23:10:39.328Z] 23:10:39     INFO - Attempting to start the profiler to help with diagnosing the hang.
[task 2024-08-25T23:10:39.328Z] 23:10:39     INFO - Sending SIGUSR1 to pid 27776 start the profiler.
[task 2024-08-25T23:10:39.328Z] 23:10:39     INFO - Waiting 10s to capture a profile.
[task 2024-08-25T23:10:49.337Z] 23:10:49     INFO - Sending SIGUSR2 to pid 27776 stop the profiler.
[task 2024-08-25T23:10:49.337Z] 23:10:49     INFO - Wait 10s for Firefox to write the profile to disk.
[task 2024-08-25T23:10:59.347Z] 23:10:59     INFO - Determining child pids from psutil...
[task 2024-08-25T23:10:59.360Z] 23:10:59     INFO - []
[task 2024-08-25T23:10:59.362Z] 23:10:59     INFO - ==> process 27776 launched child process 27863
[task 2024-08-25T23:10:59.362Z] 23:10:59     INFO - ==> process 27776 launched child process 27868
[task 2024-08-25T23:10:59.363Z] 23:10:59     INFO - ==> process 27776 launched child process 27951
[task 2024-08-25T23:10:59.367Z] 23:10:59     INFO - ==> process 27776 launched child process 27954
[task 2024-08-25T23:10:59.367Z] 23:10:59     INFO - ==> process 27776 launched child process 27956
[task 2024-08-25T23:10:59.367Z] 23:10:59     INFO - ==> process 27776 launched child process 27957
[task 2024-08-25T23:10:59.367Z] 23:10:59     INFO - ==> process 27776 launched child process 27958
[task 2024-08-25T23:10:59.367Z] 23:10:59     INFO - ==> process 27776 launched child process 28069
[task 2024-08-25T23:10:59.367Z] 23:10:59     INFO - ==> process 27776 launched child process 28109
[task 2024-08-25T23:10:59.367Z] 23:10:59     INFO - ==> process 27776 launched child process 28110
[task 2024-08-25T23:10:59.367Z] 23:10:59     INFO - ==> process 27776 launched child process 28111
[task 2024-08-25T23:10:59.367Z] 23:10:59     INFO - ==> process 27776 launched child process 28112
[task 2024-08-25T23:10:59.368Z] 23:10:59     INFO - Found child pids: {28069, 28109, 28110, 27951, 28111, 28112, 27954, 27956, 27957, 27958, 27863, 27868}
[task 2024-08-25T23:10:59.369Z] 23:10:59     INFO - Failed to get child procs
[task 2024-08-25T23:10:59.369Z] 23:10:59     INFO - Killing process: 28069
[task 2024-08-25T23:10:59.370Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.370Z] 23:10:59     INFO - Killing process: 28109
[task 2024-08-25T23:10:59.370Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.370Z] 23:10:59     INFO - Killing process: 28110
[task 2024-08-25T23:10:59.370Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.370Z] 23:10:59     INFO - Killing process: 27951
[task 2024-08-25T23:10:59.370Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.371Z] 23:10:59     INFO - Killing process: 28111
[task 2024-08-25T23:10:59.371Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.371Z] 23:10:59     INFO - Killing process: 28112
[task 2024-08-25T23:10:59.371Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.371Z] 23:10:59     INFO - Killing process: 27954
[task 2024-08-25T23:10:59.371Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.371Z] 23:10:59     INFO - Killing process: 27956
[task 2024-08-25T23:10:59.372Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.372Z] 23:10:59     INFO - Can't trigger Breakpad, process no longer exists
[task 2024-08-25T23:10:59.372Z] 23:10:59     INFO - Killing process: 27957
[task 2024-08-25T23:10:59.372Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.373Z] 23:10:59     INFO - Can't trigger Breakpad, process no longer exists
[task 2024-08-25T23:10:59.373Z] 23:10:59     INFO - Killing process: 27958
[task 2024-08-25T23:10:59.373Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.373Z] 23:10:59     INFO - Killing process: 27863
[task 2024-08-25T23:10:59.374Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.374Z] 23:10:59     INFO - Killing process: 27868
[task 2024-08-25T23:10:59.374Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.375Z] 23:10:59     INFO - Can't trigger Breakpad, process no longer exists
[task 2024-08-25T23:10:59.375Z] 23:10:59     INFO - Killing process: 27776
[task 2024-08-25T23:10:59.376Z] 23:10:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-08-25T23:10:59.376Z] 23:10:59     INFO - psutil found pid 27776 dead
[task 2024-08-25T23:10:59.439Z] 23:10:59     INFO - TEST-INFO | Main app process: exit 0
[task 2024-08-25T23:10:59.440Z] 23:10:59     INFO - runtests.py | Application ran for: 0:13:36.735136
[task 2024-08-25T23:10:59.440Z] 23:10:59     INFO - zombiecheck | Reading PID log: /tmp/tmpch4nq7ujpidlog
[task 2024-08-25T23:10:59.441Z] 23:10:59     INFO - ==> process 27776 launched child process 27863
[task 2024-08-25T23:10:59.441Z] 23:10:59     INFO - ==> process 27776 launched child process 27868
[task 2024-08-25T23:10:59.441Z] 23:10:59     INFO - ==> process 27776 launched child process 27951
[task 2024-08-25T23:10:59.442Z] 23:10:59     INFO - ==> process 27776 launched child process 27954
[task 2024-08-25T23:10:59.442Z] 23:10:59     INFO - ==> process 27776 launched child process 27956
[task 2024-08-25T23:10:59.443Z] 23:10:59     INFO - ==> process 27776 launched child process 27957
[task 2024-08-25T23:10:59.443Z] 23:10:59     INFO - ==> process 27776 launched child process 27958
[task 2024-08-25T23:10:59.443Z] 23:10:59     INFO - ==> process 27776 launched child process 28069
[task 2024-08-25T23:10:59.444Z] 23:10:59     INFO - ==> process 27776 launched child process 28109
[task 2024-08-25T23:10:59.444Z] 23:10:59     INFO - ==> process 27776 launched child process 28110
[task 2024-08-25T23:10:59.444Z] 23:10:59     INFO - ==> process 27776 launched child process 28111
[task 2024-08-25T23:10:59.444Z] 23:10:59     INFO - ==> process 27776 launched child process 28112
[task 2024-08-25T23:10:59.445Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 28069
[task 2024-08-25T23:10:59.445Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 28109
[task 2024-08-25T23:10:59.446Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 28110
[task 2024-08-25T23:10:59.446Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 27951
[task 2024-08-25T23:10:59.447Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 28111
[task 2024-08-25T23:10:59.447Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 28112
[task 2024-08-25T23:10:59.447Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 27954
[task 2024-08-25T23:10:59.447Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 27956
[task 2024-08-25T23:10:59.447Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 27957
[task 2024-08-25T23:10:59.447Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 27958
[task 2024-08-25T23:10:59.447Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 27863
[task 2024-08-25T23:10:59.447Z] 23:10:59     INFO - zombiecheck | Checking for orphan process with PID: 27868
[task 2024-08-25T23:10:59.447Z] 23:10:59     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Frd1RUNYTkWqpCVORn0XWg/artifacts/public/build/target.crashreporter-symbols.zip
[task 2024-08-25T23:11:09.862Z] 23:11:09     INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --cyborg=/tmp/tmpx9zym6kf/211875d1-eee9-1016-b589-6270ce81b006.trace /tmp/tmpaheuhpxh.mozrunner/minidumps/211875d1-eee9-1016-b589-6270ce81b006.dmp /tmp/tmp6w5abudm
[task 2024-08-25T23:11:10.231Z] 23:11:10     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/211875d1-eee9-1016-b589-6270ce81b006.dmp
[task 2024-08-25T23:11:10.231Z] 23:11:10     INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/211875d1-eee9-1016-b589-6270ce81b006.extra
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - PROCESS-CRASH | application crashed [@ libpthread.so.0 + 0x000000000001159c] | editor/spellchecker/tests/mochitest.toml 
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Process type: main
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Process pid: 28110
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Crash dump filename: /tmp/tmpaheuhpxh.mozrunner/minidumps/211875d1-eee9-1016-b589-6270ce81b006.dmp
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Operating system: Linux
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -                   4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - CPU: amd64
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -      family 6 model 85 stepping 7
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -      4 CPUs
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Linux Ubuntu 18.04 - bionic (Ubuntu 18.04.6 LTS)
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - 
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Crash reason:  SIGABRT
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Crash address: 0x000003e800000404
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Crashing instruction: `cmp rax, -0x1000`
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - No memory accessed by instruction
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Process uptime: not available
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - 
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO - Thread 0 IPC Launch (crashed)
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -  0  libpthread.so.0 + 0x1159c
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -      rax = 0xfffffffffffffffc    rdx = 0x00007f3c350bb620
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -      rcx = 0x00007f3c7fcf459c    rbx = 0x0000000000000005
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -      rsi = 0x0000000000000007    rdi = 0x0000000000000005
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -      rbp = 0x0000000000000000    rsp = 0x00007f3c350bb5a0
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -       r8 = 0x0000000000000000     r9 = 0x0000000000000000
[task 2024-08-25T23:11:10.244Z] 23:11:10     INFO -      r10 = 0x0000000000000000    r11 = 0x0000000000000293
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      r12 = 0x00007f3c2480e420    r13 = 0x0000000000000010
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      r14 = 0x00007f3c350bb620    r15 = 0x00007f3c5cce3946
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      rip = 0x00007f3c7fcf459c
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -     Found by: given as instruction pointer in context
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -  1  firefox-bin!Unlock [Mutex.h:b9bdd4e7625ed47d2deeb0eb51f8beca7b25a2ea : 106]
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -     Found by: inlining
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -  2  firefox-bin!Unlock [Mutex.h:b9bdd4e7625ed47d2deeb0eb51f8beca7b25a2ea : 210]
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -     Found by: inlining
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -  3  firefox-bin!~AutoLock [Mutex.h:b9bdd4e7625ed47d2deeb0eb51f8beca7b25a2ea : 254]
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -     Found by: inlining
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -  4  firefox-bin!arena_dalloc(void*, unsigned long, arena_t*) [mozjemalloc.cpp:b9bdd4e7625ed47d2deeb0eb51f8beca7b25a2ea : 3993 + 0x15]
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      rsp = 0x00007f3c350bb5c0    rip = 0x0000558de551d5b3
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -     Found by: stack scanning
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -  5  0x200000004
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      rbx = 0x00007f3c350bb620    rbp = 0x00007f3c350bb620
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      rsp = 0x00007f3c350bb600    r12 = 0x00007f3c350bb5e0
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      r13 = 0xa60c694253ce1500    r14 = 0x00007f3c2d135800
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      r15 = 0x00007f3c7fcf4dd2    rip = 0x0000000200000005
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -     Found by: call frame info
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -  6  firefox-bin!Malloc [mozjemalloc.cpp:b9bdd4e7625ed47d2deeb0eb51f8beca7b25a2ea : 3505]
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -     Found by: inlining
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -  7  firefox-bin!BaseAllocator::malloc(unsigned long) [mozjemalloc.cpp:b9bdd4e7625ed47d2deeb0eb51f8beca7b25a2ea : 4728 + 0x2c]
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      rbp = 0x00007f3c350bb620    rsp = 0x00007f3c350bb610
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -      rip = 0x0000558de5523c38
[task 2024-08-25T23:11:10.245Z] 23:11:10     INFO -     Found by: stack scanning
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.