Closed Bug 1769950 Opened 3 years ago Closed 3 years ago

Intermittent [tier 2] netwerk/test/mochitests/test_1396395.html | Test timed out. -

Categories

(Core :: Networking, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2022-05-18T11:20:11.292Z] 11:20:11     INFO - TEST-START | netwerk/test/mochitests/test_1396395.html
[task 2022-05-18T11:25:39.236Z] 11:25:39     INFO - TEST-INFO | started process screentopng
[task 2022-05-18T11:25:39.368Z] 11:25:39     INFO - TEST-INFO | screentopng: exit 0
[task 2022-05-18T11:25:39.369Z] 11:25:39     INFO - TEST-UNEXPECTED-FAIL | netwerk/test/mochitests/test_1396395.html | Test timed out. - 
[task 2022-05-18T11:26:09.237Z] 11:26:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:26:09.238Z] 11:26:09     INFO - TEST-UNEXPECTED-FAIL | netwerk/test/mochitests/test_1396395.html | Test timed out. - 
[task 2022-05-18T11:26:39.238Z] 11:26:39     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:26:39.239Z] 11:26:39     INFO - TEST-UNEXPECTED-FAIL | netwerk/test/mochitests/test_1396395.html | Test timed out. - 
[task 2022-05-18T11:27:09.239Z] 11:27:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:27:09.240Z] 11:27:09     INFO - TEST-UNEXPECTED-FAIL | netwerk/test/mochitests/test_1396395.html | Test timed out. - 
[task 2022-05-18T11:27:09.240Z] 11:27:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:27:09.241Z] 11:27:09     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. - 
[task 2022-05-18T11:27:09.241Z] 11:27:09     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:27:09.241Z] 11:27:09     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 28 remaining tests. - 
[task 2022-05-18T11:28:33.381Z] 11:28:33     INFO - GECKO(17406) | 1652873313380	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates
[task 2022-05-18T11:34:43.394Z] 11:34:43     INFO - Buffered messages finished
[task 2022-05-18T11:34:43.395Z] 11:34:43    ERROR - TEST-UNEXPECTED-TIMEOUT | netwerk/test/mochitests/test_1396395.html | application timed out after 370 seconds with no output
[task 2022-05-18T11:34:43.395Z] 11:34:43    ERROR - Force-terminating active process(es).
[task 2022-05-18T11:34:43.395Z] 11:34:43     INFO - Determining child pids from psutil...
[task 2022-05-18T11:34:43.419Z] 11:34:43     INFO - [17481, 17488, 17532, 17537, 17722, 17723, 17724, 17725]
[task 2022-05-18T11:34:43.420Z] 11:34:43     INFO - ==> process 17406 launched child process 17429
[task 2022-05-18T11:34:43.420Z] 11:34:43     INFO - ==> process 17406 launched child process 17481
[task 2022-05-18T11:34:43.422Z] 11:34:43     INFO - ==> process 17406 launched child process 17488
[task 2022-05-18T11:34:43.423Z] 11:34:43     INFO - ==> process 17406 launched child process 17532
[task 2022-05-18T11:34:43.424Z] 11:34:43     INFO - ==> process 17406 launched child process 17537
[task 2022-05-18T11:34:43.424Z] 11:34:43     INFO - ==> process 17406 launched child process 17543
[task 2022-05-18T11:34:43.425Z] 11:34:43     INFO - ==> process 17406 launched child process 17656
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - ==> process 17406 launched child process 17657
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - ==> process 17406 launched child process 17722
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - ==> process 17406 launched child process 17723
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - ==> process 17406 launched child process 17724
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - ==> process 17406 launched child process 17725
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - Found child pids: {17537, 17543, 17481, 17488, 17429, 17724, 17656, 17657, 17722, 17723, 17532, 17725}
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - Failed to get child procs
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - Killing process: 17537
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - Killing process: 17543
[task 2022-05-18T11:34:43.426Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.427Z] 11:34:43     INFO - Can't trigger Breakpad, process no longer exists
[task 2022-05-18T11:34:43.427Z] 11:34:43     INFO - Killing process: 17481
[task 2022-05-18T11:34:43.427Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.427Z] 11:34:43     INFO - Killing process: 17488
[task 2022-05-18T11:34:43.427Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.427Z] 11:34:43     INFO - Killing process: 17429
[task 2022-05-18T11:34:43.438Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.439Z] 11:34:43     INFO - Can't trigger Breakpad, process no longer exists
[task 2022-05-18T11:34:43.439Z] 11:34:43     INFO - Killing process: 17724
[task 2022-05-18T11:34:43.439Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.439Z] 11:34:43     INFO - Killing process: 17656
[task 2022-05-18T11:34:43.440Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.440Z] 11:34:43     INFO - Can't trigger Breakpad, process no longer exists
[task 2022-05-18T11:34:43.440Z] 11:34:43     INFO - Killing process: 17657
[task 2022-05-18T11:34:43.441Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.443Z] 11:34:43     INFO - Can't trigger Breakpad, process no longer exists
[task 2022-05-18T11:34:43.443Z] 11:34:43     INFO - Killing process: 17722
[task 2022-05-18T11:34:43.444Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.444Z] 11:34:43     INFO - Killing process: 17723
[task 2022-05-18T11:34:43.445Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.445Z] 11:34:43     INFO - Killing process: 17532
[task 2022-05-18T11:34:43.445Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.446Z] 11:34:43     INFO - Killing process: 17725
[task 2022-05-18T11:34:43.446Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.447Z] 11:34:43     INFO - Killing process: 17406
[task 2022-05-18T11:34:43.447Z] 11:34:43     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-05-18T11:34:43.503Z] 11:34:43     INFO - psutil found pid 17406 dead
[task 2022-05-18T11:34:43.511Z] 11:34:43     INFO - TEST-INFO | Main app process: exit 0
[task 2022-05-18T11:34:43.513Z] 11:34:43     INFO - runtests.py | Application ran for: 0:14:40.943307
[task 2022-05-18T11:34:43.513Z] 11:34:43     INFO - zombiecheck | Reading PID log: /tmp/tmp9kgpx8fhpidlog
[task 2022-05-18T11:34:43.514Z] 11:34:43     INFO - ==> process 17406 launched child process 17429
[task 2022-05-18T11:34:43.514Z] 11:34:43     INFO - ==> process 17406 launched child process 17481
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17488
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17532
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17537
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17543
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17656
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17657
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17722
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17723
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17724
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - ==> process 17406 launched child process 17725
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17537
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17543
[task 2022-05-18T11:34:43.517Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17481
[task 2022-05-18T11:34:43.518Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17488
[task 2022-05-18T11:34:43.518Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17429
[task 2022-05-18T11:34:43.519Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17724
[task 2022-05-18T11:34:43.519Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17656
[task 2022-05-18T11:34:43.519Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17657
[task 2022-05-18T11:34:43.520Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17722
[task 2022-05-18T11:34:43.520Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17723
[task 2022-05-18T11:34:43.520Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17532
[task 2022-05-18T11:34:43.520Z] 11:34:43     INFO - zombiecheck | Checking for orphan process with PID: 17725
[task 2022-05-18T11:34:43.520Z] 11:34:43     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/JC9DbPV9Q4ajnhCQnXRrOw/artifacts/public/build/target.crashreporter-symbols.zip
[task 2022-05-18T11:34:45.821Z] 11:34:45     INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --human /tmp/tmpzy0j22tj.mozrunner/minidumps/2fd3882f-f8ab-4cce-5982-3f60bb605e09.dmp /tmp/tmpwak9456j
[task 2022-05-18T11:34:48.081Z] 11:34:48     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/2fd3882f-f8ab-4cce-5982-3f60bb605e09.dmp
[task 2022-05-18T11:34:48.085Z] 11:34:48     INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/2fd3882f-f8ab-4cce-5982-3f60bb605e09.extra
[task 2022-05-18T11:34:48.097Z] 11:34:48     INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ fcntl]
[task 2022-05-18T11:34:48.097Z] 11:34:48     INFO - Crash dump filename: /tmp/tmpzy0j22tj.mozrunner/minidumps/2fd3882f-f8ab-4cce-5982-3f60bb605e09.dmp
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO - Operating system: Linux
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO -                   4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO - CPU: amd64
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO -      family 6 model 85 stepping 4
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO -      4 CPUs
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO - Linux Ubuntu 18.04 - bionic (Ubuntu 18.04.6 LTS)
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO - 
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO - Crash reason:  SIGABRT
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO - Crash address: 0x3e80000062d
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO - Process uptime: not available
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO - 
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO - Thread 0 IPC Launch (crashed)
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO -  0  libpthread.so.0!fcntl [fcntl.c : 63 + 0x1c]
[task 2022-05-18T11:34:48.098Z] 11:34:48     INFO -      rax = 0xfffffffffffffffc    rdx = 0x00007f05f12c6620
[task 2022-05-18T11:34:48.099Z] 11:34:48     INFO -      rcx = 0x00007f0637bbf59c    rbx = 0x0000000000000005
[task 2022-05-18T11:34:48.099Z] 11:34:48     INFO -      rsi = 0x0000000000000007    rdi = 0x0000000000000005
[task 2022-05-18T11:34:48.099Z] 11:34:48     INFO -      rbp = 0x0000000000000000    rsp = 0x00007f05f12c65a0
[task 2022-05-18T11:34:48.099Z] 11:34:48     INFO -       r8 = 0x0000000000000000     r9 = 0x0000000000000000
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r10 = 0x0000000000000000    r11 = 0x0000000000000293
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r12 = 0x00007f0615e131e5    r13 = 0x0000000000000010
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r14 = 0x00007f05f12c6620    r15 = 0x000000003430382a
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      rip = 0x00007f0637bbf59c
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -     Found by: given as instruction pointer in context
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -  1  libxul.so!lprofLockFd + 0x40
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      rbx = 0x0000000000000005    rbp = 0x0000000000000000
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6620    r12 = 0x00007f0615e131e5
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r13 = 0x0000000000000010    r14 = 0x00007f05f12c6620
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r15 = 0x000000003430382a    rip = 0x00007f0627e8bae1
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -  2  libxul.so!llvm_gcda_start_file + 0x11f
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      rbx = 0x00007f061604ea47    rbp = 0x00007f061604ea47
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6660    r12 = 0x00007f0615e131e5
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r13 = 0x0000000000000010    r14 = 0x00000000bb0da3d9
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r15 = 0x000000003430382a    rip = 0x00007f0627e8a520
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -  3  libxul.so!__llvm_gcov_writeout + 0x1f
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      rbx = 0x00007f0636a8bf00    rbp = 0x00007f05f12c66d0
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      rsp = 0x00007f05f12c66b0    r12 = 0x00007f05ed5fcba0
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r13 = 0x00007f05f12c6720    r14 = 0x00007f062cf2ca78
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r15 = 0x00007f0636aaf7d8    rip = 0x00007f061ab8d4c0
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -  4  libxul.so!llvm_writeout_files + 0x32
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      rbx = 0x00007f0636a8bf00    rbp = 0x00007f05f12c6830
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      rsp = 0x00007f05f12c66e0    r12 = 0x00007f05ed5fcba0
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r13 = 0x00007f05f12c6720    r14 = 0x00007f062cf2ca78
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -      r15 = 0x00007f0636aaf7d8    rip = 0x00007f0627e8b663
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.100Z] 11:34:48     INFO -  5  libxul.so!base::LaunchApp(std::vector<std::string, std::allocator<std::string> > const&, base::LaunchOptions const&, int*) + 0x159e
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rbx = 0x00007f05ef611c00    rbp = 0x00007f05f12c6830
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6700    r12 = 0x00007f05ed5fcba0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r13 = 0x00007f05f12c6720    r14 = 0x00007f05f12c6788
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r15 = 0x00007f0636aaf7d8    rip = 0x00007f061b89d09f
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -  6  libxul.so!mozilla::ipc::PosixProcessLauncher::DoLaunch() + 0x56
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rbx = 0x00007f05f12c6878    rbp = 0x00007f05f12c6860
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6840    r12 = 0x00007f05e81b02c0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f05ed9c69a0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r15 = 0x00007f05f12c6940    rip = 0x00007f061b91f3f7
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -  7  libxul.so!mozilla::ipc::BaseProcessLauncher::PerformAsyncLaunch() + 0x8d
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rbx = 0x00007f05f12c68c8    rbp = 0x00007f05f12c68b0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6870    r12 = 0x00007f05e81b02c0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f05ed9c69a0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r15 = 0x00007f05f12c6940    rip = 0x00007f061b91d74e
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -  8  libxul.so!mozilla::detail::ProxyRunnable<mozilla::MozPromise<mozilla::ipc::LaunchResults, mozilla::ipc::LaunchError, true>, RefPtr<mozilla::MozPromise<mozilla::ipc::LaunchResults, mozilla::ipc::LaunchError, true> > (mozilla::ipc::BaseProcessLauncher::*)(), mozilla::ipc::BaseProcessLauncher, >::Run() + 0x8c
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rbx = 0x00007f05ed983650    rbp = 0x00007f05f12c68e0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rsp = 0x00007f05f12c68c0    r12 = 0x00007f060f344100
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f05ed9c69a0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r15 = 0x00007f05f12c6940    rip = 0x00007f061b935c3d
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -  9  libxul.so!mozilla::TaskQueue::Runner::Run() + 0x328
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rbx = 0x00007f05ed983650    rbp = 0x00007f05f12c69d0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rsp = 0x00007f05f12c68f0    r12 = 0x00007f060f344100
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f05ed9c69a0
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      r15 = 0x00007f05f12c6940    rip = 0x00007f061abcf779
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO - 10  libxul.so!nsThread::ProcessNextEvent(bool, bool*) + 0xca6
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rbx = 0x00007f05f12c6a70    rbp = 0x00007f05f12c6b00
[task 2022-05-18T11:34:48.101Z] 11:34:48     INFO -      rsp = 0x00007f05f12c69e0    r12 = 0x00007f060418eae0
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r13 = 0x00007f060418ebe8    r14 = 0x00007f05f12c6b1f
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r15 = 0x000004fcf3afa0b8    rip = 0x00007f061abe3337
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO - 11  libxul.so!NS_ProcessNextEvent(nsIThread*, bool) + 0x41
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rbx = 0x0000000000000001    rbp = 0x00007f05f12c6b30
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6b10    r12 = 0x00007f06099d1d60
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r13 = 0x00007f05f12c6bc0    r14 = 0x00007f06099d1d40
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r15 = 0x00007f060418eae0    rip = 0x00007f061abe9a72
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO - 12  libxul.so!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) + 0x19b
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rbx = 0x0000000000000000    rbp = 0x00007f05f12c6b70
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6b40    r12 = 0x00007f06099d1d60
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r13 = 0x00007f05f12c6bc0    r14 = 0x00007f06099d1d40
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r15 = 0x00007f060418eae0    rip = 0x00007f061b95aa3c
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO - 13  libxul.so!MessageLoop::Run() + 0x8d
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rbx = 0x00007f060418eae0    rbp = 0x00007f05f12c6ba0
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6b80    r12 = 0x00007f05f1a73ca0
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r13 = 0x0000000000000002    r14 = 0x00007f05f12c6bc0
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r15 = 0x000000000000000a    rip = 0x00007f061b8a687e
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO - 14  libxul.so!nsThread::ThreadFunc(void*) + 0x28b
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rbx = 0x00007f060418eae0    rbp = 0x00007f05f12c6d90
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6bb0    r12 = 0x00007f05f1a73ca0
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r13 = 0x0000000000000002    r14 = 0x00007f05f12c6bc0
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r15 = 0x000000000000000a    rip = 0x00007f061abde02c
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO - 15  libnspr4.so!_pt_root + 0x1ee
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rbx = 0x00007f0636a5dd40    rbp = 0x00007f05f12c6de0
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6da0    r12 = 0x00007f05f1a73ca0
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r13 = 0x0000000000000002    r14 = 0x00007f05f12c7700
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -      r15 = 0x0000000000004448    rip = 0x00007f0637f3432f
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.102Z] 11:34:48     INFO - 16  firefox-bin!set_alt_signal_stack_and_start(PthreadCreateParams*) + 0xf8
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      rbx = 0x00007f05f5607000    rbp = 0x00007f05f12c6eb0
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6df0    r12 = 0x00007f0637f34140
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f05f1a73ca0
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      r15 = 0x00007f05f12c6e20    rip = 0x000055f25e846d59
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO - 17  libpthread.so.0!start_thread [pthread_create.c : 463 + 0xc]
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      rbx = 0x0000000000000000    rbp = 0x0000000000000000
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6ec0    r12 = 0x00007f05f12c6f80
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f05f197a1c0
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      r15 = 0x00007ffc5ef60668    rip = 0x00007f0637bb56db
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO - 18  libc.so.6!__GI___clone + 0x3e
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      rbx = 0x00007f05f12c7700    rbp = 0x0000000000000000
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      rsp = 0x00007f05f12c6f80    r12 = 0x00007f05f12c6f80
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f05f197a1c0
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -      r15 = 0x00007ffc5ef60668    rip = 0x00007f0636d9ba3f
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO -     Found by: call frame info
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO - 
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO - 
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO - Loaded modules:
[task 2022-05-18T11:34:48.103Z] 11:34:48     INFO - 0x55f25e6e4000 - 0x55f25e865fff  firefox-bin  ???  (main)
<...>
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - 0x7f0637fe4000 - 0x7f0637ff0fff  libplc4.so  ???
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - 0x7ffc5efb6000 - 0x7ffc5efb7fff  linux-gate.so  ???
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - 
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - Unloaded modules:
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - 
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - Unimplemented streams encountered:
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - Stream 0x47670006 LinuxCmdLine (Google Extension) @ 0x000121a8
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - Stream 0x47670008 LinuxAuxv (Google Extension) @ 0x00013700
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - Stream 0x4767000a LinuxDsoDebug (Google Extension) @ 0x00038a60
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --human /tmp/tmpzy0j22tj.mozrunner/minidumps/1c5c75d0-e01c-478b-5be9-b665abb0e920.dmp /tmp/tmpwak9456j
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/1c5c75d0-e01c-478b-5be9-b665abb0e920.dmp
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - PROCESS-CRASH | Main app process exited normally | application crashed [None]
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - Crash dump filename: /tmp/tmpzy0j22tj.mozrunner/minidumps/1c5c75d0-e01c-478b-5be9-b665abb0e920.dmp
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - stderr from minidump-stackwalk:
[task 2022-05-18T11:34:48.115Z] 11:34:48     INFO - [ERROR] MissingSystemInfo - Error processing dump: The system information stream was not found
[task 2022-05-18T11:34:48.191Z] 11:34:48     INFO - TEST-UNEXPECTED-CRASH | Main app process exited normally | application terminated with exit code 0
[task 2022-05-18T11:34:48.192Z] 11:34:48     INFO - TEST-INFO 
[task 2022-05-18T11:34:48.192Z] 11:34:48     INFO - Stopping web server
[task 2022-05-18T11:34:48.195Z] 11:34:48     INFO - Server shut down.
[task 2022-05-18T11:34:48.216Z] 11:34:48     INFO - Web server killed.
[task 2022-05-18T11:34:48.216Z] 11:34:48     INFO - Stopping web socket server
[task 2022-05-18T11:34:48.236Z] 11:34:48     INFO - Stopping ssltunnel
[task 2022-05-18T11:34:48.256Z] 11:34:48  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2022-05-18T11:34:48.257Z] 11:34:48     INFO - runtests.py | Running tests: end.
[task 2022-05-18T11:34:48.301Z] 11:34:48     INFO - Buffered messages finished
[task 2022-05-18T11:34:48.302Z] 11:34:48     INFO - Running manifest: toolkit/components/extensions/test/mochitest/mochitest-serviceworker.ini
[task 2022-05-18T11:34:48.302Z] 11:34:48     INFO - The following extra prefs will be set:
[task 2022-05-18T11:34:48.302Z] 11:34:48     INFO -   extensions.webextensions.remote=true
[task 2022-05-18T11:34:48.303Z] 11:34:48     INFO -   extensions.backgroundServiceWorker.enabled=true
[task 2022-05-18T11:34:48.303Z] 11:34:48     INFO -   extensions.backgroundServiceWorker.forceInTestExtension=true
[task 2022-05-18T11:34:48.319Z] 11:34:48     INFO -  Setting pipeline to PAUSED ...
[task 2022-05-18T11:34:48.319Z] 11:34:48     INFO -  Pipeline is PREROLLING ...
[task 2022-05-18T11:34:48.321Z] 11:34:48     INFO -  Pipeline is PREROLLED ...
[task 2022-05-18T11:34:48.321Z] 11:34:48     INFO -  Setting pipeline to PLAYING ...
[task 2022-05-18T11:34:48.321Z] 11:34:48     INFO -  New clock: GstSystemClock
[task 2022-05-18T11:34:48.354Z] 11:34:48     INFO -  Got EOS from element "pipeline0".
[task 2022-05-18T11:34:48.354Z] 11:34:48     INFO -  Execution ended after 0:00:00.033413392
[task 2022-05-18T11:34:48.355Z] 11:34:48     INFO -  Setting pipeline to PAUSED ...
[task 2022-05-18T11:34:48.355Z] 11:34:48     INFO -  Setting pipeline to READY ...
[task 2022-05-18T11:34:48.355Z] 11:34:48     INFO -  (gst-launch-1.0:17782): GStreamer-CRITICAL **: 11:34:48.354: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2022-05-18T11:34:48.355Z] 11:34:48     INFO -  Setting pipeline to NULL ...
[task 2022-05-18T11:34:48.356Z] 11:34:48     INFO -  Freeing pipeline ...
[task 2022-05-18T11:34:48.698Z] 11:34:48     INFO - PID 17805 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2022-05-18T11:34:48.779Z] 11:34:48     INFO - MochitestServer : launching ['/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-f', '/builds/worker/workspace/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpiur73kd8.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2022-05-18T11:34:48.779Z] 11:34:48     INFO - runtests.py | Server pid: 17811
[task 2022-05-18T11:34:48.785Z] 11:34:48     INFO - runtests.py | Websocket server pid: 17814
[task 2022-05-18T11:34:48.800Z] 11:34:48     INFO - runtests.py | SSL tunnel pid: 17818
[task 2022-05-18T11:34:48.873Z] 11:34:48     INFO -  [CodeCoverage] Setting handlers for process 17811.
[task 2022-05-18T11:34:48.901Z] 11:34:48     INFO - runtests.py | Running with scheme: http
[task 2022-05-18T11:34:48.902Z] 11:34:48     INFO - runtests.py | Running with e10s: True
[task 2022-05-18T11:34:48.902Z] 11:34:48     INFO - runtests.py | Running with fission: True
[task 2022-05-18T11:34:48.903Z] 11:34:48     INFO - runtests.py | Running with cross-origin iframes: True
[task 2022-05-18T11:34:48.904Z] 11:34:48     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2022-05-18T11:34:48.905Z] 11:34:48     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2022-05-18T11:34:48.906Z] 11:34:48     INFO - runtests.py | Running tests: start.
[task 2022-05-18T11:34:48.906Z] 11:34:48     INFO - 
[task 2022-05-18T11:34:48.977Z] 11:34:48     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpiur73kd8.mozrunner
[task 2022-05-18T11:34:48.984Z] 11:34:48     INFO - runtests.py | Application pid: 17839
[task 2022-05-18T11:34:48.984Z] 11:34:48     INFO - TEST-INFO | started process GECKO(17839)
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.