1469990 - Intermittent toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js | application crashed [unknown top frame]

Status: RESOLVED INCOMPLETE

(Toolkit :: Places, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: aiakab [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=184028691&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/ZV-0xAt8RYykJppVIXcs_w/runs/0/artifacts/public/logs/live_backing.log

19:00:16     INFO -  TEST-START | toolkit/components/places/tests/migration/test_current_from_v46.js
19:00:17     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-brh0bi',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:17     INFO -  Failed to remove directory: c:\users\task_1529518498\appdata\local\temp\xpc-plugins-eqnjsr. Waiting.
19:00:18     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-eqnjsr',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:19     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-eqnjsr',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:19     INFO -  Failed to remove directory: c:\users\task_1529518498\appdata\local\temp\xpc-plugins-brh0bi. Waiting.
19:00:20     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-eqnjsr',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:21     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-eqnjsr',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:23     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-eqnjsr',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:24     INFO -  TEST-PASS | toolkit/components/places/tests/maintenance/test_favicons_replaceOnStartup_clone.js | took 221850ms
19:00:24     INFO -  TEST-TIMEOUT | toolkit/components/places/tests/history/test_removeMany.js | took 300000ms
19:00:24     INFO -  TEST-TIMEOUT | toolkit/components/places/tests/history/test_removeVisits.js | took 300000ms
19:00:24     INFO -  mozcrash kill_pid(): wait failed (-1) terminating pid 8000: error 5
19:00:24     INFO -  xpcshell return code: None
19:00:24     INFO -  toolkit/components/places/tests/history/test_removeVisits.js | Process still running after test!
19:00:30     INFO -  TEST-TIMEOUT | toolkit/components/places/tests/history/test_removeByFilter.js | took 303856ms
19:00:30     INFO -  TEST-PASS | toolkit/components/places/tests/legacy/test_bookmarks_setNullTitle.js | took 275656ms
19:00:30     INFO -  mozcrash kill_pid(): wait failed (-1) terminating pid 11684: error 5
19:00:30     INFO -  TEST-PASS | toolkit/components/places/tests/history/test_update.js | took 275664ms
19:00:30     INFO -  xpcshell return code: None
19:00:30     INFO -  Failed to remove directory: c:\users\task_1529518498\appdata\local\temp\xpc-plugins-eqnjsr. Waiting.
19:00:30     INFO -  toolkit/components/places/tests/history/test_removeMany.js | Process still running after test!
19:00:30     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-brh0bi',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:40     INFO -  mozcrash kill_pid(): wait failed (-1) terminating pid 11784: error 5
19:00:40     INFO -  xpcshell return code: None
19:00:40     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-brh0bi',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:40     INFO -  toolkit/components/places/tests/history/test_removeByFilter.js | Process still running after test!
19:00:53     INFO -  TEST-TIMEOUT | toolkit/components/places/tests/history/test_removeVisitsByFilter.js | took 301137ms
19:00:53     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-eqnjsr',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:53     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-brh0bi',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:53     INFO -  mozcrash kill_pid(): wait failed (-1) terminating pid 8660: error 5
19:00:53     INFO -  xpcshell return code: None
19:00:53     INFO -  toolkit/components/places/tests/history/test_removeVisitsByFilter.js | Process still running after test!
19:00:53     INFO -  TEST-PASS | toolkit/components/places/tests/migration/test_current_from_downgraded.js | took 174238ms
19:00:53     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-eqnjsr',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:54     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-eqnjsr',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:54     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-brh0bi',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:00:55     INFO -  TEST-TIMEOUT | toolkit/components/places/tests/legacy/test_bookmarks.js | took 300000ms
19:00:55     INFO -  mozcrash kill_pid(): wait failed (-1) terminating pid 8832: error 5
19:00:55     INFO -  xpcshell return code: None
19:00:55     INFO -  toolkit/components/places/tests/legacy/test_bookmarks.js | Process still running after test!
19:01:03     INFO -  toolkit/components/places/tests/history/test_removeByFilter.js failed or timed out, will retry.
19:01:03     INFO -  toolkit/components/places/tests/history/test_removeVisits.js failed or timed out, will retry.
19:01:04     INFO -  rmtree() failed for "('c:\\users\\task_1529518498\\appdata\\local\\temp\\xpc-plugins-brh0bi',)". Reason: The process cannot access the file because it is being used by another process (13). Retrying...
19:01:04     INFO -  toolkit/components/places/tests/history/test_removeMany.js failed or timed out, will retry.
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/maintenance/test_places_replaceOnStartup_clone.js | took 240832ms
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/maintenance/test_corrupt_places_schema.js | took 298085ms
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/maintenance/test_preventive_maintenance_checkAndFixDatabase.js | took 203154ms
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/migration/test_current_from_v41.js | took 112205ms
19:01:08     INFO -  TEST-TIMEOUT | toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js | took 302199ms
19:01:08     INFO -  TEST-TIMEOUT | toolkit/components/places/tests/maintenance/test_corrupt_telemetry.js | took 302196ms
19:01:08     INFO -  TEST-TIMEOUT | toolkit/components/places/tests/legacy/test_protectRoots.js | took 302198ms
19:01:08     INFO -  test_end for toolkit/components/places/tests/maintenance/test_corrupt_telemetry.js logged while not in progress. Logged with data: {"status": "PASS", "test": "toolkit/components/places/tests/maintenance/test_corrupt_telemetry.js", "message": "xpcshell return code: 0"}
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/migration/test_current_from_v38.js | took 116301ms
19:01:08     INFO -  test_end for toolkit/components/places/tests/legacy/test_protectRoots.js logged while not in progress. Logged with data: {"status": "PASS", "test": "toolkit/components/places/tests/legacy/test_protectRoots.js", "message": "xpcshell return code: 0"}
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/maintenance/test_places_replaceOnStartup.js | took 263185ms
19:01:08     INFO -  test_end for toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js logged while not in progress. Logged with data: {"status": "PASS", "test": "toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js", "message": "xpcshell return code: 0"}
19:01:08     INFO -  mozcrash kill_pid(): unable to terminate pid 7612: 5
19:01:08     INFO -  mozcrash kill_pid(): unable to terminate pid 5752: 5
19:01:08     INFO -  mozcrash kill_pid(): unable to terminate pid 3896: 5
19:01:08     INFO -  mozcrash Copy/paste: Z:\task_1529518498\build\win32-minidump_stackwalk.exe c:\users\task_1529518498\appdata\local\temp\xpc-other-07uzit\f9370748-059c-4ba4-8cac-531e1957804a.dmp Z:\task_1529518498\build\symbols
19:01:08     INFO -  mozcrash Copy/paste: Z:\task_1529518498\build\win32-minidump_stackwalk.exe c:\users\task_1529518498\appdata\local\temp\xpc-other-1jnavc\0fc008f7-4a0a-481e-be6c-7e214b18a267.dmp Z:\task_1529518498\build\symbols
19:01:08     INFO -  xpcshell return code: 0
19:01:08     INFO -  mozcrash Copy/paste: Z:\task_1529518498\build\win32-minidump_stackwalk.exe c:\users\task_1529518498\appdata\local\temp\xpc-other-bedvjr\b53957c5-0123-478e-8c73-f4c18cb315b7.dmp Z:\task_1529518498\build\symbols
19:01:08     INFO -  xpcshell return code: 0
19:01:08     INFO -  xpcshell return code: 0
19:01:08     INFO -  mozcrash Saved minidump as Z:\task_1529518498\build\blobber_upload_dir\b53957c5-0123-478e-8c73-f4c18cb315b7.dmp
19:01:08  WARNING -  PROCESS-CRASH | toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js | application crashed [unknown top frame]
19:01:08     INFO -  Crash dump filename: c:\users\task_1529518498\appdata\local\temp\xpc-other-bedvjr\b53957c5-0123-478e-8c73-f4c18cb315b7.dmp
19:01:08     INFO -  stderr from minidump_stackwalk:
19:01:08     INFO -  2018-06-20 19:01:08: minidump.cc:4359: INFO: Minidump opened minidump c:\users\task_1529518498\appdata\local\temp\xpc-other-bedvjr\b53957c5-0123-478e-8c73-f4c18cb315b7.dmp
19:01:08     INFO -  2018-06-20 19:01:08: minidump.cc:4808: ERROR: ReadBytes: read 0/32
19:01:08     INFO -  2018-06-20 19:01:08: minidump.cc:4453: ERROR: Minidump cannot read header
19:01:08     INFO -  2018-06-20 19:01:08: stackwalk.cc:133: ERROR: Minidump c:\users\task_1529518498\appdata\local\temp\xpc-other-bedvjr\b53957c5-0123-478e-8c73-f4c18cb315b7.dmp could not be read
19:01:08     INFO -  2018-06-20 19:01:08: minidump.cc:4331: INFO: Minidump closing minidump
19:01:08     INFO -  minidump_stackwalk exited with return code 1
19:01:08     INFO -  >>>>>>>
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 83
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 83
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 83
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: Failed to get directory to cache.: file z:/build/build/src/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp, line 83
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 2682
19:01:08     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
19:01:08     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
19:01:08     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
19:01:08     INFO -  running event loop
19:01:08     INFO -  toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js | Starting
19:01:08     INFO -  (xpcshell/head.js) | test pending (2)
19:01:08     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004002: file z:/build/build/src/toolkit/components/resistfingerprinting/nsRFPService.cpp, line 682
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js |  - Database file found - true == true
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js |  - Database file should not exist yet - true == true
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/toolkit/components/places/Database.cpp, line 2033
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/toolkit/components/places/Database.cpp, line 1243
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/toolkit/components/places/Database.cpp, line 657
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js |  - 2 == 2
19:01:08     INFO -  TEST-PASS | toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js |  - Found no icons - 0 == 0
19:01:08     INFO -  (xpcshell/head.js) | test run_next_test 1 pending (2)
19:01:08     INFO -  (xpcshell/head.js) | test finished (2)
19:01:08     INFO -  (xpcshell/head.js) | test run_next_test 1 finished (1)
19:01:08     INFO -  exiting test
19:01:08     INFO -  PID 5752 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"Places Connection shutdown","state":{"progress":{}},"filename":"z:/build/build/src/toolkit/components/places/Database.cpp","lineNumber":529,"stack":["jar:file:///Z:/task_1529518498/build/application/firefox/omni.ja!/components/nsAsyncShutdown.js:addBlocker:154","resource://gre/modules/PlacesUtils.jsm:null:1852","resource://gre/modules/XPCOMUtils.jsm:get:200","Z:/task_1529518498/build/tests/xpcshell/tests/toolkit/components/places/tests/maintenance/test_corrupt_favicons_schema.js:null:16"]}] Barrier: profile-before-change
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: '!obs', file z:/build/build/src/toolkit/components/clearsitedata/ClearSiteData.cpp, line 154
19:01:08     INFO -  PID 5752 | [5752, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file z:/build/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 3343
19:01:08     INFO -  PID 5752 | nsStringStats
19:01:08     INFO -  PID 5752 |  => mAllocCount:           5132
19:01:08     INFO -  PID 5752 |  => mReallocCount:          224
19:01:08     INFO -  PID 5752 |  => mFreeCount:            5132
19:01:08     INFO -  PID 5752 |  => mShareCount:           4074
19:01:08     INFO -  PID 5752 |  => mAdoptCount:            428
19:01:08     INFO -  PID 5752 |  => mAdoptFreeCount:        432
19:01:08     INFO -  PID 5752 |  => Process ID: 5752, Thread ID: 12160
19:01:08     INFO -  <<<<<<<

Comment 1

[Intermittent Failures Robot]

1 failures in 521 pushes (0.002 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1469990&startday=2018-06-18&endday=2018-06-24&tree=trunk

Comment 2

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup