Closed
Bug 1890104
Opened 1 year ago
Closed 9 months ago
Intermittent TransportTest.TestTransferIceCombinedPackets | Value of: (count * sizeof(buf)) == p2_->receivedBytes()
Categories
(Core :: WebRTC: Networking, defect, P5)
Core
WebRTC: Networking
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: sstanca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=453738808&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dErhSBBBRGORMozmSdvyNg/runs/0/artifacts/public/logs/live_backing.log
[task 2024-04-06T09:22:10.498Z] 09:22:10 INFO - TEST-PASS | TransportTest.TestTransferIceMultiple | test completed (time: 99ms)
[task 2024-04-06T09:22:10.498Z] 09:22:10 INFO - TEST-START | TransportTest.TestTransferIceCombinedPackets
[task 2024-04-06T09:22:10.511Z] 09:22:10 INFO - Got candidate candidate:0 1 UDP 2122187007 172.17.0.4 60129 typ host (ufrag=ufrag)
[task 2024-04-06T09:22:10.511Z] 09:22:10 INFO - Got candidate candidate:2 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 60116 typ host (ufrag=ufrag)
[task 2024-04-06T09:22:10.512Z] 09:22:10 INFO - Got candidate candidate:3 1 TCP 2105442559 172.17.0.4 51697 typ host tcptype passive (ufrag=ufrag)
[task 2024-04-06T09:22:10.512Z] 09:22:10 INFO - Got candidate candidate:3 1 TCP 2105458943 172.17.0.4 9 typ host tcptype active (ufrag=ufrag)
[task 2024-04-06T09:22:10.512Z] 09:22:10 INFO - Got candidate candidate:3 1 TCP 2105426175 172.17.0.4 63265 typ host tcptype so (ufrag=ufrag)
[task 2024-04-06T09:22:10.512Z] 09:22:10 INFO - Got candidate candidate:4 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:4 60070 typ host tcptype passive (ufrag=ufrag)
[task 2024-04-06T09:22:10.513Z] 09:22:10 INFO - Got candidate candidate:4 1 TCP 2105524479 fd15:4ba5:5a2b:100a:0:242:ac11:4 9 typ host tcptype active (ufrag=ufrag)
[task 2024-04-06T09:22:10.513Z] 09:22:10 INFO - Got candidate candidate:4 1 TCP 2105491711 fd15:4ba5:5a2b:100a:0:242:ac11:4 58331 typ host tcptype so (ufrag=ufrag)
[task 2024-04-06T09:22:10.513Z] 09:22:10 INFO - Got candidate (ufrag=ufrag)
[task 2024-04-06T09:22:10.514Z] 09:22:10 INFO - Got candidate candidate:0 1 UDP 2122187007 172.17.0.4 53052 typ host (ufrag=ufrag)
[task 2024-04-06T09:22:10.514Z] 09:22:10 INFO - Got candidate candidate:2 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 38993 typ host (ufrag=ufrag)
[task 2024-04-06T09:22:10.514Z] 09:22:10 INFO - Got candidate candidate:3 1 TCP 2105442559 172.17.0.4 60468 typ host tcptype passive (ufrag=ufrag)
[task 2024-04-06T09:22:10.515Z] 09:22:10 INFO - Got candidate candidate:3 1 TCP 2105458943 172.17.0.4 9 typ host tcptype active (ufrag=ufrag)
[task 2024-04-06T09:22:10.515Z] 09:22:10 INFO - Got candidate candidate:3 1 TCP 2105426175 172.17.0.4 52256 typ host tcptype so (ufrag=ufrag)
[task 2024-04-06T09:22:10.515Z] 09:22:10 INFO - Got candidate candidate:4 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:4 60617 typ host tcptype passive (ufrag=ufrag)
[task 2024-04-06T09:22:10.516Z] 09:22:10 INFO - Got candidate candidate:4 1 TCP 2105524479 fd15:4ba5:5a2b:100a:0:242:ac11:4 9 typ host tcptype active (ufrag=ufrag)
[task 2024-04-06T09:22:10.516Z] 09:22:10 INFO - Got candidate candidate:4 1 TCP 2105491711 fd15:4ba5:5a2b:100a:0:242:ac11:4 55175 typ host tcptype so (ufrag=ufrag)
[task 2024-04-06T09:22:10.516Z] 09:22:10 INFO - Got candidate (ufrag=ufrag)
[task 2024-04-06T09:22:10.576Z] 09:22:10 INFO - Now connected
[task 2024-04-06T09:22:10.576Z] 09:22:10 INFO - Now connected
[task 2024-04-06T09:22:10.585Z] 09:22:10 INFO - Received 1024 bytes
[task 2024-04-06T09:22:10.585Z] 09:22:10 INFO - Received 1024 bytes
[task 2024-04-06T09:22:10.586Z] 09:22:10 INFO - Received == 2 packets
[task 2024-04-06T09:22:10.586Z] 09:22:10 INFO - Received 1024 bytes
[task 2024-04-06T09:22:10.586Z] 09:22:10 WARNING - TEST-UNEXPECTED-FAIL | TransportTest.TestTransferIceCombinedPackets | Value of: (count * sizeof(buf)) == p2_->receivedBytes()
[task 2024-04-06T09:22:10.586Z] 09:22:10 INFO - Actual: false
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - Expected: true
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - @ /builds/worker/checkouts/gecko/dom/media/webrtc/transport/test/transport_unittests.cpp:921
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - ICE(IcePeer): no local addresses available
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - insert 'ice.pref.interface' (registry) succeeded: ice.pref.interface
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - insert 'ice.pref.interface.eth0' (UCHAR) succeeded: 0x7f
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - ICE(IcePeer): All candidates initialized
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - ICE(IcePeer): no local addresses available
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - ICE(IcePeer2): no local addresses available
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - ICE(IcePeer): All candidates initialized
[task 2024-04-06T09:22:10.587Z] 09:22:10 INFO - ICE(IcePeer2): All candidates initialized
[task 2024-04-06T09:22:10.588Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(njho): setting pair to state FROZEN: njho|IP4:172.17.0.4:36973/UDP|IP4:172.17.0.4:35492/UDP(host(IP4:172.17.0.4:36973/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 35492 typ host)
[task 2024-04-06T09:22:10.588Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(njho): Pairing candidate IP4:172.17.0.4:36973/UDP (7e7f00ff):IP4:172.17.0.4:35492/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2024-04-06T09:22:10.589Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(o1yA): setting pair to state FROZEN: o1yA|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:36119/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:34436/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:36119/UDP)|candidate:1 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 34436 typ host)
[task 2024-04-06T09:22:10.589Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(o1yA): Pairing candidate IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:36119/UDP (7e7f00ff):IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:34436/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2024-04-06T09:22:10.590Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(0UXc): setting pair to state FROZEN: 0UXc|IP4:172.17.0.4:56539/TCP|IP4:172.17.0.4:58176/TCP(host(IP4:172.17.0.4:56539/TCP) active|candidate:2 1 TCP 2105508095 172.17.0.4 58176 typ host tcptype passive)
[task 2024-04-06T09:22:10.590Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(0UXc): Pairing candidate IP4:172.17.0.4:56539/TCP (7d7fc0ff):IP4:172.17.0.4:58176/TCP (7d7f80ff) priority=9043088413699310078 (7d7f80fffaff81fe)
[task 2024-04-06T09:22:10.590Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(yuSt): setting pair to state FROZEN: yuSt|IP4:172.17.0.4:62286/TCP|IP4:172.17.0.4:51213/TCP(host(IP4:172.17.0.4:62286/TCP) so|candidate:2 1 TCP 2105491711 172.17.0.4 51213 typ host tcptype so)
[task 2024-04-06T09:22:10.591Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(yuSt): Pairing candidate IP4:172.17.0.4:62286/TCP (7d7f40ff):IP4:172.17.0.4:51213/TCP (7d7f40ff) priority=9043018044955066879 (7d7f40fffafe81ff)
[task 2024-04-06T09:22:10.591Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(/djx): setting pair to state FROZEN: /djx|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:51481/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:49599/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:51481/TCP) active|candidate:3 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:4 49599 typ host tcptype passive)
[task 2024-04-06T09:22:10.592Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(/djx): Pairing candidate IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:51481/TCP (7d7fc0ff):IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:49599/TCP (7d7f80ff) priority=9043088413699310078 (7d7f80fffaff81fe)
[task 2024-04-06T09:22:10.592Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(WuBc): setting pair to state FROZEN: WuBc|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:61928/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:59732/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:61928/TCP) so|candidate:3 1 TCP 2105491711 fd15:4ba5:5a2b:100a:0:242:ac11:4 59732 typ host tcptype so)
[task 2024-04-06T09:22:10.592Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(WuBc): Pairing candidate IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:61928/TCP (7d7f40ff):IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:59732/TCP (7d7f40ff) priority=9043018044955066879 (7d7f40fffafe81ff)
[task 2024-04-06T09:22:10.593Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(PlIc): setting pair to state FROZEN: PlIc|IP4:172.17.0.4:57760/UDP|IP4:172.17.0.4:55838/UDP(host(IP4:172.17.0.4:57760/UDP)|candidate:0 2 UDP 2122252542 172.17.0.4 55838 typ host)
[task 2024-04-06T09:22:10.593Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(PlIc): Pairing candidate IP4:172.17.0.4:57760/UDP (7e7f00fe):IP4:172.17.0.4:55838/UDP (7e7f00fe) priority=9115005265987371517 (7e7f00fefcfe01fd)
[task 2024-04-06T09:22:10.594Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(2ZH4): setting pair to state FROZEN: 2ZH4|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:47191/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:35478/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:47191/UDP)|candidate:1 2 UDP 2122252542 fd15:4ba5:5a2b:100a:0:242:ac11:4 35478 typ host)
[task 2024-04-06T09:22:10.594Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(2ZH4): Pairing candidate IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:47191/UDP (7e7f00fe):IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:35478/UDP (7e7f00fe) priority=9115005265987371517 (7e7f00fefcfe01fd)
[task 2024-04-06T09:22:10.594Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(Gm5V): setting pair to state FROZEN: Gm5V|IP4:172.17.0.4:56423/TCP|IP4:172.17.0.4:57383/TCP(host(IP4:172.17.0.4:56423/TCP) active|candidate:2 2 TCP 2105508094 172.17.0.4 57383 typ host tcptype passive)
[task 2024-04-06T09:22:10.595Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(Gm5V): Pairing candidate IP4:172.17.0.4:56423/TCP (7d7fc0fe):IP4:172.17.0.4:57383/TCP (7d7f80fe) priority=9043088409404342780 (7d7f80fefaff81fc)
[task 2024-04-06T09:22:10.595Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(DoGB): setting pair to state FROZEN: DoGB|IP4:172.17.0.4:55321/TCP|IP4:172.17.0.4:50750/TCP(host(IP4:172.17.0.4:55321/TCP) so|candidate:2 2 TCP 2105491710 172.17.0.4 50750 typ host tcptype so)
[task 2024-04-06T09:22:10.595Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(DoGB): Pairing candidate IP4:172.17.0.4:55321/TCP (7d7f40fe):IP4:172.17.0.4:50750/TCP (7d7f40fe) priority=9043018040660099581 (7d7f40fefafe81fd)
[task 2024-04-06T09:22:10.596Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(djme): setting pair to state FROZEN: djme|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:65525/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:51129/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:65525/TCP) active|candidate:3 2 TCP 2105508094 fd15:4ba5:5a2b:100a:0:242:ac11:4 51129 typ host tcptype passive)
[task 2024-04-06T09:22:10.596Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(djme): Pairing candidate IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:65525/TCP (7d7fc0fe):IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:51129/TCP (7d7f80fe) priority=9043088409404342780 (7d7f80fefaff81fc)
[task 2024-04-06T09:22:10.597Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(J1ak): setting pair to state FROZEN: J1ak|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:64829/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:52628/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:64829/TCP) so|candidate:3 2 TCP 2105491710 fd15:4ba5:5a2b:100a:0:242:ac11:4 52628 typ host tcptype so)
[task 2024-04-06T09:22:10.597Z] 09:22:10 INFO - ICE(IcePeer2)/CAND-PAIR(J1ak): Pairing candidate IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:64829/TCP (7d7f40fe):IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:52628/TCP (7d7f40fe) priority=9043018040660099581 (7d7f40fefafe81fd)
[task 2024-04-06T09:22:10.597Z] 09:22:10 INFO - ICE(IcePeer2): peer (IcePeer2) starting grace period timer for 5000 ms
[task 2024-04-06T09:22:10.597Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(njho): setting pair to state WAITING: njho|IP4:172.17.0.4:36973/UDP|IP4:172.17.0.4:35492/UDP(host(IP4:172.17.0.4:36973/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 35492 typ host)
[task 2024-04-06T09:22:10.598Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(o1yA): setting pair to state WAITING: o1yA|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:36119/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:34436/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:36119/UDP)|candidate:1 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 34436 typ host)
[task 2024-04-06T09:22:10.598Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(0UXc): setting pair to state WAITING: 0UXc|IP4:172.17.0.4:56539/TCP|IP4:172.17.0.4:58176/TCP(host(IP4:172.17.0.4:56539/TCP) active|candidate:2 1 TCP 2105508095 172.17.0.4 58176 typ host tcptype passive)
[task 2024-04-06T09:22:10.599Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(/djx): setting pair to state WAITING: /djx|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:51481/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:49599/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:51481/TCP) active|candidate:3 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:4 49599 typ host tcptype passive)
[task 2024-04-06T09:22:10.599Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/ICE-STREAM(IcePeer2): Starting check timer for stream.
[task 2024-04-06T09:22:10.600Z] 09:22:10 INFO - ICE-PEER(IcePeer2)/CAND-PAIR(njho): setting pair to state IN_PROGRESS: njho|IP4:172.17.0.4:36973/UDP|IP4:172.17.0.4:35492/UDP(host(IP4:172.17.0.4:36973/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 35492 typ host)
[task 2024-04-06T09:22:10.600Z] 09:22:10 INFO - ICE(IcePeer2): peer (IcePeer2) is now checking
[task 2024-04-06T09:22:10.600Z] 09:22:10 INFO - ICE(IcePeer2): peer (IcePeer2) no streams with pre-answer requests
[task 2024-04-06T09:22:10.601Z] 09:22:10 INFO - STUN-SERVER(server(IP4:172.17.0.4:0/UDP)): Falling back to default client, username=: ufrag:ufrag
[task 2024-04-06T09:22:10.601Z] 09:22:10 INFO - STUN-SERVER(server(IP4:172.17.0.4:0/UDP)): Falling back to default client, username=: ufrag:ufrag
[task 2024-04-06T09:22:10.601Z] 09:22:10 INFO - ICE-PEER(IcePeer)/CAND-PAIR(RNKz): setting pair to state FROZEN: RNKz|IP4:172.17.0.4:35492/UDP|IP4:172.17.0.4:36973/UDP(host(IP4:172.17.0.4:35492/UDP)|candidate:0 1 UDP 2122252543 172.17.0.4 36973 typ host)
[task 2024-04-06T09:22:10.602Z] 09:22:10 INFO - ICE(IcePeer)/CAND-PAIR(RNKz): Pairing candidate IP4:172.17.0.4:35492/UDP (7e7f00ff):IP4:172.17.0.4:36973/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2024-04-06T09:22:10.602Z] 09:22:10 INFO - ICE-PEER(IcePeer)/CAND-PAIR(x9RH): setting pair to state FROZEN: x9RH|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:34436/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:36119/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:34436/UDP)|candidate:1 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 36119 typ host)
[task 2024-04-06T09:22:10.603Z] 09:22:10 INFO - ICE(IcePeer)/CAND-PAIR(x9RH): Pairing candidate IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:34436/UDP (7e7f00ff):IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:36119/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2024-04-06T09:22:10.603Z] 09:22:10 INFO - ICE-PEER(IcePeer)/CAND-PAIR(BhV6): setting pair to state FROZEN: BhV6|IP4:172.17.0.4:52854/TCP|IP4:172.17.0.4:49281/TCP(host(IP4:172.17.0.4:52854/TCP) active|candidate:2 1 TCP 2105508095 172.17.0.4 49281 typ host tcptype passive)
[task 2024-04-06T09:22:10.603Z] 09:22:10 INFO - ICE(IcePeer)/CAND-PAIR(BhV6): Pairing candidate IP4:172.17.0.4:52854/TCP (7d7fc0ff):IP4:172.17.0.4:49281/TCP (7d7f80ff) priority=9043088413699310079 (7d7f80fffaff81ff)
[task 2024-04-06T09:22:10.604Z] 09:22:10 INFO - ICE-PEER(IcePeer)/CAND-PAIR(Aoh5): setting pair to state FROZEN: Aoh5|IP4:172.17.0.4:51213/TCP|IP4:172.17.0.4:62286/TCP(host(IP4:172.17.0.4:51213/TCP) so|candidate:2 1 TCP 2105491711 172.17.0.4 62286 typ host tcptype so)
[task 2024-04-06T09:22:10.604Z] 09:22:10 INFO - ICE(IcePeer)/CAND-PAIR(Aoh5): Pairing candidate IP4:172.17.0.4:51213/TCP (7d7f40ff):IP4:172.17.0.4:62286/TCP (7d7f40ff) priority=9043018044955066879 (7d7f40fffafe81ff)
[task 2024-04-06T09:22:10.605Z] 09:22:10 INFO - ICE-PEER(IcePeer)/CAND-PAIR(EMfm): setting pair to state FROZEN: EMfm|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:49190/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:57118/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:49190/TCP) active|candidate:3 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:4 57118 typ host tcptype passive)
<...>
[task 2024-04-06T09:22:10.866Z] 09:22:10 INFO - ICE(P1): peer (P1:default) is now checking
[task 2024-04-06T09:22:10.866Z] 09:22:10 INFO - ICE-PEER(P1:default): role conflict, both controlling
[task 2024-04-06T09:22:10.866Z] 09:22:10 INFO - ICE-PEER(P1:default): returning 487 role conflict
[task 2024-04-06T09:22:10.867Z] 09:22:10 INFO - ICE-PEER(P1:default)/STREAM(P1:stream0 - ufrag:pass)/COMP(1): error processing pre-answer request. Would have returned 487
[task 2024-04-06T09:22:10.867Z] 09:22:10 INFO - ICE-PEER(P2:default): role conflict, both controlling
[task 2024-04-06T09:22:10.868Z] 09:22:10 INFO - ICE-PEER(P2:default): detected role conflict. Switching to controlled
[task 2024-04-06T09:22:10.869Z] 09:22:10 INFO - STUN-CLIENT(uxK2|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60116/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:38993/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60116/UDP)|candidate:2 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 38993 typ host)): Received response; processing
[task 2024-04-06T09:22:10.869Z] 09:22:10 INFO - ICE-PEER(P1:default)/CAND-PAIR(uxK2): setting pair to state SUCCEEDED: uxK2|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60116/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:38993/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60116/UDP)|candidate:2 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 38993 typ host)
[task 2024-04-06T09:22:10.869Z] 09:22:10 INFO - ICE-PEER(P1:default)/STREAM(P1:stream0 - ufrag:pass)/COMP(1)/CAND-PAIR(uxK2): nominated pair is uxK2|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60116/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:38993/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60116/UDP)|candidate:2 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 38993 typ host)
[task 2024-04-06T09:22:10.870Z] 09:22:10 INFO - ICE-PEER(P1:default)/STREAM(P1:stream0 - ufrag:pass)/COMP(1)/CAND-PAIR(uxK2): cancelling all pairs but uxK2|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60116/UDP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:38993/UDP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60116/UDP)|candidate:2 1 UDP 2122252543 fd15:4ba5:5a2b:100a:0:242:ac11:4 38993 typ host)
[task 2024-04-06T09:22:10.870Z] 09:22:10 INFO - ICE-PEER(P1:default)/STREAM(P1:stream0 - ufrag:pass)/COMP(1)/CAND-PAIR(U8q1): cancelling FROZEN/WAITING pair U8q1|IP4:172.17.0.4:60129/UDP|IP4:172.17.0.4:53052/UDP(host(IP4:172.17.0.4:60129/UDP)|candidate:0 1 UDP 2122187007 172.17.0.4 53052 typ host) because CAND-PAIR(uxK2) was nominated.
[task 2024-04-06T09:22:10.871Z] 09:22:10 INFO - ICE-PEER(P1:default)/CAND-PAIR(U8q1): setting pair to state CANCELLED: U8q1|IP4:172.17.0.4:60129/UDP|IP4:172.17.0.4:53052/UDP(host(IP4:172.17.0.4:60129/UDP)|candidate:0 1 UDP 2122187007 172.17.0.4 53052 typ host)
[task 2024-04-06T09:22:10.871Z] 09:22:10 INFO - ICE-PEER(P1:default)/STREAM(P1:stream0 - ufrag:pass)/COMP(1)/CAND-PAIR(uW6+): cancelling FROZEN/WAITING pair uW6+|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:61375/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60617/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:61375/TCP) active|candidate:4 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:4 60617 typ host tcptype passive) because CAND-PAIR(uxK2) was nominated.
[task 2024-04-06T09:22:10.872Z] 09:22:10 INFO - ICE-PEER(P1:default)/CAND-PAIR(uW6+): setting pair to state CANCELLED: uW6+|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:61375/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:60617/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:61375/TCP) active|candidate:4 1 TCP 2105508095 fd15:4ba5:5a2b:100a:0:242:ac11:4 60617 typ host tcptype passive)
[task 2024-04-06T09:22:10.872Z] 09:22:10 INFO - ICE-PEER(P1:default)/STREAM(P1:stream0 - ufrag:pass)/COMP(1)/CAND-PAIR(2pst): cancelling FROZEN/WAITING pair 2pst|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:58331/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:55175/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:58331/TCP) so|candidate:4 1 TCP 2105491711 fd15:4ba5:5a2b:100a:0:242:ac11:4 55175 typ host tcptype so) because CAND-PAIR(uxK2) was nominated.
[task 2024-04-06T09:22:10.873Z] 09:22:10 INFO - ICE-PEER(P1:default)/CAND-PAIR(2pst): setting pair to state CANCELLED: 2pst|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:58331/TCP|IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:55175/TCP(host(IP6:[fd15:4ba5:5a2b:100a:0:242:ac11:4]:58331/TCP) so|candidate:4 1 TCP 2105491711 fd15:4ba5:5a2b:100a:0:242:ac11:4 55175 typ host tcptype so)
[task 2024-04-06T09:22:10.873Z] 09:22:10 INFO - ICE-PEER(P1:default)/STREAM(P1:stream0 - ufrag:pass)/COMP(1)/CAND-PAIR(hwrF): cancelling FROZEN/WAITING pair hwrF|IP4:172.17.0.4:65051/TCP|IP4:172.17.0.4:60468/TCP(host(IP4:172.17.0.4:65051/TCP) active|candidate:3 1 TCP 2105442559 172.17.0.4 60468 typ host tcptype passive) because CAND-PAIR(uxK2) was nominated.
[task 2024-04-06T09:22:10.874Z] 09:22:10 INFO - ICE-PEER(P1:default)/CAND-PAIR(hwrF): setting pair to state CANCELLED: hwrF|IP4:172.17.0.4:65051/TCP|IP4:172.17.0.4:60468/TCP(host(IP4:172.17.0.4:65051/TCP) active|candidate:3 1 TCP 2105442559 172.17.0.4 60468 typ host tcptype passive)
[task 2024-04-06T09:22:10.874Z] 09:22:10 INFO - ICE-PEER(P1:default)/STREAM(P1:stream0 - ufrag:pass)/COMP(1)/CAND-PAIR(N63k): cancelling FROZEN/WAITING pair N63k|IP4:172.17.0.4:63265/TCP|IP4:172.17.0.4:52256/TCP(host(IP4:172.17.0.4:63265/TCP) so|candidate:3 1 TCP 2105426175 172.17.0.4 52256 typ host tcptype so) because CAND-PAIR(uxK2) was nominated.
[task 2024-04-06T09:22:10.874Z] 09:22:10 INFO - ICE-PEER(P1:default)/CAND-PAIR(N63k): setting pair to state CANCELLED: N63k|IP4:172.17.0.4:63265/TCP|IP4:172.17.0.4:52256/TCP(host(IP4:172.17.0.4:63265/TCP) so|candidate:3 1 TCP 2105426175 172.17.0.4 52256 typ host tcptype so)
[task 2024-04-06T09:22:10.875Z] 09:22:10 INFO - ICE-PEER(P1:default)/ICE-STREAM(P1:stream0 - ufrag:pass): all active components have nominated candidate pairs
[task 2024-04-06T09:22:10.875Z] 09:22:10 INFO - ICE-PEER(P1:default): all checks completed success=1 fail=0
[task 2024-04-06T09:22:10.875Z] 09:22:10 INFO - ICE(P1): peer (P1:default) cancelling grace period timer
[task 2024-04-06T09:22:10.876Z] 09:22:10 WARNING - TEST-UNEXPECTED-FAIL | TransportTest.TestTransferIceCombinedPackets | test completed (time: 146ms)
[task 2024-04-06T09:22:10.876Z] 09:22:10 INFO - TEST-START | TransportTest.TestCipherMismatch
[task 2024-04-06T09:22:10.876Z] 09:22:10 INFO - TEST-PASS | TransportTest.TestCipherMismatch | test completed (time: 128ms)
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 3•11 months ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=486474643&repo=mozilla-central
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 6•9 months ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: REOPENED → RESOLVED
Closed: 1 year ago → 9 months ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•