Closed Bug 1173961 Opened 6 years ago Closed 3 years ago

Intermittent test_response_sw_reroute.html | application crashed [@ mozalloc_abort(char const * const)] after "FATAL ERROR: AsyncShutdown timeout in profile-before-change2 Conditions: [{"name":"TelemetryController: shutting down","state":{"initialized":t"

Categories

(Toolkit :: Telemetry, defect, P3)

x86_64
Windows 8
defect

Tracking

()

RESOLVED FIXED
Tracking Status
firefox43 --- affected
firefox44 --- affected
firefox45 --- affected

People

(Reporter: RyanVM, Unassigned)

References

Details

(Keywords: crash, intermittent-failure)

00:12:29 INFO - 4441 INFO TEST-OK | dom/tests/mochitest/fetch/test_response_sw_reroute.html | took 1477ms
00:12:40 INFO - WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"TelemetryController: shutting down","state":{"initialized":true,"initStarted":true,"haveDelayedInitTask":false,"shutdownBarrier":"Complete","connectionsBarrier":"Not started"},"filename":"resource://gre/modules/TelemetryController.jsm","lineNumber":684,"stack":["resource://gre/modules/TelemetryController.jsm:setupTelemetry:684","resource://gre/modules/TelemetryController.jsm:Impl.observe:761","resource://gre/modules/TelemetryController.jsm:this.TelemetryController<.observe:162","resource://gre/components/TelemetryStartup.js:TelemetryStartup.prototype.observe:30","null:null:0"]}] Barrier: profile-before-change2
00:12:40 INFO - WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"Waiting for ping task","state":"(none)","filename":"resource://gre/modules/TelemetrySend.jsm","lineNumber":696,"stack":["resource://gre/modules/TelemetrySend.jsm:TelemetrySendImpl._trackPendingPingTask:696","resource://gre/modules/TelemetrySend.jsm:TelemetrySendImpl._sendPersistedPings<:498","self-hosted:next:623","resource://gre/modules/Task.jsm:TaskImpl_run:314","resource://gre/modules/Task.jsm:TaskImpl:275","resource://gre/modules/Task.jsm:createAsyncFunction/asyncFunction:249","resource://gre/modules/TelemetrySend.jsm:TelemetrySendImpl._reschedulePingSendTimer/this._pingSendTimer<:681","resource://gre/modules/Timer.jsm:setTimeout_timer:30","null:null:0"]}] Barrier: TelemetrySend: Waiting for pending ping activity
00:13:31 INFO - FATAL ERROR: AsyncShutdown timeout in profile-before-change2 Conditions: [{"name":"TelemetryController: shutting down","state":{"initialized":true,"initStarted":true,"haveDelayedInitTask":false,"shutdownBarrier":"Complete","connectionsBarrier":"Not started"},"filename":"resource://gre/modules/TelemetryController.jsm","lineNumber":684,"stack":["resource://gre/modules/TelemetryController.jsm:setupTelemetry:684","resource://gre/modules/TelemetryController.jsm:Impl.observe:761","resource://gre/modules/TelemetryController.jsm:this.TelemetryController<.observe:162","resource://gre/components/TelemetryStartup.js:TelemetryStartup.prototype.observe:30","null:null:0"]}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
00:13:31 INFO - [Parent 2948] ###!!! ABORT: file resource://gre/modules/TelemetryController.jsm, line 684
00:13:31 INFO - TEST-INFO | Main app process: exit status 1
00:13:31 INFO - 4442 INFO TEST-START | Shutdown
00:13:31 INFO - 4443 INFO Passed: 80656
00:13:31 INFO - 4444 INFO Failed: 0
00:13:31 INFO - 4445 INFO Todo: 1119
00:13:31 INFO - 4446 INFO Slowest: 20931ms - /tests/dom/security/test/mixedcontentblocker/test_main.html
00:13:31 INFO - 4447 INFO SimpleTest FINISHED
00:13:31 INFO - 4448 INFO TEST-INFO | Ran 1 Loops
00:13:31 INFO - 4449 INFO SimpleTest FINISHED
00:13:31 WARNING - TEST-UNEXPECTED-FAIL | dom/tests/mochitest/fetch/test_response_sw_reroute.html | application terminated with exit code 1
00:13:39 WARNING - PROCESS-CRASH | dom/tests/mochitest/fetch/test_response_sw_reroute.html | application crashed [@ mozalloc_abort(char const * const)]
00:13:39 INFO - Crash dump filename: c:\users\cltbld~1.t-w\appdata\local\temp\tmp6oychp.mozrunner\minidumps\223984b4-730e-4b44-9b9c-207862b8aa9b.dmp
00:13:39 INFO - Operating system: Windows NT
00:13:39 INFO - 6.2.9200
00:13:39 INFO - CPU: amd64
00:13:39 INFO - family 6 model 30 stepping 5
00:13:39 INFO - 8 CPUs
00:13:39 INFO - Crash reason: EXCEPTION_BREAKPOINT
00:13:39 INFO - Crash address: 0x7fa3a4301eb
00:13:39 INFO - Thread 0 (crashed)
00:13:39 INFO - 0 mozglue.dll!mozalloc_abort(char const * const) [mozalloc_abort.cpp:39e638ed06bf : 33 + 0x0]
00:13:39 INFO - rbx = 0x0000008cf4083df0 r12 = 0x0000008c80045b20
00:13:39 INFO - r13 = 0x0000000000000000 r14 = 0x0000000000000001
00:13:39 INFO - r15 = 0x0000000000000000 rip = 0x000007fa3a4301eb
00:13:39 INFO - rsp = 0x0000008cf4083d60 rbp = 0x0000008cf4083e90
00:13:39 INFO - Found by: given as instruction pointer in context
00:13:39 INFO - 1 xul.dll!NS_DebugBreak [nsDebugImpl.cpp:39e638ed06bf : 425 + 0xa]
00:13:39 INFO - rbx = 0x0000008cf4083df0 r12 = 0x0000008c80045b20
00:13:39 INFO - r13 = 0x0000000000000000 r14 = 0x0000000000000001
00:13:39 INFO - r15 = 0x0000000000000000 rip = 0x000007fa2c54dc68
00:13:39 INFO - rsp = 0x0000008cf4083d90 rbp = 0x0000008cf4083e90
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 2 xul.dll!nsDebugImpl::Abort(char const *,int) [nsDebugImpl.cpp:39e638ed06bf : 147 + 0x14]
00:13:39 INFO - rbx = 0x0000008cf4083df0 r12 = 0x0000008c80045b20
00:13:39 INFO - r13 = 0x0000000000000000 r14 = 0x0000000000000001
00:13:39 INFO - r15 = 0x0000000000000000 rip = 0x000007fa2c54d285
00:13:39 INFO - rsp = 0x0000008cf4084230 rbp = 0x0000008cf4083e90
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 3 xul.dll!XPTC__InvokebyIndex [xptcinvoke_asm_x86_64.asm:39e638ed06bf : 97 + 0x3]
00:13:39 INFO - rbx = 0x0000008cf4083df0 r12 = 0x0000008c80045b20
00:13:39 INFO - r13 = 0x0000000000000000 r14 = 0x0000000000000001
00:13:39 INFO - r15 = 0x0000000000000000 rip = 0x000007fa2c54dd12
00:13:39 INFO - rsp = 0x0000008cf4084270 rbp = 0x0000008cf4083e90
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 4 0x8c80045b1f
00:13:39 INFO - rbx = 0x0000008cf4083df0 r12 = 0x0000008c80045b20
00:13:39 INFO - r13 = 0x0000000000000000 r14 = 0x0000000000000001
00:13:39 INFO - r15 = 0x0000000000000000 rip = 0x0000008c80045b20
00:13:39 INFO - rsp = 0x0000008cf4084280 rbp = 0x0000008cf4083e90
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 5 xul.dll!nsXPConnect::GetRuntimeInstance() [nsXPConnect.cpp:39e638ed06bf : 169 + 0xb]
00:13:39 INFO - rip = 0x000007fa2bf4bf20 rsp = 0x0000008cf4084290
00:13:39 INFO - Found by: stack scanning
00:13:39 INFO - 6 xul.dll!XPC_WN_CallMethod(JSContext *,unsigned int,JS::Value *) [XPCWrappedNativeJSOps.cpp:39e638ed06bf : 1144 + 0x39a]
00:13:39 INFO - rip = 0x000007fa2c13f05e rsp = 0x0000008cf40842c0
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 7 xul.dll!js::Invoke(JSContext *,JS::CallArgs,js::MaybeConstruct) [Interpreter.cpp:39e638ed06bf : 703 + 0xac]
00:13:39 INFO - rip = 0x000007fa2bf229e1 rsp = 0x0000008cf4084830
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 8 xul.dll!Interpret [Interpreter.cpp:39e638ed06bf : 2954 + 0x16]
00:13:39 INFO - rip = 0x000007fa2c04c7f5 rsp = 0x0000008cf40850b0
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 9 xul.dll!js::RunScript(JSContext *,js::RunState &) [Interpreter.cpp:39e638ed06bf : 652 + 0xa]
00:13:39 INFO - rip = 0x000007fa2bf31d82 rsp = 0x0000008cf4086b60
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 10 xul.dll!js::Invoke(JSContext *,JS::CallArgs,js::MaybeConstruct) [Interpreter.cpp:39e638ed06bf : 723 + 0xb]
00:13:39 INFO - rip = 0x000007fa2bf22aec rsp = 0x0000008cf4086be0
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 11 xul.dll!js::CallOrConstructBoundFunction(JSContext *,unsigned int,JS::Value *) [jsfun.cpp:39e638ed06bf : 1578 + 0x12]
00:13:39 INFO - rip = 0x000007fa2c3f5486 rsp = 0x0000008cf4087460
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 12 xul.dll!js::Invoke(JSContext *,JS::CallArgs,js::MaybeConstruct) [Interpreter.cpp:39e638ed06bf : 703 + 0xac]
00:13:39 INFO - rip = 0x000007fa2bf229e1 rsp = 0x0000008cf40875b0
00:13:39 INFO - Found by: call frame info
00:13:39 INFO - 13 xul.dll!js::fun_call(JSContext *,unsigned int,JS::Value *) [jsfun.cpp:39e638ed06bf : 1216 + 0x13]
00:13:39 INFO - rip = 0x000007fa2c43c0d7 rsp = 0x0000008cf4087e30
00:13:39 INFO - Found by: call frame info
This is handled by bug 1173709.
[Mass Closure] Closing Intermittent as a one off
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → WORKSFORME
I can consistently reproduce this shutdown crash on Firefox 43+ on OS X if I enable the MOZ_CHAOSMODE=0 environment variable (from bug 1182516) when running Firefox from the command line:

  MOZ_CHAOSMODE=0 /Applications/Nightly.app/Contents/MacOS/firefox

FATAL ERROR: AsyncShutdown timeout in profile-before-change2 Conditions: [{"name":"TelemetryController: shutting down","state":{"initialized":true,"initStarted":true,"haveDelayedInitTask":true,"shutdownBarrier":"Not started","connectionsBarrier":"Not started","sendModule":{"sendingEnabled":true,"pendingPingRequestCount":0,"pendingPingActivityCount":0,"unpersistedPingCount":0,"persistedPingCount":96,"schedulerState":{"shutdown":false,"hasSendTask":false,"sendsFailed":false,"sendTaskState":null,"backoffDelay":60000}}},"filename":"resource://gre/modules/TelemetryController.jsm","lineNumber":772,"stack":["resource://gre/modules/TelemetryController.jsm:setupTelemetry:772","resource://gre/modules/TelemetryController.jsm:Impl.observe:868","resource://gre/modules/TelemetryController.jsm:this.TelemetryController<.observe:198","resource://gre/components/TelemetryStartup.js:TelemetryStartup.prototype.observe:30"]}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
[72438] ###!!! ABORT: file resource://gre/modules/TelemetryController.jsm, line 772
[72438] ###!!! ABORT: file resource://gre/modules/TelemetryController.jsm, line 772

bp-e7e9e0e3-6e28-4d47-a7e8-8fcc32151124
bp-39a2d3e0-638a-4043-84ef-d9e772151124
bp-2788340c-6139-462d-b7f0-5c65b2151124
bp-cb230ba4-d034-4214-bc33-cdcfb2151124
bp-6a917010-37ac-4187-b1b0-c5fc42151124
bp-b64911a9-e295-4dd9-acc8-8483c2151124
bp-5b1f937e-5c41-4ff1-b203-69ef82151124
Blocks: 1173709
Status: RESOLVED → REOPENED
Resolution: WORKSFORME → ---
STR:
1. Run MOZ_CHAOSMODE=0 /Applications/Nightly.app/Contents/MacOS/firefox
2. Open about:home page!
3. Exit Firefox.

RESULT:
CRASH!
Bulk assigning P3 to all open intermittent bugs without a priority set in Firefox components per bug 1298978.
Priority: -- → P3
Status: REOPENED → RESOLVED
Closed: 6 years ago3 years ago
Resolution: --- → FIXED
You need to log in before you can comment on or make changes to this bug.