Closed
Bug 1733604
Opened 4 years ago
Closed 4 years ago
Intermittent TV browser/components/places/tests/browser/browser_bookmarkProperties_editFolder.js | Test timed out -
Categories
(Firefox :: Bookmarks & History, defect, P5)
Firefox
Bookmarks & History
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=353348527&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NhusKh52SF2a37KvvPKZAA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NhusKh52SF2a37KvvPKZAA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2021-10-01T12:51:51.985Z] 12:51:51 INFO - TEST-PASS | browser/components/places/tests/browser/browser_bookmarkProperties_editFolder.js | 'placesCmd_show:info' on folder is enabled - true == true -
[task 2021-10-01T12:51:51.985Z] 12:51:51 INFO - withBookmarksDialog: opening the dialog
[task 2021-10-01T12:51:51.985Z] 12:51:51 INFO - withBookmarksDialog: waiting for the dialog
[task 2021-10-01T12:51:51.985Z] 12:51:51 INFO - Console message: Invalid chrome URI (need path): chrome://browser/
[task 2021-10-01T12:51:51.985Z] 12:51:51 INFO - Buffered messages finished
[task 2021-10-01T12:51:51.985Z] 12:51:51 INFO - TEST-UNEXPECTED-FAIL | browser/components/places/tests/browser/browser_bookmarkProperties_editFolder.js | Test timed out -
[task 2021-10-01T12:51:51.985Z] 12:51:51 INFO - GECKO(1949) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-10-01T12:51:51.986Z] 12:51:51 INFO - GECKO(1949) | MEMORY STAT | vsize 3295MB | residentFast 541MB | heapAllocated 260MB
[task 2021-10-01T12:51:51.986Z] 12:51:51 INFO - TEST-OK | browser/components/places/tests/browser/browser_bookmarkProperties_editFolder.js | took 90103ms
[task 2021-10-01T12:51:51.986Z] 12:51:51 INFO - GECKO(1949) | [Child 2023: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f14f363e400 == 1 [pid = 2023] [id = 2]
[task 2021-10-01T12:51:51.987Z] 12:51:51 INFO - GECKO(1949) | [Child 2023: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f150de64580) [pid = 2023] [serial = 7] [outer = 0]
[task 2021-10-01T12:51:51.987Z] 12:51:51 INFO - GECKO(1949) | [Child 2023: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f14f363f800) [pid = 2023] [serial = 8] [outer = 7f150de64580]
[task 2021-10-01T12:51:51.988Z] 12:51:51 INFO - GECKO(1949) | [Child 2023: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (7f14f3645800) [pid = 2023] [serial = 9] [outer = 7f150de64580]
[task 2021-10-01T12:51:51.988Z] 12:51:51 INFO - GECKO(1949) | JavaScript error: chrome://browser/content/tabbrowser-tabs.js, line 1123: TypeError: can't access property "closing", selectedTab is null
[task 2021-10-01T12:51:51.989Z] 12:51:51 INFO - Console message: [JavaScript Error: "TypeError: can't access property "closing", selectedTab is null" {file: "chrome://browser/content/tabbrowser-tabs.js" line: 1123}]
[task 2021-10-01T12:52:15.888Z] 12:52:15 INFO - GECKO(1949) | [Child 2023: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f14f363f800) [pid = 2023] [serial = 8] [outer = 0] [url = about:blank]
[task 2021-10-01T12:56:42.360Z] 12:56:42 INFO - Console message: [JavaScript Warning: "telemetry.state_file_read_errors - Unknown scalar."]
[task 2021-10-01T12:56:42.361Z] 12:56:42 INFO - Console message: [JavaScript Warning: "telemetry.generated_new_client_id - Unknown scalar."]
[task 2021-10-01T12:58:42.360Z] 12:58:42 INFO - GECKO(1949) | 1633093122358 addons.xpi ERROR System addon update list error Error: got node name: html, expected: updates
[task 2021-10-01T12:58:42.365Z] 12:58:42 INFO - Console message: [JavaScript Error: "1633093122358 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-10-01T12:58:42.365Z] 12:58:42 INFO - append@resource://gre/modules/Log.jsm:723:12
[task 2021-10-01T12:58:42.365Z] 12:58:42 INFO - log@resource://gre/modules/Log.jsm:379:16
[task 2021-10-01T12:58:42.366Z] 12:58:42 INFO - error@resource://gre/modules/Log.jsm:387:10
[task 2021-10-01T12:58:42.366Z] 12:58:42 INFO - updateSystemAddons/res<@resource://gre/modules/addons/XPIInstall.jsm:4042:25
[task 2021-10-01T12:58:42.366Z] 12:58:42 INFO -
[task 2021-10-01T13:04:52.374Z] 13:04:52 INFO - Buffered messages finished
[task 2021-10-01T13:04:52.376Z] 13:04:52 ERROR - TEST-UNEXPECTED-TIMEOUT | Last test finished | application timed out after 370 seconds with no output
[task 2021-10-01T13:04:52.377Z] 13:04:52 ERROR - Force-terminating active process(es).
[task 2021-10-01T13:04:52.378Z] 13:04:52 INFO - Determining child pids from psutil...
[task 2021-10-01T13:04:52.382Z] 13:04:52 INFO - [2023, 2042, 2073, 2077, 2088, 2192, 2212]
[task 2021-10-01T13:04:52.383Z] 13:04:52 INFO - ==> process 1949 launched child process 1967
[task 2021-10-01T13:04:52.383Z] 13:04:52 INFO - ==> process 1949 launched child process 2023
[task 2021-10-01T13:04:52.384Z] 13:04:52 INFO - ==> process 1949 launched child process 2042
[task 2021-10-01T13:04:52.384Z] 13:04:52 INFO - ==> process 1949 launched child process 2073
[task 2021-10-01T13:04:52.384Z] 13:04:52 INFO - ==> process 1949 launched child process 2077
[task 2021-10-01T13:04:52.385Z] 13:04:52 INFO - ==> process 1949 launched child process 2088
[task 2021-10-01T13:04:52.385Z] 13:04:52 INFO - ==> process 1949 launched child process 2192
[task 2021-10-01T13:04:52.386Z] 13:04:52 INFO - ==> process 1949 launched child process 2212
[task 2021-10-01T13:04:52.387Z] 13:04:52 INFO - Found child pids: {2212, 2023, 2088, 1967, 2192, 2073, 2042, 2077}
[task 2021-10-01T13:04:52.387Z] 13:04:52 INFO - Failed to get child procs
[task 2021-10-01T13:04:52.388Z] 13:04:52 INFO - Killing process: 2212
[task 2021-10-01T13:04:52.390Z] 13:04:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-01T13:04:52.390Z] 13:04:52 INFO - Killing process: 2023
[task 2021-10-01T13:04:52.391Z] 13:04:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-01T13:04:52.392Z] 13:04:52 INFO - Killing process: 2088
[task 2021-10-01T13:04:52.393Z] 13:04:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-01T13:04:52.394Z] 13:04:52 INFO - Killing process: 1967
[task 2021-10-01T13:04:52.394Z] 13:04:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-01T13:04:52.395Z] 13:04:52 INFO - Can't trigger Breakpad, process no longer exists
[task 2021-10-01T13:04:52.397Z] 13:04:52 INFO - Killing process: 2192
[task 2021-10-01T13:04:52.397Z] 13:04:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-01T13:04:52.397Z] 13:04:52 INFO - Killing process: 2073
[task 2021-10-01T13:04:52.397Z] 13:04:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-01T13:04:52.398Z] 13:04:52 INFO - Killing process: 2042
[task 2021-10-01T13:04:52.398Z] 13:04:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-01T13:04:52.398Z] 13:04:52 INFO - Killing process: 2077
[task 2021-10-01T13:04:52.398Z] 13:04:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-01T13:04:52.398Z] 13:04:52 INFO - Killing process: 1949
[task 2021-10-01T13:04:52.399Z] 13:04:52 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-10-01T13:04:52.540Z] 13:04:52 INFO - psutil found pid 1949 dead
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - TEST-INFO | Main app process: exit 0
[task 2021-10-01T13:04:52.565Z] 13:04:52 ERROR - TEST-UNEXPECTED-FAIL | ShutdownLeaks | process() called before end of test suite
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - TEST-INFO | Confirming we saw 16 DOCSHELL created and 3 destroyed log strings.
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - TEST-INFO | Confirming we saw 45 DOMWINDOW created and 19 destroyed log strings.
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - runtests.py | Application ran for: 0:14:41.412426
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - zombiecheck | Reading PID log: /tmp/tmpzsyrl7l0pidlog
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - ==> process 1949 launched child process 1967
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - ==> process 1949 launched child process 2023
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - ==> process 1949 launched child process 2042
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - ==> process 1949 launched child process 2073
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - ==> process 1949 launched child process 2077
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - ==> process 1949 launched child process 2088
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - ==> process 1949 launched child process 2192
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - ==> process 1949 launched child process 2212
[task 2021-10-01T13:04:52.565Z] 13:04:52 INFO - zombiecheck | Checking for orphan process with PID: 2212
[task 2021-10-01T13:04:52.568Z] 13:04:52 INFO - zombiecheck | Checking for orphan process with PID: 2023
[task 2021-10-01T13:04:52.568Z] 13:04:52 INFO - zombiecheck | Checking for orphan process with PID: 2088
[task 2021-10-01T13:04:52.568Z] 13:04:52 INFO - zombiecheck | Checking for orphan process with PID: 1967
[task 2021-10-01T13:04:52.568Z] 13:04:52 INFO - zombiecheck | Checking for orphan process with PID: 2192
[task 2021-10-01T13:04:52.568Z] 13:04:52 INFO - zombiecheck | Checking for orphan process with PID: 2073
[task 2021-10-01T13:04:52.568Z] 13:04:52 INFO - zombiecheck | Checking for orphan process with PID: 2042
[task 2021-10-01T13:04:52.568Z] 13:04:52 INFO - zombiecheck | Checking for orphan process with PID: 2077
[task 2021-10-01T13:04:52.568Z] 13:04:52 INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump_stackwalk/minidump_stackwalk /tmp/tmphkofeqf8.mozrunner/minidumps/503c9100-78ad-a923-96c3-192581e0ee68.dmp /builds/worker/workspace/build/symbols
[task 2021-10-01T13:04:52.582Z] 13:04:52 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/503c9100-78ad-a923-96c3-192581e0ee68.dmp
[task 2021-10-01T13:04:52.582Z] 13:04:52 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [None]
[task 2021-10-01T13:04:52.582Z] 13:04:52 INFO - Crash dump filename: /tmp/tmphkofeqf8.mozrunner/minidumps/503c9100-78ad-a923-96c3-192581e0ee68.dmp
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Operating system:
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - CPU:
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - 0 CPUs
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - GPU: UNKNOWN
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - No crash
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Process uptime: not available
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Thread 0 tid 2212
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Thread 1 tid 2216
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Thread 2 tid 2217
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Thread 3 tid 2220
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Thread 4 tid 2221
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Thread 5 tid 2222
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Thread 6 tid 2223
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Thread 7 tid 2224
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.583Z] 13:04:52 INFO - Thread 8 tid 2225
[task 2021-10-01T13:04:52.584Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.584Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.584Z] 13:04:52 INFO - Thread 9 tid 2226
[task 2021-10-01T13:04:52.584Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.584Z] 13:04:52 INFO -
[task 2021-10-01T13:04:52.584Z] 13:04:52 INFO - Thread 10 tid 2227
[task 2021-10-01T13:04:52.584Z] 13:04:52 INFO - <no frames>
[task 2021-10-01T13:04:52.584Z] 13:04:52 INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump_stackwalk/minidump_stackwalk /tmp/tmphkofeqf8.mozrunner/minidumps/3484db88-b1af-65ae-4359-92620105441f.dmp /builds/worker/workspace/build/symbols
[task 2021-10-01T13:04:58.284Z] 13:04:58 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/3484db88-b1af-65ae-4359-92620105441f.dmp
[task 2021-10-01T13:04:58.285Z] 13:04:58 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/3484db88-b1af-65ae-4359-92620105441f.extra
[task 2021-10-01T13:04:58.479Z] 13:04:58 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ mozilla::profiler::ThreadRegistration::GetTLS()]
[task 2021-10-01T13:04:58.480Z] 13:04:58 INFO - Crash dump filename: /tmp/tmphkofeqf8.mozrunner/minidumps/3484db88-b1af-65ae-4359-92620105441f.dmp
[task 2021-10-01T13:04:58.480Z] 13:04:58 INFO - Operating system: Linux
[task 2021-10-01T13:04:58.480Z] 13:04:58 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-10-01T13:04:58.480Z] 13:04:58 INFO - CPU: amd64
[task 2021-10-01T13:04:58.481Z] 13:04:58 INFO - family 6 model 85 stepping 7
[task 2021-10-01T13:04:58.481Z] 13:04:58 INFO - 2 CPUs
[task 2021-10-01T13:04:58.481Z] 13:04:58 INFO -
[task 2021-10-01T13:04:58.481Z] 13:04:58 INFO - GPU: UNKNOWN
[task 2021-10-01T13:04:58.481Z] 13:04:58 INFO -
[task 2021-10-01T13:04:58.481Z] 13:04:58 INFO - Crash reason: SIGABRT
[task 2021-10-01T13:04:58.481Z] 13:04:58 INFO - Crash address: 0x3e8000005d1
[task 2021-10-01T13:04:58.481Z] 13:04:58 INFO - Process uptime: not available
[task 2021-10-01T13:04:58.482Z] 13:04:58 INFO -
[task 2021-10-01T13:04:58.482Z] 13:04:58 INFO - Thread 0 tid 1949 (crashed) 0 libc.so.6 + 0x114cf9
[task 2021-10-01T13:04:58.482Z] 13:04:58 INFO - rax = 0xfffffffffffffffc rdx = 0x00000000ffffffff
[task 2021-10-01T13:04:58.483Z] 13:04:58 INFO - rcx = 0x00007f8810b20cf9 rbx = 0x00007f87f3042640
[task 2021-10-01T13:04:58.483Z] 13:04:58 INFO - rsi = 0x0000000000000003 rdi = 0x00007f87f3042640
[task 2021-10-01T13:04:58.483Z] 13:04:58 INFO - rbp = 0x0000000000000003 rsp = 0x00007fff31627050
[task 2021-10-01T13:04:58.483Z] 13:04:58 INFO - r8 = 0x0000000000000000 r9 = 0x00007f8811d620e0
[task 2021-10-01T13:04:58.484Z] 13:04:58 INFO - r10 = 0x00007f87f43b6ca0 r11 = 0x0000000000000293
[task 2021-10-01T13:04:58.484Z] 13:04:58 INFO - r12 = 0x00000000ffffffff r13 = 0x00007fff31627088
[task 2021-10-01T13:04:58.484Z] 13:04:58 INFO - r14 = 0x00000000ffffffff r15 = 0x0000000000000003
[task 2021-10-01T13:04:58.484Z] 13:04:58 INFO - rip = 0x00007f8810b20cf9
[task 2021-10-01T13:04:58.484Z] 13:04:58 INFO - Found by: given as instruction pointer in context
[task 2021-10-01T13:04:58.484Z] 13:04:58 INFO - 1 libxul.so!mozilla::profiler::ThreadRegistration::GetTLS() [ProfilerThreadRegistration.h:2d75255c3a30e11d80ef6285bfee2497d8e0662a : 353 + 0xc]
[task 2021-10-01T13:04:58.484Z] 13:04:58 INFO - rsp = 0x00007fff31627060 rip = 0x00007f87ffe7bc28
[task 2021-10-01T13:04:58.484Z] 13:04:58 INFO - Found by: stack scanning
[task 2021-10-01T13:04:58.485Z] 13:04:58 INFO - 2 libxul.so!PollWrapper(_GPollFD*, unsigned int, int) [nsAppShell.cpp:2d75255c3a30e11d80ef6285bfee2497d8e0662a : 58 + 0xf]
[task 2021-10-01T13:04:58.485Z] 13:04:58 INFO - rsp = 0x00007fff31627080 rip = 0x00007f8802ec2fd8
[task 2021-10-01T13:04:58.485Z] 13:04:58 INFO - Found by: stack scanning
[task 2021-10-01T13:04:58.485Z] 13:04:58 INFO - 3 libxul.so!nsCOMPtr<nsIPowerManagerService>::~nsCOMPtr() [nsCOMPtr.h:2d75255c3a30e11d80ef6285bfee2497d8e0662a : 453 + 0x7]
[task 2021-10-01T13:04:58.485Z] 13:04:58 INFO - rsp = 0x00007fff316270b8 rip = 0x00007f8802ec2f5a
[task 2021-10-01T13:04:58.485Z] 13:04:58 INFO - Found by: stack scanning
[task 2021-10-01T13:04:58.485Z] 13:04:58 INFO -
[task 2021-10-01T13:04:58.485Z] 13:04:58 INFO - Thread 1 tid 1955
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•