Closed Bug 1471454 Opened 7 years ago Closed 7 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/media/tests/mochitest/identity/test_setIdentityProviderWithErrors.html | application timed out after 370 seconds with no output

Tracking

()

Status:

RESOLVED DUPLICATE of bug 1471222

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Treeherder Bug Filer

Reporter

Description

•

7 years ago

treeherder

Filed by: rgurzau [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=185036835&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/JNYPSo-sSvm9VZQ8v6DsFg/runs/0/artifacts/public/logs/live_backing.log [task 2018-06-26T23:06:19.732Z] 23:06:19 INFO - TEST-OK | dom/media/tests/mochitest/identity/test_setIdentityProviderWithErrors.html | took 6065ms [task 2018-06-26T23:06:19.898Z] 23:06:19 INFO - TEST-START | Shutdown [task 2018-06-26T23:06:19.899Z] 23:06:19 INFO - Passed: 1563 [task 2018-06-26T23:06:19.900Z] 23:06:19 INFO - Failed: 0 [task 2018-06-26T23:06:19.906Z] 23:06:19 INFO - Todo: 54 [task 2018-06-26T23:06:19.908Z] 23:06:19 INFO - Mode: e10s [task 2018-06-26T23:06:19.908Z] 23:06:19 INFO - Slowest: 14175ms - /tests/dom/media/tests/mochitest/identity/test_peerConnection_peerIdentity.html [task 2018-06-26T23:06:19.930Z] 23:06:19 INFO - SimpleTest FINISHED [task 2018-06-26T23:06:19.931Z] 23:06:19 INFO - TEST-INFO | Ran 1 Loops [task 2018-06-26T23:06:19.931Z] 23:06:19 INFO - SimpleTest FINISHED [task 2018-06-26T23:06:20.442Z] 23:06:20 INFO - GECKO(2496) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost [task 2018-06-26T23:06:20.927Z] 23:06:20 INFO - GECKO(2496) | 1530054380920 Marionette DEBUG Received observer notification xpcom-will-shutdown [task 2018-06-26T23:06:20.928Z] 23:06:20 INFO - GECKO(2496) | 1530054380921 Marionette INFO Stopped listening on port 2828 [task 2018-06-26T23:06:20.930Z] 23:06:20 INFO - GECKO(2496) | 1530054380921 Marionette DEBUG Remote service is inactive [task 2018-06-26T23:06:21.676Z] 23:06:21 INFO - GECKO(2496) | ----------------------------------------------------- [task 2018-06-26T23:06:21.677Z] 23:06:21 INFO - GECKO(2496) | Suppressions used: [task 2018-06-26T23:06:21.677Z] 23:06:21 INFO - GECKO(2496) | count bytes template [task 2018-06-26T23:06:21.678Z] 23:06:21 INFO - GECKO(2496) | 638 20352 nsComponentManagerImpl [task 2018-06-26T23:06:21.679Z] 23:06:21 INFO - GECKO(2496) | 2 416 mozJSComponentLoader::LoadModule [task 2018-06-26T23:06:21.679Z] 23:06:21 INFO - GECKO(2496) | 2 288 libfontconfig.so [task 2018-06-26T23:06:21.680Z] 23:06:21 INFO - GECKO(2496) | ----------------------------------------------------- [task 2018-06-26T23:06:22.383Z] 23:06:22 INFO - GECKO(2496) | ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FA,name=PContent::Msg_AccumulateChildHistograms) Closed channel: cannot send/recv [task 2018-06-26T23:06:22.385Z] 23:06:22 INFO - GECKO(2496) | ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FB,name=PContent::Msg_AccumulateChildKeyedHistograms) Closed channel: cannot send/recv [task 2018-06-26T23:06:22.386Z] 23:06:22 INFO - GECKO(2496) | ###!!! [Child][MessageChannel] Error: (msgtype=0x2D00FF,name=PContent::Msg_RecordDiscardedData) Closed channel: cannot send/recv [task 2018-06-26T23:06:22.725Z] 23:06:22 INFO - GECKO(2496) | Timecard created 1530054374.259255 [task 2018-06-26T23:06:22.726Z] 23:06:22 INFO - GECKO(2496) | Timestamp | Delta | Event | File | Function [task 2018-06-26T23:06:22.726Z] 23:06:22 INFO - GECKO(2496) | ====================================================================================================================== [task 2018-06-26T23:06:22.727Z] 23:06:22 INFO - GECKO(2496) | 0.000527 | 0.000527 | Constructor Completed | PeerConnectionImpl.cpp:360 | PeerConnectionImpl [task 2018-06-26T23:06:22.727Z] 23:06:22 INFO - GECKO(2496) | 0.002219 | 0.001692 | Initializing PC Ctx | PeerConnectionImpl.cpp:651 | Initialize [task 2018-06-26T23:06:22.727Z] 23:06:22 INFO - GECKO(2496) | 2.857449 | 2.855230 | Create Offer | PeerConnectionImpl.cpp:1539 | CreateOffer [task 2018-06-26T23:06:22.728Z] 23:06:22 INFO - GECKO(2496) | 2.872289 | 0.014840 | Set Local Description | PeerConnectionImpl.cpp:1744 | SetLocalDescription [task 2018-06-26T23:06:22.728Z] 23:06:22 INFO - GECKO(2496) | 2.999748 | 0.127459 | Ice gathering state: gathering | PeerConnectionImpl.cpp:3192 | IceGatheringStateChange [task 2018-06-26T23:06:22.729Z] 23:06:22 INFO - GECKO(2496) | 3.027313 | 0.027565 | Ice gathering state: complete | PeerConnectionImpl.cpp:3195 | IceGatheringStateChange [task 2018-06-26T23:06:22.730Z] 23:06:22 INFO - GECKO(2496) | 3.287039 | 0.259726 | Set Remote Description | PeerConnectionImpl.cpp:1847 | SetRemoteDescription [task 2018-06-26T23:06:22.733Z] 23:06:22 INFO - GECKO(2496) | 3.451144 | 0.164105 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.735Z] 23:06:22 INFO - GECKO(2496) | 3.480930 | 0.029786 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.737Z] 23:06:22 INFO - GECKO(2496) | 3.551595 | 0.070665 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.739Z] 23:06:22 INFO - GECKO(2496) | 3.585756 | 0.034161 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.740Z] 23:06:22 INFO - GECKO(2496) | 3.739752 | 0.153996 | Ice state: checking | PeerConnectionImpl.cpp:3144 | IceConnectionStateChange [task 2018-06-26T23:06:22.743Z] 23:06:22 INFO - GECKO(2496) | 3.749744 | 0.009992 | Ice state: connected | PeerConnectionImpl.cpp:3147 | IceConnectionStateChange [task 2018-06-26T23:06:22.744Z] 23:06:22 INFO - GECKO(2496) | 8.451046 | 4.701302 | Destructor Invoked | PeerConnectionImpl.cpp:374 | ~PeerConnectionImpl [task 2018-06-26T23:06:22.746Z] 23:06:22 INFO - GECKO(2496) | [Child 2541: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:399: PeerConnectionCtx is already gone. Ignoring... [task 2018-06-26T23:06:22.748Z] 23:06:22 INFO - GECKO(2496) | [Child 2541: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:403: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for 67c76c7698b726a3 [task 2018-06-26T23:06:22.753Z] 23:06:22 INFO - GECKO(2496) | Timecard created 1530054374.282801 [task 2018-06-26T23:06:22.756Z] 23:06:22 INFO - GECKO(2496) | Timestamp | Delta | Event | File | Function [task 2018-06-26T23:06:22.759Z] 23:06:22 INFO - GECKO(2496) | ====================================================================================================================== [task 2018-06-26T23:06:22.760Z] 23:06:22 INFO - GECKO(2496) | 0.000210 | 0.000210 | Constructor Completed | PeerConnectionImpl.cpp:360 | PeerConnectionImpl [task 2018-06-26T23:06:22.762Z] 23:06:22 INFO - GECKO(2496) | 0.001202 | 0.000992 | Initializing PC Ctx | PeerConnectionImpl.cpp:651 | Initialize [task 2018-06-26T23:06:22.764Z] 23:06:22 INFO - GECKO(2496) | 2.877008 | 2.875806 | Set Remote Description | PeerConnectionImpl.cpp:1847 | SetRemoteDescription [task 2018-06-26T23:06:22.767Z] 23:06:22 INFO - GECKO(2496) | 2.974665 | 0.097657 | Create Answer | PeerConnectionImpl.cpp:1610 | CreateAnswer [task 2018-06-26T23:06:22.768Z] 23:06:22 INFO - GECKO(2496) | 3.173956 | 0.199291 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.769Z] 23:06:22 INFO - GECKO(2496) | 3.176435 | 0.002479 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.772Z] 23:06:22 INFO - GECKO(2496) | 3.192682 | 0.016247 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.776Z] 23:06:22 INFO - GECKO(2496) | 3.197189 | 0.004507 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.780Z] 23:06:22 INFO - GECKO(2496) | 3.198800 | 0.001611 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.781Z] 23:06:22 INFO - GECKO(2496) | 3.203741 | 0.004941 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.782Z] 23:06:22 INFO - GECKO(2496) | 3.205404 | 0.001663 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.782Z] 23:06:22 INFO - GECKO(2496) | 3.210040 | 0.004636 | Add Ice Candidate | PeerConnectionImpl.cpp:2015 | AddIceCandidate [task 2018-06-26T23:06:22.782Z] 23:06:22 INFO - GECKO(2496) | 3.211991 | 0.001951 | Set Local Description | PeerConnectionImpl.cpp:1744 | SetLocalDescription [task 2018-06-26T23:06:22.784Z] 23:06:22 INFO - GECKO(2496) | 3.415121 | 0.203130 | Ice gathering state: gathering | PeerConnectionImpl.cpp:3192 | IceGatheringStateChange [task 2018-06-26T23:06:22.784Z] 23:06:22 INFO - GECKO(2496) | 3.415769 | 0.000648 | Ice gathering state: complete | PeerConnectionImpl.cpp:3195 | IceGatheringStateChange [task 2018-06-26T23:06:22.785Z] 23:06:22 INFO - GECKO(2496) | 3.415826 | 0.000057 | Ice state: checking | PeerConnectionImpl.cpp:3144 | IceConnectionStateChange [task 2018-06-26T23:06:22.786Z] 23:06:22 INFO - GECKO(2496) | 3.728016 | 0.312190 | Ice state: connected | PeerConnectionImpl.cpp:3147 | IceConnectionStateChange [task 2018-06-26T23:06:22.786Z] 23:06:22 INFO - GECKO(2496) | 8.431721 | 4.703705 | Destructor Invoked | PeerConnectionImpl.cpp:374 | ~PeerConnectionImpl [task 2018-06-26T23:06:22.790Z] 23:06:22 INFO - GECKO(2496) | [Child 2541: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:399: PeerConnectionCtx is already gone. Ignoring... [task 2018-06-26T23:06:22.791Z] 23:06:22 INFO - GECKO(2496) | [Child 2541: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:403: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for e0ef9ca0181e33f5 [task 2018-06-26T23:06:24.636Z] 23:06:24 INFO - GECKO(2496) | ----------------------------------------------------- [task 2018-06-26T23:06:24.636Z] 23:06:24 INFO - GECKO(2496) | Suppressions used: [task 2018-06-26T23:06:24.636Z] 23:06:24 INFO - GECKO(2496) | count bytes template [task 2018-06-26T23:06:24.636Z] 23:06:24 INFO - GECKO(2496) | 638 20352 nsComponentManagerImpl [task 2018-06-26T23:06:24.639Z] 23:06:24 INFO - GECKO(2496) | 5 1040 mozJSComponentLoader::LoadModule [task 2018-06-26T23:06:24.639Z] 23:06:24 INFO - GECKO(2496) | 611 17713 libfontconfig.so [task 2018-06-26T23:06:24.639Z] 23:06:24 INFO - GECKO(2496) | 2 65568 nr_reg_local_init [task 2018-06-26T23:06:24.641Z] 23:06:24 INFO - GECKO(2496) | 195 18592 r_log_register [task 2018-06-26T23:06:24.642Z] 23:06:24 INFO - GECKO(2496) | 60 2761 nr_reg_set [task 2018-06-26T23:06:24.643Z] 23:06:24 INFO - GECKO(2496) | 1 29 libglib-2.0.so [task 2018-06-26T23:06:24.643Z] 23:06:24 INFO - GECKO(2496) | ----------------------------------------------------- [task 2018-06-26T23:06:26.034Z] 23:06:26 INFO - GECKO(2496) | ----------------------------------------------------- [task 2018-06-26T23:06:26.034Z] 23:06:26 INFO - GECKO(2496) | Suppressions used: [task 2018-06-26T23:06:26.034Z] 23:06:26 INFO - GECKO(2496) | count bytes template [task 2018-06-26T23:06:26.034Z] 23:06:26 INFO - GECKO(2496) | 647 20632 nsComponentManagerImpl [task 2018-06-26T23:06:26.034Z] 23:06:26 INFO - GECKO(2496) | 41 8528 mozJSComponentLoader::LoadModule [task 2018-06-26T23:06:26.034Z] 23:06:26 INFO - GECKO(2496) | 611 17509 libfontconfig.so [task 2018-06-26T23:06:26.034Z] 23:06:26 INFO - GECKO(2496) | 4 176 _PR_Getfd [task 2018-06-26T23:06:26.035Z] 23:06:26 INFO - GECKO(2496) | 1 29 libglib-2.0.so [task 2018-06-26T23:06:26.035Z] 23:06:26 INFO - GECKO(2496) | ----------------------------------------------------- [task 2018-06-26T23:12:36.042Z] 23:12:36 INFO - Buffered messages finished [task 2018-06-26T23:12:36.044Z] 23:12:36 ERROR - TEST-UNEXPECTED-TIMEOUT | dom/media/tests/mochitest/identity/test_setIdentityProviderWithErrors.html | application timed out after 370 seconds with no output [task 2018-06-26T23:12:36.046Z] 23:12:36 ERROR - Force-terminating active process(es).

Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)

Updated

•

7 years ago

Status: NEW → RESOLVED

Closed: 7 years ago

Resolution: --- → DUPLICATE

Comment hidden (Intermittent Failures Robot)

You need to log in before you can comment on or make changes to this bug.

Bugzilla

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/media/tests/mochitest/identity/test_setIdentityProviderWithErrors.html | application timed out after 370 seconds with no output

Categories

(Core :: WebRTC, defect, P5)

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Crash Data

Security

(public)

User Story

Description

Updated

Comment 2