1860858 - Intermittent TestNrSocketTest.FullConeTimeout | Value of: CheckConnectivityVia(public_addrs_[0], private_addrs_[0], sender_external_address)

Status: RESOLVED INCOMPLETE

(Core :: WebRTC: Networking, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=433624192&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ORXyO4BrSx2ayQBrvdVz2g/runs/0/artifacts/public/logs/live_backing.log

---

[task 2023-10-24T17:43:26.480Z] 17:43:26     INFO -  TEST-START | TestNrSocketTest.FullConeTimeout
[task 2023-10-24T17:43:26.743Z] 17:43:26  WARNING -  TEST-UNEXPECTED-FAIL | TestNrSocketTest.FullConeTimeout | Value of: CheckConnectivityVia(public_addrs_[0], private_addrs_[0], sender_external_address)
[task 2023-10-24T17:43:26.744Z] 17:43:26     INFO -    Actual: false
[task 2023-10-24T17:43:26.744Z] 17:43:26     INFO -  Expected: true
[task 2023-10-24T17:43:26.744Z] 17:43:26     INFO -   @ /builds/worker/checkouts/gecko/dom/media/webrtc/transport/test/test_nr_socket_unittest.cpp:744
[task 2023-10-24T17:43:26.744Z] 17:43:26     INFO -  ICE(IcePeer): no local addresses available
[task 2023-10-24T17:43:26.745Z] 17:43:26     INFO -  insert 'ice.pref.interface' (registry) succeeded: ice.pref.interface
[task 2023-10-24T17:43:26.745Z] 17:43:26     INFO -  insert 'ice.pref.interface.en0' (UCHAR) succeeded: 0x7f
[task 2023-10-24T17:43:26.745Z] 17:43:26     INFO -  ICE(IcePeer): All candidates initialized
[task 2023-10-24T17:43:26.745Z] 17:43:26     INFO -  ICE(IcePeer): no local addresses available
[task 2023-10-24T17:43:26.745Z] 17:43:26     INFO -  ICE(IcePeer2): no local addresses available
[task 2023-10-24T17:43:26.746Z] 17:43:26     INFO -  ICE(IcePeer): All candidates initialized
[task 2023-10-24T17:43:26.746Z] 17:43:26     INFO -  ICE(IcePeer2): All candidates initialized
[task 2023-10-24T17:43:26.746Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/CAND-PAIR(ofro): setting pair to state FROZEN: ofro|IP4:10.49.58.114:50521/UDP|IP4:10.49.58.114:58021/UDP(host(IP4:10.49.58.114:50521/UDP)|candidate:0 1 UDP 2122252543 10.49.58.114 58021 typ host)
[task 2023-10-24T17:43:26.746Z] 17:43:26     INFO -  ICE(IcePeer2)/CAND-PAIR(ofro): Pairing candidate IP4:10.49.58.114:50521/UDP (7e7f00ff):IP4:10.49.58.114:58021/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2023-10-24T17:43:26.746Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/CAND-PAIR(Sgg6): setting pair to state FROZEN: Sgg6|IP4:10.49.58.114:59920/TCP|IP4:10.49.58.114:56893/TCP(host(IP4:10.49.58.114:59920/TCP) active|candidate:1 1 TCP 2105508095 10.49.58.114 56893 typ host tcptype passive)
[task 2023-10-24T17:43:26.747Z] 17:43:26     INFO -  ICE(IcePeer2)/CAND-PAIR(Sgg6): Pairing candidate IP4:10.49.58.114:59920/TCP (7d7fc0ff):IP4:10.49.58.114:56893/TCP (7d7f80ff) priority=9043088413699310078 (7d7f80fffaff81fe)
[task 2023-10-24T17:43:26.747Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/CAND-PAIR(AmMw): setting pair to state FROZEN: AmMw|IP4:10.49.58.114:62242/TCP|IP4:10.49.58.114:56373/TCP(host(IP4:10.49.58.114:62242/TCP) so|candidate:1 1 TCP 2105491711 10.49.58.114 56373 typ host tcptype so)
[task 2023-10-24T17:43:26.747Z] 17:43:26     INFO -  ICE(IcePeer2)/CAND-PAIR(AmMw): Pairing candidate IP4:10.49.58.114:62242/TCP (7d7f40ff):IP4:10.49.58.114:56373/TCP (7d7f40ff) priority=9043018044955066879 (7d7f40fffafe81ff)
[task 2023-10-24T17:43:26.747Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/CAND-PAIR(Flqp): setting pair to state FROZEN: Flqp|IP4:10.49.58.114:51483/UDP|IP4:10.49.58.114:54644/UDP(host(IP4:10.49.58.114:51483/UDP)|candidate:0 2 UDP 2122252542 10.49.58.114 54644 typ host)
[task 2023-10-24T17:43:26.748Z] 17:43:26     INFO -  ICE(IcePeer2)/CAND-PAIR(Flqp): Pairing candidate IP4:10.49.58.114:51483/UDP (7e7f00fe):IP4:10.49.58.114:54644/UDP (7e7f00fe) priority=9115005265987371517 (7e7f00fefcfe01fd)
[task 2023-10-24T17:43:26.748Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/CAND-PAIR(ghrV): setting pair to state FROZEN: ghrV|IP4:10.49.58.114:64517/TCP|IP4:10.49.58.114:62937/TCP(host(IP4:10.49.58.114:64517/TCP) active|candidate:1 2 TCP 2105508094 10.49.58.114 62937 typ host tcptype passive)
[task 2023-10-24T17:43:26.748Z] 17:43:26     INFO -  ICE(IcePeer2)/CAND-PAIR(ghrV): Pairing candidate IP4:10.49.58.114:64517/TCP (7d7fc0fe):IP4:10.49.58.114:62937/TCP (7d7f80fe) priority=9043088409404342780 (7d7f80fefaff81fc)
[task 2023-10-24T17:43:26.748Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/CAND-PAIR(hLqj): setting pair to state FROZEN: hLqj|IP4:10.49.58.114:61330/TCP|IP4:10.49.58.114:54404/TCP(host(IP4:10.49.58.114:61330/TCP) so|candidate:1 2 TCP 2105491710 10.49.58.114 54404 typ host tcptype so)
[task 2023-10-24T17:43:26.749Z] 17:43:26     INFO -  ICE(IcePeer2)/CAND-PAIR(hLqj): Pairing candidate IP4:10.49.58.114:61330/TCP (7d7f40fe):IP4:10.49.58.114:54404/TCP (7d7f40fe) priority=9043018040660099581 (7d7f40fefafe81fd)
[task 2023-10-24T17:43:26.749Z] 17:43:26     INFO -  ICE(IcePeer2): peer (IcePeer2) starting grace period timer for 5000 ms
[task 2023-10-24T17:43:26.749Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/CAND-PAIR(ofro): setting pair to state WAITING: ofro|IP4:10.49.58.114:50521/UDP|IP4:10.49.58.114:58021/UDP(host(IP4:10.49.58.114:50521/UDP)|candidate:0 1 UDP 2122252543 10.49.58.114 58021 typ host)
[task 2023-10-24T17:43:26.749Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/CAND-PAIR(Sgg6): setting pair to state WAITING: Sgg6|IP4:10.49.58.114:59920/TCP|IP4:10.49.58.114:56893/TCP(host(IP4:10.49.58.114:59920/TCP) active|candidate:1 1 TCP 2105508095 10.49.58.114 56893 typ host tcptype passive)
[task 2023-10-24T17:43:26.749Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/ICE-STREAM(IcePeer2): Starting check timer for stream.
[task 2023-10-24T17:43:26.750Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/CAND-PAIR(ofro): setting pair to state IN_PROGRESS: ofro|IP4:10.49.58.114:50521/UDP|IP4:10.49.58.114:58021/UDP(host(IP4:10.49.58.114:50521/UDP)|candidate:0 1 UDP 2122252543 10.49.58.114 58021 typ host)
[task 2023-10-24T17:43:26.750Z] 17:43:26     INFO -  ICE(IcePeer2): peer (IcePeer2) is now checking
[task 2023-10-24T17:43:26.750Z] 17:43:26     INFO -  ICE(IcePeer2): peer (IcePeer2) no streams with pre-answer requests
[task 2023-10-24T17:43:26.750Z] 17:43:26     INFO -  STUN-SERVER(server(IP4:10.49.58.114:0/UDP)): Falling back to default client, username=: ufrag:ufrag
[task 2023-10-24T17:43:26.750Z] 17:43:26     INFO -  STUN-SERVER(server(IP4:10.49.58.114:0/UDP)): Falling back to default client, username=: ufrag:ufrag
[task 2023-10-24T17:43:26.750Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(k8Fj): setting pair to state FROZEN: k8Fj|IP4:10.49.58.114:58021/UDP|IP4:10.49.58.114:50521/UDP(host(IP4:10.49.58.114:58021/UDP)|candidate:0 1 UDP 2122252543 10.49.58.114 50521 typ host)
[task 2023-10-24T17:43:26.751Z] 17:43:26     INFO -  ICE(IcePeer)/CAND-PAIR(k8Fj): Pairing candidate IP4:10.49.58.114:58021/UDP (7e7f00ff):IP4:10.49.58.114:50521/UDP (7e7f00ff) priority=9115005270282338815 (7e7f00fffcfe01ff)
[task 2023-10-24T17:43:26.751Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(QSez): setting pair to state FROZEN: QSez|IP4:10.49.58.114:59003/TCP|IP4:10.49.58.114:65371/TCP(host(IP4:10.49.58.114:59003/TCP) active|candidate:1 1 TCP 2105508095 10.49.58.114 65371 typ host tcptype passive)
[task 2023-10-24T17:43:26.751Z] 17:43:26     INFO -  ICE(IcePeer)/CAND-PAIR(QSez): Pairing candidate IP4:10.49.58.114:59003/TCP (7d7fc0ff):IP4:10.49.58.114:65371/TCP (7d7f80ff) priority=9043088413699310079 (7d7f80fffaff81ff)
[task 2023-10-24T17:43:26.751Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(faL5): setting pair to state FROZEN: faL5|IP4:10.49.58.114:56373/TCP|IP4:10.49.58.114:62242/TCP(host(IP4:10.49.58.114:56373/TCP) so|candidate:1 1 TCP 2105491711 10.49.58.114 62242 typ host tcptype so)
[task 2023-10-24T17:43:26.752Z] 17:43:26     INFO -  ICE(IcePeer)/CAND-PAIR(faL5): Pairing candidate IP4:10.49.58.114:56373/TCP (7d7f40ff):IP4:10.49.58.114:62242/TCP (7d7f40ff) priority=9043018044955066879 (7d7f40fffafe81ff)
[task 2023-10-24T17:43:26.752Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(dk41): setting pair to state FROZEN: dk41|IP4:10.49.58.114:54644/UDP|IP4:10.49.58.114:51483/UDP(host(IP4:10.49.58.114:54644/UDP)|candidate:0 2 UDP 2122252542 10.49.58.114 51483 typ host)
[task 2023-10-24T17:43:26.752Z] 17:43:26     INFO -  ICE(IcePeer)/CAND-PAIR(dk41): Pairing candidate IP4:10.49.58.114:54644/UDP (7e7f00fe):IP4:10.49.58.114:51483/UDP (7e7f00fe) priority=9115005265987371517 (7e7f00fefcfe01fd)
[task 2023-10-24T17:43:26.752Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(Ooq4): setting pair to state FROZEN: Ooq4|IP4:10.49.58.114:60566/TCP|IP4:10.49.58.114:52057/TCP(host(IP4:10.49.58.114:60566/TCP) active|candidate:1 2 TCP 2105508094 10.49.58.114 52057 typ host tcptype passive)
[task 2023-10-24T17:43:26.752Z] 17:43:26     INFO -  ICE(IcePeer)/CAND-PAIR(Ooq4): Pairing candidate IP4:10.49.58.114:60566/TCP (7d7fc0fe):IP4:10.49.58.114:52057/TCP (7d7f80fe) priority=9043088409404342781 (7d7f80fefaff81fd)
[task 2023-10-24T17:43:26.753Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(M16f): setting pair to state FROZEN: M16f|IP4:10.49.58.114:54404/TCP|IP4:10.49.58.114:61330/TCP(host(IP4:10.49.58.114:54404/TCP) so|candidate:1 2 TCP 2105491710 10.49.58.114 61330 typ host tcptype so)
[task 2023-10-24T17:43:26.753Z] 17:43:26     INFO -  ICE(IcePeer)/CAND-PAIR(M16f): Pairing candidate IP4:10.49.58.114:54404/TCP (7d7f40fe):IP4:10.49.58.114:61330/TCP (7d7f40fe) priority=9043018040660099581 (7d7f40fefafe81fd)
[task 2023-10-24T17:43:26.753Z] 17:43:26     INFO -  ICE(IcePeer): peer (IcePeer) starting grace period timer for 5000 ms
[task 2023-10-24T17:43:26.753Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(k8Fj): setting pair to state WAITING: k8Fj|IP4:10.49.58.114:58021/UDP|IP4:10.49.58.114:50521/UDP(host(IP4:10.49.58.114:58021/UDP)|candidate:0 1 UDP 2122252543 10.49.58.114 50521 typ host)
[task 2023-10-24T17:43:26.754Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(QSez): setting pair to state WAITING: QSez|IP4:10.49.58.114:59003/TCP|IP4:10.49.58.114:65371/TCP(host(IP4:10.49.58.114:59003/TCP) active|candidate:1 1 TCP 2105508095 10.49.58.114 65371 typ host tcptype passive)
[task 2023-10-24T17:43:26.754Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/ICE-STREAM(IcePeer): Starting check timer for stream.
[task 2023-10-24T17:43:26.754Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(k8Fj): setting pair to state IN_PROGRESS: k8Fj|IP4:10.49.58.114:58021/UDP|IP4:10.49.58.114:50521/UDP(host(IP4:10.49.58.114:58021/UDP)|candidate:0 1 UDP 2122252543 10.49.58.114 50521 typ host)
[task 2023-10-24T17:43:26.754Z] 17:43:26     INFO -  ICE(IcePeer): peer (IcePeer) is now checking
<...>
[task 2023-10-24T17:43:26.797Z] 17:43:26     INFO -  ICE-PEER(IcePeer2)/ICE-STREAM(IcePeer2): all active components have nominated candidate pairs
[task 2023-10-24T17:43:26.797Z] 17:43:26     INFO -  ICE-PEER(IcePeer2): all checks completed success=1 fail=0
[task 2023-10-24T17:43:26.797Z] 17:43:26     INFO -  ICE(IcePeer2): peer (IcePeer2) cancelling grace period timer
[task 2023-10-24T17:43:26.797Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(Kj3P): setting pair to state IN_PROGRESS: Kj3P|IP4:10.49.58.114:52557/UDP|IP4:10.49.58.114:61198/UDP(host(IP4:10.49.58.114:52557/UDP)|candidate:0 2 UDP 2122252542 10.49.58.114 61198 typ host)
[task 2023-10-24T17:43:26.797Z] 17:43:26     INFO -  STUN-CLIENT(Kj3P|IP4:10.49.58.114:52557/UDP|IP4:10.49.58.114:61198/UDP(host(IP4:10.49.58.114:52557/UDP)|candidate:0 2 UDP 2122252542 10.49.58.114 61198 typ host)): Received response; processing
[task 2023-10-24T17:43:26.797Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(Kj3P): setting pair to state SUCCEEDED: Kj3P|IP4:10.49.58.114:52557/UDP|IP4:10.49.58.114:61198/UDP(host(IP4:10.49.58.114:52557/UDP)|candidate:0 2 UDP 2122252542 10.49.58.114 61198 typ host)
[task 2023-10-24T17:43:26.798Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/STREAM(IcePeer)/COMP(2)/CAND-PAIR(Kj3P): nominated pair is Kj3P|IP4:10.49.58.114:52557/UDP|IP4:10.49.58.114:61198/UDP(host(IP4:10.49.58.114:52557/UDP)|candidate:0 2 UDP 2122252542 10.49.58.114 61198 typ host)
[task 2023-10-24T17:43:26.798Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/STREAM(IcePeer)/COMP(2)/CAND-PAIR(Kj3P): cancelling all pairs but Kj3P|IP4:10.49.58.114:52557/UDP|IP4:10.49.58.114:61198/UDP(host(IP4:10.49.58.114:52557/UDP)|candidate:0 2 UDP 2122252542 10.49.58.114 61198 typ host)
[task 2023-10-24T17:43:26.798Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/STREAM(IcePeer)/COMP(2)/CAND-PAIR(PO2Z): cancelling FROZEN/WAITING pair PO2Z|IP4:10.49.58.114:58598/TCP|IP4:10.49.58.114:54215/TCP(host(IP4:10.49.58.114:58598/TCP) active|candidate:1 2 TCP 2105508094 10.49.58.114 54215 typ host tcptype passive) because CAND-PAIR(Kj3P) was nominated.
[task 2023-10-24T17:43:26.798Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(PO2Z): setting pair to state CANCELLED: PO2Z|IP4:10.49.58.114:58598/TCP|IP4:10.49.58.114:54215/TCP(host(IP4:10.49.58.114:58598/TCP) active|candidate:1 2 TCP 2105508094 10.49.58.114 54215 typ host tcptype passive)
[task 2023-10-24T17:43:26.799Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/STREAM(IcePeer)/COMP(2)/CAND-PAIR(4z6o): cancelling FROZEN/WAITING pair 4z6o|IP4:10.49.58.114:51924/TCP|IP4:10.49.58.114:64571/TCP(host(IP4:10.49.58.114:51924/TCP) so|candidate:1 2 TCP 2105491710 10.49.58.114 64571 typ host tcptype so) because CAND-PAIR(Kj3P) was nominated.
[task 2023-10-24T17:43:26.799Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/CAND-PAIR(4z6o): setting pair to state CANCELLED: 4z6o|IP4:10.49.58.114:51924/TCP|IP4:10.49.58.114:64571/TCP(host(IP4:10.49.58.114:51924/TCP) so|candidate:1 2 TCP 2105491710 10.49.58.114 64571 typ host tcptype so)
[task 2023-10-24T17:43:26.799Z] 17:43:26     INFO -  ICE-PEER(IcePeer)/ICE-STREAM(IcePeer): all active components have nominated candidate pairs
[task 2023-10-24T17:43:26.799Z] 17:43:26     INFO -  ICE-PEER(IcePeer): all checks completed success=1 fail=0
[task 2023-10-24T17:43:26.799Z] 17:43:26     INFO -  ICE(IcePeer): peer (IcePeer) cancelling grace period timer
[task 2023-10-24T17:43:26.799Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:49995/UDP denying ingress from IP4:127.0.0.1:60712/UDP: Not behind the same NAT
[task 2023-10-24T17:43:26.799Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:63756/UDP creating port mapping IP4:127.0.0.1:59467/UDP -> IP4:127.0.0.1:51500/UDP
[task 2023-10-24T17:43:26.800Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:51500/UDP denying ingress from IP4:127.0.0.1:59467/UDP: Not behind the same NAT
[task 2023-10-24T17:43:26.800Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:51500/UDP creating port mapping IP4:127.0.0.1:61957/UDP -> IP4:127.0.0.1:63756/UDP
[task 2023-10-24T17:43:26.800Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:63756/UDP denying ingress from IP4:127.0.0.1:61957/UDP: Not behind the same NAT
[task 2023-10-24T17:43:26.800Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:58221/UDP creating port mapping IP4:127.0.0.1:51119/UDP -> IP4:127.0.0.1:54349/UDP
[task 2023-10-24T17:43:26.800Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:63389/UDP creating port mapping IP4:127.0.0.1:56329/UDP -> IP4:127.0.0.1:63240/UDP
[task 2023-10-24T17:43:26.800Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:62007/UDP creating port mapping IP4:127.0.0.1:50675/UDP -> IP4:127.0.0.1:62379/UDP
[task 2023-10-24T17:43:26.801Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:62007/UDP denying ingress from IP4:127.0.0.1:54341/UDP: Filtered (no port mapping for source)
[task 2023-10-24T17:43:26.801Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:62007/UDP creating port mapping IP4:127.0.0.1:50675/UDP -> IP4:127.0.0.1:54341/UDP
[task 2023-10-24T17:43:26.801Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:60838/UDP creating port mapping IP4:127.0.0.1:57726/UDP -> IP4:127.0.0.1:61117/UDP
[task 2023-10-24T17:43:26.801Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:60838/UDP creating port mapping IP4:127.0.0.1:61940/UDP -> IP4:127.0.0.1:63311/UDP
[task 2023-10-24T17:43:26.801Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:58594/UDP creating port mapping IP4:127.0.0.1:51522/UDP -> IP4:127.0.0.1:61304/UDP
[task 2023-10-24T17:43:26.801Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:58594/UDP denying ingress from IP4:127.0.0.1:54546/UDP: Filtered (no port mapping for source)
[task 2023-10-24T17:43:26.801Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:58594/UDP creating port mapping IP4:127.0.0.1:60611/UDP -> IP4:127.0.0.1:54546/UDP
[task 2023-10-24T17:43:26.802Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:60698/UDP creating port mapping IP4:127.0.0.1:57840/UDP -> IP4:127.0.0.1:51015/UDP
[task 2023-10-24T17:43:26.802Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:50382/UDP creating port mapping IP4:127.0.0.1:62131/UDP -> IP4:127.0.0.1:57840/UDP
[task 2023-10-24T17:43:26.802Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:60698/UDP denying ingress from IP4:127.0.0.1:62131/UDP: Hairpinning disallowed
[task 2023-10-24T17:43:26.802Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:55351/UDP creating port mapping IP4:127.0.0.1:65149/UDP -> IP4:127.0.0.1:62030/UDP
[task 2023-10-24T17:43:26.802Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:64278/UDP creating port mapping IP4:127.0.0.1:59992/UDP -> IP4:127.0.0.1:65149/UDP
[task 2023-10-24T17:43:26.803Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:52767/UDP creating port mapping IP4:127.0.0.1:59022/UDP -> IP4:127.0.0.1:54429/UDP
[task 2023-10-24T17:43:26.803Z] 17:43:26     INFO -  TestNrSocket IP4:127.0.0.1:52767/UDP denying ingress from IP4:127.0.0.1:54429/UDP: Stale port mapping
[task 2023-10-24T17:43:26.803Z] 17:43:26  WARNING -  TEST-UNEXPECTED-FAIL | TestNrSocketTest.FullConeTimeout | test completed (time: 264ms)
[task 2023-10-24T17:43:26.803Z] 17:43:26     INFO -  TEST-START | TestNrSocketTest.PublicConnectivityTcp

Comment 1

[Noemi Erli[:noemi_erli]]

Fixed by the backout: https://hg.mozilla.org/integration/autoland/rev/9fe5bdf354caa00d21aab24def8841ee4b2abdab

Comment 2

[Noemi Erli[:noemi_erli]]

This is happening again with the reland of https://bugzilla.mozilla.org/show_bug.cgi?id=1845407

Comment 3

[Intermittent Failures Robot]

10 failures in 3657 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 3

Platform and build breakdown:

• macosx1015-64-qr: 8
  • debug: 6
  • opt: 2
• macosx1015-64-shippable-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1860858&startday=2023-10-23&endday=2023-10-29&tree=all

Comment 4

[Natalia Csoregi [:nataliaCs]]

Update

There have been 36 total failures since the bug has been reopened:

• 34 failures on OS X 10.15 WebRender debug/opt
• 2 failures on OS X 10.15 WebRender Shippable opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=434687834&repo=autoland&lineNumber=42175

Comment 5

[Intermittent Failures Robot]

52 failures in 3356 pushes (0.015 failures/push) were associated with this bug in the last 7 days.

This is the #33 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 9
• autoland: 38
• try: 5

Platform and build breakdown:

• macosx1015-64-qr: 45
  • debug: 29
  • opt: 16
• macosx1015-64-shippable-qr: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1860858&startday=2023-10-30&endday=2023-11-05&tree=all

Comment 6

[Intermittent Failures Robot]

27 failures in 3288 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 16
• mozilla-central: 9
• try: 2

Platform and build breakdown:

• macosx1015-64-qr: 22
  • debug: 14
  • opt: 8
• macosx1015-64-shippable-qr: 5
  • opt: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1860858&startday=2023-11-06&endday=2023-11-12&tree=all

Comment 7

[Natalia Csoregi [:nataliaCs]]

Update

There have been 34 total failures within the last 7 days:

• 30 failures on OS X 10.15 WebRender debug
• 4 failures on OS X 10.15 WebRender Shippable opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=436717185&repo=mozilla-central&lineNumber=45583

Comment 8

[Intermittent Failures Robot]

35 failures in 3495 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #45 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 9
• autoland: 24
• try: 2

Platform and build breakdown:

• macosx1015-64-qr: 31
  • debug: 16
  • opt: 15
• macosx1015-64-shippable-qr: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1860858&startday=2023-11-13&endday=2023-11-19&tree=all

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1860858&startday=2023-12-04&endday=2023-12-10&tree=all

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• macosx1015-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1860858&startday=2023-12-11&endday=2023-12-17&tree=all

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• pine: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1860858&startday=2023-12-18&endday=2023-12-24&tree=all

Comment 12

[Intermittent Failures Robot]

4 failures in 1162 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• pine: 4

Platform and build breakdown:

• macosx1015-64-qr: 2
  • debug: 2
• macosx1015-64-shippable-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1860858&startday=2023-12-25&endday=2023-12-31&tree=all

Comment 13

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.