1597254 - Intermittent dom/media/tests/mochitest/<random_test> | Valid rtp timestamp X <= (>=) Y(

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=276724783&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/UvSL0CGLRZOpaIwe8erQSQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-11-18T12:07:49.724Z] 12:07:49 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Element ended should be the inverse of the MediaStream's active state
[task 2019-11-18T12:07:49.731Z] 12:07:49 INFO - TEST-FAIL | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | The author of the test has indicated that flaky timeouts are expected. Reason: WebRTC inherently depends on timeouts
[task 2019-11-18T12:07:49.731Z] 12:07:49 INFO - Found transceiver that should be receiving RTP: mid=0 currentDirection=sendrecv kind=audio track-id={cd4d361d-d09b-8d45-9d96-043e3abf388c}
[task 2019-11-18T12:07:49.731Z] 12:07:49 INFO - waitForRtpFlow({cd4d361d-d09b-8d45-9d96-043e3abf388c})
[task 2019-11-18T12:07:49.731Z] 12:07:49 INFO - waitForRtpFlow({1805d0d7-1582-9b4b-9d9f-b90a6cb4efd1})
[task 2019-11-18T12:07:49.732Z] 12:07:49 INFO - Element local{1805d0d7-1582-9b4b-9d9f-b90a6cb4efd1} has enough data.
[task 2019-11-18T12:07:49.732Z] 12:07:49 INFO - Element remote{cd4d361d-d09b-8d45-9d96-043e3abf388c} has enough data.
[task 2019-11-18T12:07:49.732Z] 12:07:49 INFO - Checking for stats in [["BkWp",{"id":"BkWp","timestamp":1574078872581,"type":"candidate-pair","bytesReceived":6981,"bytesSent":6964,"lastPacketReceivedTimestamp":1574078869398,"lastPacketSentTimestamp":1574078869398,"localCandidateId":"Ssia","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"DvFV","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["Ssia",{"id":"Ssia","timestamp":1574078872581,"type":"local-candidate","address":"6e503757-1094-47c1-8ebe-3df0d1ce1c36.local","candidateType":"host","port":57158,"priority":2122252543,"protocol":"udp"}],["ogK0",{"id":"ogK0","timestamp":1574078872581,"type":"local-candidate","address":"6e503757-1094-47c1-8ebe-3df0d1ce1c36.local","candidateType":"host","port":60857,"priority":2105524479,"protocol":"tcp"}],["DvFV",{"id":"DvFV","timestamp":1574078872581,"type":"remote-candidate","address":"bd967b70-2640-4e08-b026-1024b4dfad32.local","candidateType":"host","port":52936,"priority":2122252543,"protocol":"udp"}],["ZX1s",{"id":"ZX1s","timestamp":1574078872581,"type":"remote-candidate","address":"bd967b70-2640-4e08-b026-1024b4dfad32.local","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"}],["inbound_rtp_audio_0",{"id":"inbound_rtp_audio_0","timestamp":1574078872581,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":126802898,"jitter":0,"packetsLost":0,"packetsReceived":30,"bytesReceived":5590,"nackCount":0,"remoteId":"inbound_rtcp_audio_0"}],["inbound_rtcp_audio_0",{"id":"inbound_rtcp_audio_0","timestamp":1574078872031,"type":"remote-outbound-rtp","kind":"audio","mediaType":"audio","ssrc":126802898,"bytesSent":438,"packetsSent":2,"localId":"inbound_rtp_audio_0"}]] for audio track {cd4d361d-d09b-8d45-9d96-043e3abf388c}retry number 0
[task 2019-11-18T12:07:49.732Z] 12:07:49 INFO - Should have RTP stats for track {cd4d361d-d09b-8d45-9d96-043e3abf388c}
[task 2019-11-18T12:07:49.732Z] 12:07:49 INFO - RTP stats: {"id":"inbound_rtp_audio_0","timestamp":1574078872581,"type":"inbound-rtp","kind":"audio","mediaType":"audio","ssrc":126802898,"jitter":0,"packetsLost":0,"packetsReceived":30,"bytesReceived":5590,"nackCount":0,"remoteId":"inbound_rtcp_audio_0"}
[task 2019-11-18T12:07:49.733Z] 12:07:49 INFO - Track {cd4d361d-d09b-8d45-9d96-043e3abf388c} has 30 inbound-rtp RTP packets.
[task 2019-11-18T12:07:49.733Z] 12:07:49 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | RTP flowing for audio track {cd4d361d-d09b-8d45-9d96-043e3abf388c}
[task 2019-11-18T12:07:49.733Z] 12:07:49 INFO - Checking for stats in [["BkWp",{"id":"BkWp","timestamp":1574078872581,"type":"candidate-pair","bytesReceived":6981,"bytesSent":6964,"lastPacketReceivedTimestamp":1574078869398,"lastPacketSentTimestamp":1574078869398,"localCandidateId":"Ssia","nominated":true,"priority":9115005270282338000,"readable":true,"remoteCandidateId":"DvFV","selected":true,"state":"succeeded","transportId":"transport_0","writable":true}],["Ssia",{"id":"Ssia","timestamp":1574078872581,"type":"local-candidate","address":"6e503757-1094-47c1-8ebe-3df0d1ce1c36.local","candidateType":"host","port":57158,"priority":2122252543,"protocol":"udp"}],["ogK0",{"id":"ogK0","timestamp":1574078872581,"type":"local-candidate","address":"6e503757-1094-47c1-8ebe-3df0d1ce1c36.local","candidateType":"host","port":60857,"priority":2105524479,"protocol":"tcp"}],["DvFV",{"id":"DvFV","timestamp":1574078872581,"type":"remote-candidate","address":"bd967b70-2640-4e08-b026-1024b4dfad32.local","candidateType":"host","port":52936,"priority":2122252543,"protocol":"udp"}],["ZX1s",{"id":"ZX1s","timestamp":1574078872581,"type":"remote-candidate","address":"bd967b70-2640-4e08-b026-1024b4dfad32.local","candidateType":"host","port":9,"priority":2105524479,"protocol":"tcp"}],["outbound_rtp_audio_0",{"id":"outbound_rtp_audio_0","timestamp":1574078872581,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":4017159908,"bytesSent":5590,"packetsSent":30,"nackCount":0,"remoteId":""}]] for audio track {1805d0d7-1582-9b4b-9d9f-b90a6cb4efd1}retry number 0
[task 2019-11-18T12:07:49.734Z] 12:07:49 INFO - Should have RTP stats for track {1805d0d7-1582-9b4b-9d9f-b90a6cb4efd1}
[task 2019-11-18T12:07:49.734Z] 12:07:49 INFO - RTP stats: {"id":"outbound_rtp_audio_0","timestamp":1574078872581,"type":"outbound-rtp","kind":"audio","mediaType":"audio","ssrc":4017159908,"bytesSent":5590,"packetsSent":30,"nackCount":0,"remoteId":""}
[task 2019-11-18T12:07:49.734Z] 12:07:49 INFO - Track {1805d0d7-1582-9b4b-9d9f-b90a6cb4efd1} has 30 outbound-rtp RTP packets.
[task 2019-11-18T12:07:49.734Z] 12:07:49 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | RTP flowing for audio track {1805d0d7-1582-9b4b-9d9f-b90a6cb4efd1}
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - Run step 41: PC_LOCAL_CHECK_STATS
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - PeerConnectionWrapper (pcLocal): Got stats: {}
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - Checking stats for eyeJ : [object Object]
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Coherent stats id
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Valid rtp timestamp 1574078872699 >= 1574078868432 (
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - 4267 ms)
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - Buffered messages finished
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Valid rtp timestamp 1574078872699 <= 1574078869774 (
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - 2925 ms)
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:277:18
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - checkStats@https://example.com/tests/dom/media/tests/mochitest/pc.js:2106:11
[task 2019-11-18T12:07:49.735Z] 12:07:49 INFO - PC_LOCAL_CHECK_STATS/<@https://example.com/tests/dom/media/tests/mochitest/templates.js:522:20
[task 2019-11-18T12:07:49.742Z] 12:07:49 INFO - Checking stats for edvq : [object Object]
[task 2019-11-18T12:07:49.743Z] 12:07:49 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Coherent stats id
[task 2019-11-18T12:07:49.743Z] 12:07:49 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Valid rtp timestamp 1574078872699 >= 1574078868432 (
[task 2019-11-18T12:07:49.743Z] 12:07:49 INFO - 4267 ms)
[task 2019-11-18T12:07:49.743Z] 12:07:49 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-11-18T12:07:49.743Z] 12:07:49 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Valid rtp timestamp 1574078872699 <= 1574078869775 (
[task 2019-11-18T12:07:49.743Z] 12:07:49 INFO - 2924 ms)
[task 2019-11-18T12:07:49.743Z] 12:07:49 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:277:18
[task 2019-11-18T12:07:49.743Z] 12:07:49 INFO - checkStats@https://example.com/tests/dom/media/tests/mochitest/pc.js:2106:11
[task 2019-11-18T12:07:49.744Z] 12:07:49 INFO - PC_LOCAL_CHECK_STATS/<@https://example.com/tests/dom/media/tests/mochitest/templates.js:522:20
[task 2019-11-18T12:07:49.744Z] 12:07:49 INFO - Checking stats for K487 : [object Object]
[task 2019-11-18T12:07:49.744Z] 12:07:49 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Coherent stats id
[task 2019-11-18T12:07:49.744Z] 12:07:49 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Valid rtp timestamp 1574078872699 >= 1574078868432 (
[task 2019-11-18T12:07:49.744Z] 12:07:49 INFO - 4267 ms)
[task 2019-11-18T12:07:49.744Z] 12:07:49 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-11-18T12:07:49.745Z] 12:07:49 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_removeThenAddAudioTrackNoBundle.html | Valid rtp timestamp 1574078872699 <= 1574078869775 (

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• autoland: 8

Platform breakdown:

• macosx1014-64-shippable: 2
• macosx1014-64: 4
• windows7-32-shippable: 2
• android-hw-p2-8-0-arm7-api-16: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2019-11-18&endday=2019-11-24&tree=all

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• try: 1

Platform breakdown:

• macosx1014-64: 3
• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2019-11-25&endday=2019-12-01&tree=all

Comment 12

[Intermittent Failures Robot]

6 failures in 5144 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5
• try: 1

Platform breakdown:

• macosx1014-64: 4
• macosx1014-64-shippable: 1
• macosx1014-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2019-12-02&endday=2019-12-08&tree=all

Comment 13

[Intermittent Failures Robot]

6 failures in 4867 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 2
• autoland: 4

Platform breakdown:

• macosx1014-64-devedition: 2
• android-hw-p2-8-0-arm7-api-16: 1
• macosx1014-64: 2
• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2019-12-09&endday=2019-12-15&tree=all

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 7
• mozilla-central: 1

Platform breakdown:

• macosx1014-64: 4
• macosx1014-64-shippable: 2
• windows7-32-shippable: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2019-12-16&endday=2019-12-22&tree=all

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• try: 1
• mozilla-release: 1

Platform breakdown:

• macosx1014-64: 1
• macosx1014-64-shippable: 1
• windows7-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2019-12-30&endday=2020-01-05&tree=all

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 10
• mozilla-beta: 1

Platform breakdown:

• macosx1014-64: 8
• macosx1014-64-shippable: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2020-01-06&endday=2020-01-12&tree=all

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows7-32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2020-01-20&endday=2020-01-26&tree=all

Comment 23

[Intermittent Failures Robot]

2 failures in 2190 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• mozilla-beta: 1

Platform breakdown:

• windows7-32-shippable: 1
• windows7-32-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2020-01-27&endday=2020-02-02&tree=all

Comment 24

[Intermittent Failures Robot]

2 failures in 3364 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform breakdown:

• windows7-32-shippable: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2020-02-03&endday=2020-02-09&tree=all

Comment 25

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2020-02-17&endday=2020-02-23&tree=all

Comment 26

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-hw-p2-8-0-android-aarch64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2020-02-24&endday=2020-03-01&tree=all

Comment 27

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

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

Comment 28

[Natalia Csoregi [:nataliaCs]]

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=297368020&repo=autoland&lineNumber=25963

Comment 29

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2020-04-13&endday=2020-04-19&tree=all

Comment 30

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

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

Comment 31

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1597254&startday=2020-07-27&endday=2020-08-02&tree=all