Closed Bug 1939452 Opened 2 months ago Closed 19 days ago

Intermittent dom/tests/mochitest/localstorage/test_storageConstructor.html (finished) | single tracking bug

Categories

(Core :: Storage: localStorage & sessionStorage, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2024-12-30T10:04:36.726Z] 10:04:36     INFO - TEST-START | dom/tests/mochitest/localstorage/test_storageConstructor.html
[task 2024-12-30T10:04:36.913Z] 10:04:36     INFO - GECKO(6584) | MEMORY STAT | vsize 2480MB | residentFast 143MB | heapAllocated 10MB
[task 2024-12-30T10:04:37.162Z] 10:04:37     INFO - TEST-OK | dom/tests/mochitest/localstorage/test_storageConstructor.html | took 438ms
[task 2024-12-30T10:04:37.299Z] 10:04:37     INFO - TEST-START | Shutdown
[task 2024-12-30T10:04:37.302Z] 10:04:37     INFO - Passed:  280
[task 2024-12-30T10:04:37.302Z] 10:04:37     INFO - Failed:  0
[task 2024-12-30T10:04:37.302Z] 10:04:37     INFO - Todo:    0
[task 2024-12-30T10:04:37.310Z] 10:04:37     INFO - Mode:    e10s
[task 2024-12-30T10:04:37.310Z] 10:04:37     INFO - Slowest: 3157ms - /tests/dom/tests/mochitest/localstorage/test_localStorageReplace.html
[task 2024-12-30T10:04:37.311Z] 10:04:37     INFO - SimpleTest FINISHED
[task 2024-12-30T10:04:37.312Z] 10:04:37     INFO - TEST-INFO | Ran 1 Loops
[task 2024-12-30T10:04:37.312Z] 10:04:37     INFO - SimpleTest FINISHED
[task 2024-12-30T10:04:41.921Z] 10:04:41     INFO - GECKO(6584) | [Parent 6584, IPDL Background] WARNING: IPC Connection Error: [Parent][PBackgroundLSDatabaseParent] Send(msgname=PBackgroundLSSnapshot::Msg___delete__) Channel closing: too late to send, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1846
[task 2024-12-30T10:04:41.921Z] 10:04:41     INFO - GECKO(6584) | [WARN  glean_core::error_recording] localstorage.database.request_allow_to_close_response_time: Timing not running
[task 2024-12-30T10:04:42.694Z] 10:04:42     INFO - GECKO(6584) | [Child 6740, Main Thread] WARNING: HangMonitorChild::InterruptCallback: ExpectingShutdown, canceling content JS execution.
[task 2024-12-30T10:04:42.694Z] 10:04:42     INFO - GECKO(6584) | : file /builds/worker/checkouts/gecko/dom/ipc/ProcessHangMonitor.cpp:395
[task 2024-12-30T10:04:42.695Z] 10:04:42     INFO - GECKO(6584) | JavaScript warning: http://mochi.test:8888/tests/SimpleTest/TestRunner.js, line 257: Script terminated by timeout at:
[task 2024-12-30T10:04:42.696Z] 10:04:42     INFO - GECKO(6584) | TestRunner._checkForHangs@http://mochi.test:8888/tests/SimpleTest/TestRunner.js:257:1
[task 2024-12-30T10:04:42.698Z] 10:04:42     INFO - GECKO(6584) | setTimeout handler*TestRunner._checkForHangs@http://mochi.test:8888/tests/SimpleTest/TestRunner.js:255:15
[task 2024-12-30T10:04:42.699Z] 10:04:42     INFO - GECKO(6584) | TestRunner.runTests/<@http://mochi.test:8888/tests/SimpleTest/TestRunner.js:535:16
[task 2024-12-30T10:04:42.699Z] 10:04:42     INFO - GECKO(6584) | Async*TestRunner.runTests@http://mochi.test:8888/tests/SimpleTest/TestRunner.js:522:48
[task 2024-12-30T10:04:42.700Z] 10:04:42     INFO - GECKO(6584) | RunSet.runtests@http://mochi.test:8888/tests/SimpleTest/setup.js:302:14
[task 2024-12-30T10:04:42.702Z] 10:04:42     INFO - GECKO(6584) | RunSet.runall@http://mochi.test:8888/tests/SimpleTest/setup.js:281:12
[task 2024-12-30T10:04:42.703Z] 10:04:42     INFO - GECKO(6584) | getPrefList/<@http://mochi.test:8888/tests/SimpleTest/setup.js:368:14
[task 2024-12-30T10:04:42.706Z] 10:04:42     INFO - GECKO(6584) | loadFile/req.onload@http://mochi.test:8888/tests/SimpleTest/setup.js:80:19
[task 2024-12-30T10:04:42.707Z] 10:04:42     INFO - GECKO(6584) | EventHandlerNonNull*loadFile@http://mochi.test:8888/tests/SimpleTest/setup.js:75:3
[task 2024-12-30T10:04:42.707Z] 10:04:42     INFO - GECKO(6584) | getPrefList@http://mochi.test:8888/tests/SimpleTest/setup.js:366:13
[task 2024-12-30T10:04:42.708Z] 10:04:42     INFO - GECKO(6584) | hookupTests@http://mochi.test:8888/tests/SimpleTest/setup.js:389:5
[task 2024-12-30T10:04:42.708Z] 10:04:42     INFO - GECKO(6584) | parseTestManifest@http://mochi.test:8888/manifestLibrary.js:53:13
[task 2024-12-30T10:04:42.709Z] 10:04:42     INFO - GECKO(6584) | getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:66:28
[task 2024-12-30T10:04:42.710Z] 10:04:42     INFO - GECKO(6584) | EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:62:3
[task 2024-12-30T10:04:42.710Z] 10:04:42     INFO - GECKO(6584) | hookup@http://mochi.test:8888/tests/SimpleTest/setup.js:354:20
[task 2024-12-30T10:04:42.711Z] 10:04:42     INFO - GECKO(6584) | EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true&ignorePrefsFile=ignorePrefs.json:10:32
[task 2024-12-30T10:10:34.866Z] 10:10:34     INFO - TEST-UNEXPECTED-TIMEOUT | dom/tests/mochitest/localstorage/test_storageConstructor.html (finished) | application timed out after 370 seconds with no output
[task 2024-12-30T10:10:34.867Z] 10:10:34     INFO - TEST-INFO 
[task 2024-12-30T10:10:34.869Z] 10:10:34     INFO - Buffered messages finished
[task 2024-12-30T10:10:34.869Z] 10:10:34  WARNING - Force-terminating active process(es).
[task 2024-12-30T10:10:34.869Z] 10:10:34  WARNING - profiler Attempting to start the profiler to help with diagnosing the hang.
[task 2024-12-30T10:10:34.870Z] 10:10:34     INFO - profiler Sending SIGUSR1 to pid 6584 start the profiler.
[task 2024-12-30T10:10:34.870Z] 10:10:34     INFO - profiler Waiting 10s to capture a profile...
[task 2024-12-30T10:10:44.878Z] 10:10:44     INFO - profiler Sending SIGUSR2 to pid 6584 stop the profiler.
[task 2024-12-30T10:10:44.878Z] 10:10:44     INFO - profiler Wait 10s for Firefox to write the profile to disk.
[task 2024-12-30T10:10:54.882Z] 10:10:54     INFO - profiler Symbolicating profile in /home/ubuntu/tasks/task_173554720312790/build/blobber_upload_dir
[task 2024-12-30T10:10:54.882Z] 10:10:54     INFO - profiler Looking inside symbols dir: /home/ubuntu/tasks/task_173554720312790/build/symbols)
[task 2024-12-30T10:10:54.882Z] 10:10:54     INFO - profiler Symbolicating profile: /home/ubuntu/tasks/task_173554720312790/build/blobber_upload_dir/profile_0_6584.json
[task 2024-12-30T10:10:54.884Z] 10:10:54     INFO - profiler Symbolicating the performance profile... This could take a couple of minutes.
[task 2024-12-30T10:11:17.148Z] 10:11:17     INFO - Determining child pids from psutil...
[task 2024-12-30T10:11:17.165Z] 10:11:17     INFO - [6650]
[task 2024-12-30T10:11:17.165Z] 10:11:17     INFO - ==> process 6584 launched child process 6650
[task 2024-12-30T10:11:17.165Z] 10:11:17     INFO - Found child pids: {6650}
[task 2024-12-30T10:11:17.165Z] 10:11:17     INFO - Killing process: 6650
[task 2024-12-30T10:11:17.167Z] 10:11:17     INFO - TEST-INFO | started process screentopng
[task 2024-12-30T10:11:19.456Z] 10:11:19     INFO - TEST-INFO | screentopng: exit 0
[task 2024-12-30T10:11:47.985Z] 10:11:47  WARNING - failed to kill pid 6650 after 30s
[task 2024-12-30T10:11:47.985Z] 10:11:47     INFO - Killing process: 6584
[task 2024-12-30T10:11:47.985Z] 10:11:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-12-30T10:11:48.295Z] 10:11:48     INFO - psutil found pid 6584 dead
[task 2024-12-30T10:11:48.302Z] 10:11:48     INFO - TEST-INFO | Main app process: exit 6
[task 2024-12-30T10:11:48.302Z] 10:11:48     INFO - Buffered messages finished
[task 2024-12-30T10:11:48.302Z] 10:11:48     INFO - runtests.py | Application ran for: 0:07:50.142694
[task 2024-12-30T10:11:48.302Z] 10:11:48     INFO - zombiecheck | Reading PID log: /tmp/tmpt8n8u66ypidlog
[task 2024-12-30T10:11:48.302Z] 10:11:48     INFO - ==> process 6584 launched child process 6650
[task 2024-12-30T10:11:48.302Z] 10:11:48     INFO - zombiecheck | Checking for orphan process with PID: 6650
[task 2024-12-30T10:11:48.303Z] 10:11:48     INFO - mozcrash Copy/paste: /home/ubuntu/tasks/task_173554720312790/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --cyborg=/tmp/tmp3k7_pumb/795b4fcc-428b-fc90-8890-f17022cb3d0c.trace /tmp/tmp6v0nn3sz.mozrunner/minidumps/795b4fcc-428b-fc90-8890-f17022cb3d0c.dmp /home/ubuntu/tasks/task_173554720312790/build/symbols
[task 2024-12-30T10:12:03.917Z] 10:12:03     INFO - mozcrash Saved minidump as /home/ubuntu/tasks/task_173554720312790/build/blobber_upload_dir/795b4fcc-428b-fc90-8890-f17022cb3d0c.dmp
[task 2024-12-30T10:12:03.919Z] 10:12:03     INFO - mozcrash Saved app info as /home/ubuntu/tasks/task_173554720312790/build/blobber_upload_dir/795b4fcc-428b-fc90-8890-f17022cb3d0c.extra
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - PROCESS-CRASH | application crashed [@ __futex_abstimed_wait_common64] | dom/tests/mochitest/localstorage/test_storageConstructor.html (finished) 
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Process type: main
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Process pid: 6584
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Crash dump filename: /tmp/tmp6v0nn3sz.mozrunner/minidumps/795b4fcc-428b-fc90-8890-f17022cb3d0c.dmp
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Operating system: Linux
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -                   5.15.0-70-generic #77-Ubuntu SMP Tue Mar 21 14:02:37 UTC 2023
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - CPU: amd64
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      family 6 model 85 stepping 7
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      2 CPUs
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Linux Ubuntu 22.04 - jammy (Ubuntu 22.04.2 LTS)
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - 
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Crash reason:  SIGABRT
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Crash address: 0x000003e800000ef8
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Crashing instruction: `mov edi, r12d`
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - No memory accessed by instruction
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - No instruction pointer update by instruction
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Process uptime: not available
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - 
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Linux memory map count: 2203
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - 
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO - Thread 0 firefox-bin (crashed) - tid: 6584
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -  0  libc.so.6!__futex_abstimed_wait_common64 [futex-internal.c : 57]
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -     Found by: inlining
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -  1  libc.so.6!__futex_abstimed_wait_common [futex-internal.c : 87]
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -     Found by: inlining
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -  2  libc.so.6!__futex_abstimed_wait_cancelable64 [futex-internal.c : 139 + 0x2e]
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      rax = 0xfffffffffffffffc    rdx = 0x0000000000000000
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      rcx = 0x00007f09c5c50197    rbx = 0x0000000000000000
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      rsi = 0x0000000000000189    rdi = 0x00007f099279a1f0
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      rbp = 0x00007f099279a1c8    rsp = 0x00007ffcc9230140
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -       r8 = 0x0000000000000000     r9 = 0x00000000ffffffff
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      r10 = 0x0000000000000000    r11 = 0x0000000000000246
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      r12 = 0x0000000000000000    r13 = 0x0000000000000000
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      r14 = 0x0000000000000018    r15 = 0x00007f099279a1f0
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      rip = 0x00007f09c5c50197
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -     Found by: given as instruction pointer in context
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -  3  libc.so.6!__pthread_cond_wait_common [pthread_cond_wait.c : 503]
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -     Found by: inlining
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -  4  libc.so.6!__GI___pthread_cond_wait [pthread_cond_wait.c : 627 + 0xd0]
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      rbx = 0x0000000000000000    rbp = 0x00007f099279a1c8
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      rsp = 0x00007ffcc9230180    r12 = 0x00007f099279a1ec
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      r13 = 0x00007f099279a160    r14 = 0x0000000000000018
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -      r15 = 0x00007f099279a1f0    rip = 0x00007f09c5c52ac1
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -     Found by: call frame info
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -  5  firefox-bin!mozilla::detail::ConditionVariableImpl::wait(mozilla::detail::MutexImpl&) [ConditionVariable_posix.cpp:587ca662c33db8196caf8a993f9b406fe26e77e9 : 106]
[task 2024-12-30T10:12:04.515Z] 10:12:04     INFO -     Found by: inlining
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -  6  firefox-bin!mozilla::detail::ConditionVariableImpl::wait_for(mozilla::detail::MutexImpl&, mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&) [ConditionVariable_posix.cpp:587ca662c33db8196caf8a993f9b406fe26e77e9 : 113 + 0xa]
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -      rbx = 0x00007f099279a1c8    rbp = 0x00007ffcc92302b0
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -      rsp = 0x00007ffcc9230260    r12 = 0x7fffffffffffffff
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -      r13 = 0x7fffffffffffffff    r14 = 0x00007f099279a160
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -      r15 = 0x00007ffcc92302c8    rip = 0x0000564356e8abcc
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -     Found by: call frame info
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -  7  libxul.so!mozilla::OffTheBooksCondVar::Wait(mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator>) [BlockingResourceBase.cpp:587ca662c33db8196caf8a993f9b406fe26e77e9 : 568 + 0x10]
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -      rbx = 0x00007f099279a1a8    rbp = 0x00007ffcc9230300
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -      rsp = 0x00007ffcc92302c0    r12 = 0x0000000000000000
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -      r13 = 0x00007f09c592ddc0    r14 = 0x00007f09c592ddc0
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -      r15 = 0x0000000000000001    rip = 0x00007f09b6fee888
[task 2024-12-30T10:12:04.516Z] 10:12:04     INFO -     Found by: call frame info
<...>
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - 0x7f09c613b000 - 0x7f09c6171fff  ld-linux-x86-64.so.2  2.0.0.0
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - 0x7f09c6172000 - 0x7f09c6172fff  user  0.0.0.0
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - 0x7ffcc9348000 - 0x7ffcc9349fff  linux-gate.so  0.0.0.0
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - 
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - Unloaded modules:
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - 
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - Unimplemented streams encountered:
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - Stream 0x47670006 LinuxCmdLine (Google Extension) @ 0x00115440
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - Stream 0x47670008 LinuxAuxv (Google Extension) @ 0x00116208
[task 2024-12-30T10:12:03.560Z] 10:12:03     INFO - Stream 0x4767000a LinuxDsoDebug (Google Extension) @ 0x00143ad0
[task 2024-12-30T10:12:03.561Z] 10:12:03     INFO - Stopping web server
[task 2024-12-30T10:12:03.561Z] 10:12:03     INFO - Server shut down.
[task 2024-12-30T10:12:03.561Z] 10:12:03     INFO - Web server killed.
[task 2024-12-30T10:12:03.562Z] 10:12:03     INFO - Stopping web socket server
[task 2024-12-30T10:12:03.562Z] 10:12:03     INFO - Stopping ssltunnel
[task 2024-12-30T10:12:03.562Z] 10:12:03     INFO - Stopping gst for v4l2loopback
[task 2024-12-30T10:12:03.562Z] 10:12:03     INFO - runtests.py | Running tests: end.
[task 2024-12-30T10:12:03.562Z] 10:12:03     INFO - Buffered messages finished
[task 2024-12-30T10:12:03.563Z] 10:12:03     INFO - Running manifest: dom/xml/test/mochitest.toml
[task 2024-12-30T10:12:04.172Z] 10:12:04     INFO -  Setting pipeline to PAUSED ...
[task 2024-12-30T10:12:04.172Z] 10:12:04     INFO -  Pipeline is PREROLLING ...
[task 2024-12-30T10:12:04.181Z] 10:12:04     INFO -  Pipeline is PREROLLED ...
[task 2024-12-30T10:12:04.181Z] 10:12:04     INFO -  Setting pipeline to PLAYING ...
[task 2024-12-30T10:12:04.184Z] 10:12:04     INFO -  New clock: GstSystemClock
[task 2024-12-30T10:12:04.184Z] 10:12:04     INFO -  Redistribute latency...
[task 2024-12-30T10:12:04.220Z] 10:12:04     INFO -  Got EOS from element "pipeline0".
[task 2024-12-30T10:12:04.224Z] 10:12:04     INFO -  Execution ended after 0:00:00.038527470
[task 2024-12-30T10:12:04.224Z] 10:12:04     INFO -  Setting pipeline to NULL ...
[task 2024-12-30T10:12:04.780Z] 10:12:04     INFO - PID 7647 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2024-12-30T10:12:04.780Z] 10:12:04     INFO - 
[task 2024-12-30T10:12:05.021Z] 10:12:05     INFO - MochitestServer : launching ['/home/ubuntu/tasks/task_173554720312790/build/tests/bin/xpcshell', '-g', '/home/ubuntu/tasks/task_173554720312790/build/application/firefox', '-e', "const _PROFILE_PATH = '/tmp/tmps3y_r9nm.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false; const _HTTPD_PATH = '/home/ubuntu/tasks/task_173554720312790/build/tests/bin/components';", '-f', '/home/ubuntu/tasks/task_173554720312790/build/tests/mochitest/server.js']
[task 2024-12-30T10:12:05.021Z] 10:12:05     INFO - runtests.py | Server pid: 7653
[task 2024-12-30T10:12:05.021Z] 10:12:05     INFO - runtests.py | Websocket server pid: 7654
[task 2024-12-30T10:12:05.028Z] 10:12:05     INFO - runtests.py | SSL tunnel pid: 7655
[task 2024-12-30T10:12:05.405Z] 10:12:05     INFO -  Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2024-12-30T10:12:05.408Z] 10:12:05     INFO -  [Parent 7653, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:478
[task 2024-12-30T10:12:05.447Z] 10:12:05     INFO - use http3 server: 0
[task 2024-12-30T10:12:05.447Z] 10:12:05     INFO - runtests.py | Running with scheme: http
[task 2024-12-30T10:12:05.447Z] 10:12:05     INFO - runtests.py | Running with e10s: True
[task 2024-12-30T10:12:05.447Z] 10:12:05     INFO - runtests.py | Running with fission: True
[task 2024-12-30T10:12:05.447Z] 10:12:05     INFO - runtests.py | Running with cross-origin iframes: False
[task 2024-12-30T10:12:05.447Z] 10:12:05     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2024-12-30T10:12:05.447Z] 10:12:05     INFO - runtests.py | Running tests: start.
[task 2024-12-30T10:12:05.447Z] 10:12:05     INFO - 
[task 2024-12-30T10:12:05.481Z] 10:12:05     INFO - Application command: /home/ubuntu/tasks/task_173554720312790/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmps3y_r9nm.mozrunner
[task 2024-12-30T10:12:05.513Z] 10:12:05     INFO - runtests.py | Application pid: 7678
[task 2024-12-30T10:12:05.516Z] 10:12:05     INFO - TEST-INFO | started process GECKO(7678)
[task 2024-12-30T10:12:05.604Z] 10:12:05     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks.log
[task 2024-12-30T10:12:06.607Z] 10:12:06     INFO - GECKO(7678) | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-12-30T10:12:07.294Z] 10:12:07     INFO - GECKO(7678) | Initializing context 7fa42d40ce40 surface 0 on display 7fa43aafb100
[task 2024-12-30T10:12:07.300Z] 10:12:07     INFO - GECKO(7678) | GL_VENDOR: Mesa
[task 2024-12-30T10:12:07.301Z] 10:12:07     INFO - GECKO(7678) | mVendor: Unknown
[task 2024-12-30T10:12:07.301Z] 10:12:07     INFO - GECKO(7678) | GL_RENDERER: llvmpipe (LLVM 15.0.7, 256 bits)
[task 2024-12-30T10:12:07.301Z] 10:12:07     INFO - GECKO(7678) | mRenderer: Unknown
[task 2024-12-30T10:12:07.301Z] 10:12:07     INFO - GECKO(7678) | mIsMesa: 1
[task 2024-12-30T10:12:07.301Z] 10:12:07     INFO - GECKO(7678) | [Parent 7678, Renderer] WARNING: robust_buffer_access_behavior marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:644
[task 2024-12-30T10:12:07.356Z] 10:12:07     INFO - GECKO(7678) | 1735553527353	Marionette	INFO	Marionette enabled
[task 2024-12-30T10:12:07.364Z] 10:12:07     INFO - GECKO(7678) | 1735553527361	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-12-30T10:12:07.544Z] 10:12:07     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_forkserver_pid7743.log
[task 2024-12-30T10:12:07.557Z] 10:12:07     INFO - GECKO(7678) | console.error: "Warning: unrecognized command line flag" "-foreground"
[task 2024-12-30T10:12:07.721Z] 10:12:07     INFO - GECKO(7678) | 1735553527720	Marionette	INFO	Listening on port 2828
[task 2024-12-30T10:12:07.732Z] 10:12:07     INFO - GECKO(7678) | 1735553527728	Marionette	DEBUG	Marionette is listening
[task 2024-12-30T10:12:07.848Z] 10:12:07     INFO - GECKO(7678) | 1735553527844	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:42684
[task 2024-12-30T10:12:07.969Z] 10:12:07     INFO - GECKO(7678) | 1735553527968	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:42694
[task 2024-12-30T10:12:07.972Z] 10:12:07     INFO - GECKO(7678) | 1735553527969	Marionette	DEBUG	Closed connection 0
[task 2024-12-30T10:12:08.117Z] 10:12:08     INFO - GECKO(7678) | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-12-30T10:12:08.518Z] 10:12:08     INFO - GECKO(7678) | 1735553528514	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-12-30T10:12:08.532Z] 10:12:08     INFO - GECKO(7678) | 1735553528530	Marionette	DEBUG	Waiting for initial application window
[task 2024-12-30T10:12:08.632Z] 10:12:08     INFO - GECKO(7678) | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-12-30T10:12:08.792Z] 10:12:08     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_tab_pid7755.log
[task 2024-12-30T10:12:08.797Z] 10:12:08     INFO - GECKO(7678) | [Parent 7678, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1888
[task 2024-12-30T10:12:08.820Z] 10:12:08     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_rdd_pid7765.log
[task 2024-12-30T10:12:09.371Z] 10:12:09     INFO - GECKO(7678) | [Parent 7678, Main Thread] WARNING: Please do not use mouseenter/leave events in chrome. They are slower than mouseover/out!: '!nsContentUtils::IsChromeDoc(window->GetExtantDoc())', file /builds/worker/checkouts/gecko/dom/events/EventListenerManager.cpp:462
[task 2024-12-30T10:12:09.570Z] 10:12:09     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_tab_pid7795.log
[task 2024-12-30T10:12:09.580Z] 10:12:09     INFO - GECKO(7678) | [WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2024-12-30T10:12:10.242Z] 10:12:10     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_tab_pid7822.log
[task 2024-12-30T10:12:10.540Z] 10:12:10     INFO - GECKO(7678) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2024-12-30T10:12:10.542Z] 10:12:10     INFO - GECKO(7678) | [Child 7822, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:478
[task 2024-12-30T10:12:11.756Z] 10:12:11     INFO - GECKO(7678) | console.error: ({})
[task 2024-12-30T10:12:11.973Z] 10:12:11     INFO - GECKO(7678) | [Parent 7678, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1670
[task 2024-12-30T10:12:12.622Z] 10:12:12     INFO - GECKO(7678) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-12-30T10:12:12.624Z] 10:12:12     INFO - GECKO(7678) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-12-30T10:12:12.624Z] 10:12:12     INFO - GECKO(7678) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-12-30T10:12:14.142Z] 10:12:14     INFO - GECKO(7678) | [Parent 7678, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1215
[task 2024-12-30T10:12:15.872Z] 10:12:15     INFO - GECKO(7678) | [Parent 7678, sqldb:places.sqlite #2] WARNING: Suboptimal indexes for the SQL statement 0x7fa41a364480 (http://mzl.la/1FuID0j).: file /builds/worker/checkouts/gecko/storage/mozStoragePrivateHelpers.cpp:114
[task 2024-12-30T10:12:15.889Z] 10:12:15     INFO - GECKO(7678) | [Parent 7678, sqldb:places.sqlite #2] WARNING: Suboptimal indexes for the SQL statement 0x7fa41a364480 (http://mzl.la/1FuID0j).: file /builds/worker/checkouts/gecko/storage/mozStoragePrivateHelpers.cpp:114
[task 2024-12-30T10:12:15.984Z] 10:12:15     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_tab_pid7856.log
[task 2024-12-30T10:12:16.117Z] 10:12:16     INFO - GECKO(7678) | [Parent 7678, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2024-12-30T10:12:16.644Z] 10:12:16     INFO - GECKO(7678) | [Parent 7678, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2024-12-30T10:12:17.656Z] 10:12:17     INFO - GECKO(7678) | [Parent 7678, Main Thread] WARNING: Ignoring duplicate observer: browser.shopping.experience2023.optedIn: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:2870
[task 2024-12-30T10:12:17.985Z] 10:12:17     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_utility_pid7889.log
[task 2024-12-30T10:12:18.196Z] 10:12:18     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_tab_pid7897.log
[task 2024-12-30T10:12:18.569Z] 10:12:18     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_tab_pid7917.log
[task 2024-12-30T10:12:18.753Z] 10:12:18     INFO - GECKO(7678) | 1735553538751	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2024-12-30T10:12:18.801Z] 10:12:18     INFO - GECKO(7678) | 1735553538794	RemoteAgent	TRACE	[9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=40000 waitForExplicitStart=false
[task 2024-12-30T10:12:18.812Z] 10:12:18     INFO - GECKO(7678) | 1735553538809	RemoteAgent	TRACE	[9] ProgressListener Setting unload timer (40000ms)
[task 2024-12-30T10:12:18.817Z] 10:12:18     INFO - GECKO(7678) | 1735553538815	RemoteAgent	TRACE	[9] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-12-30T10:12:18.824Z] 10:12:18     INFO - GECKO(7678) | 1735553538822	RemoteAgent	TRACE	[9] Document already finished loading: about:blank
[task 2024-12-30T10:12:18.825Z] 10:12:18     INFO - GECKO(7678) | 1735553538822	RemoteAgent	TRACE	[9] ProgressListener Stop: has error=false url=about:blank
[task 2024-12-30T10:12:18.876Z] 10:12:18     INFO - GECKO(7678) | 1735553538870	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"fd336b19-0832-4943-96c0-d6556e0b6b24","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"135.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0","moz:buildID":"20241230092810","moz:headless":false,"moz:platformVersion":"5.15.0-70-generic","moz:processID":7678,"moz:profile":"/tmp/tmps3y_r9nm.mozrunner","moz:shutdownTimeout":60000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"setWindowRect":true,"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2024-12-30T10:12:18.984Z] 10:12:18     INFO - GECKO(7678) | 1735553538978	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpkpvdnqe2.zip","temporary":false}]
[task 2024-12-30T10:12:20.149Z] 10:12:20     INFO - GECKO(7678) | 1735553540148	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2024-12-30T10:12:20.280Z] 10:12:20     INFO - GECKO(7678) | 1735553540278	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmp_wfae_kc.zip","temporary":false}]
[task 2024-12-30T10:12:20.460Z] 10:12:20     INFO - GECKO(7678) | 1735553540457	Marionette	DEBUG	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2024-12-30T10:12:20.481Z] 10:12:20     INFO - GECKO(7678) | 1735553540478	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
[task 2024-12-30T10:12:20.481Z] 10:12:20     INFO - GECKO(7678) | 1735553540479	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
[task 2024-12-30T10:12:20.485Z] 10:12:20     INFO - GECKO(7678) | 1735553540484	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-12-30T10:12:20.488Z] 10:12:20     INFO - GECKO(7678) | 1735553540485	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2024-12-30T10:12:20.499Z] 10:12:20     INFO - GECKO(7678) | 1735553540495	Marionette	DEBUG	1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, v. 2.0. If a copy of the MPL was not distr ... s which flavor and url to load.\nlet ev = new CustomEvent(\"mochitest-load\", { detail: [flavor, url] });\nwin.dispatchEvent(ev);","args":[{"flavor":"mochitest","testUrl":"http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true&ignorePrefsFile=ignorePrefs.json"}],"newSandbox":true,"sandbox":"default","line":2159,"filename":"tests/mochitest/runtests.py"}]
[task 2024-12-30T10:12:20.530Z] 10:12:20     INFO - GECKO(7678) | 1735553540528	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 7678
[task 2024-12-30T10:12:20.533Z] 10:12:20     INFO - GECKO(7678) | 1735553540532	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2024-12-30T10:12:20.619Z] 10:12:20     INFO - GECKO(7678) | 1735553540617	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2024-12-30T10:12:20.751Z] 10:12:20     INFO - GECKO(7678) | 1735553540748	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2024-12-30T10:12:20.763Z] 10:12:20     INFO - GECKO(7678) | 1735553540752	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2024-12-30T10:12:20.905Z] 10:12:20     INFO - GECKO(7678) | 1735553540903	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2024-12-30T10:12:20.930Z] 10:12:20     INFO - GECKO(7678) | 1735553540928	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2024-12-30T10:12:20.953Z] 10:12:20     INFO - GECKO(7678) | 1735553540952	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2024-12-30T10:12:21.241Z] 10:12:21     INFO - runtests.py | Waiting for browser...
[task 2024-12-30T10:12:21.437Z] 10:12:21     INFO - GECKO(7678) | 1735553541436	Marionette	DEBUG	Closed connection 1
[task 2024-12-30T10:12:21.536Z] 10:12:21     INFO - GECKO(7678) | [Child 7917, Main Thread] WARNING: 'presShell->NeedLayoutFlush()', file /builds/worker/checkouts/gecko/layout/base/nsRefreshDriver.cpp:2218
[task 2024-12-30T10:12:21.587Z] 10:12:21     INFO - GECKO(7678) | [Child 7822, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, NS_ERROR_INVALID_ARG) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/intl/l10n/L10nRegistry.cpp:385
[task 2024-12-30T10:12:22.059Z] 10:12:22     INFO - GECKO(7678) | [Parent 7678, IPC I/O Parent] WARNING: [1.1]: GetUserData call for port 'F83AB80C260421C.7FDF0035C3191FB6' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:424
[task 2024-12-30T10:12:22.629Z] 10:12:22     INFO - GECKO(7678) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmps3y_r9nm.mozrunner/runtests_leaks_tab_pid7962.log
[task 2024-12-30T10:12:23.420Z] 10:12:23     INFO - GECKO(7678) | [Parent 7678, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1159
[task 2024-12-30T10:12:23.722Z] 10:12:23     INFO - SimpleTest START
[task 2024-12-30T10:12:23.726Z] 10:12:23     INFO - Dumping test context:
[task 2024-12-30T10:12:23.726Z] 10:12:23     INFO -   fission.autostart=true
[task 2024-12-30T10:12:23.764Z] 10:12:23     INFO - TEST-START | dom/xml/test/test_bug232004.xhtml
Status: NEW → RESOLVED
Closed: 19 days ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.