981256 - Intermittent test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394239477041.543 <= 1394239476533 (508.54296875 ms)

Status: RESOLVED FIXED

(Core :: WebRTC, defect)

Description

[Phil Ringnalda (:philor)]

https://tbpl.mozilla.org/php/getParsedLog.php?id=35822002&tree=Mozilla-Inbound
Windows XP 32-bit mozilla-inbound debug test mochitest-3 on 2014-03-07 16:39:28 PST for push f80cd801c0e3
slave: t-xp32-ix-128

16:44:26     INFO -  240 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394239477041.543 >= 1394239455433 (21608.54296875 ms)
16:44:26     INFO -  241 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394239477041.543 <= 1394239476533 (508.54296875 ms)
16:44:26     INFO -  System JS : WARNING http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1608 - reference to undefined property counters[res.type]
16:44:26     INFO -  3636[1300480]: Flow[e5645937eb581070:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  3636[1300480]: Flow[239edd254a7c78d1:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  242 INFO TEST-INFO | dumping last 34 message(s)
16:44:26     INFO -  243 INFO TEST-INFO | if you need more context, please use SimpleTest.requestCompleteLog() in your test
16:44:26     INFO -  244 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Coherent stats id
16:44:26     INFO -  245 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtp timestamp 1394239466641.3794 >= 1394239455433 (11208.37939453125 ms)
16:44:26     INFO -  246 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtp timestamp 1394239466641.3794 <= 1394239476583 (-9941.62060546875 ms)
16:44:26     INFO -  247 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Ssrc has length
16:44:26     INFO -  248 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Ssrc not lengthy
16:44:26     INFO -  249 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Ssrc numeric
16:44:26     INFO -  250 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Ssrc within limits
16:44:26     INFO -  251 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtp packetsReceived
16:44:26     INFO -  252 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtp bytesReceived
16:44:26     INFO -  253 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Remote is rtcp
16:44:26     INFO -  254 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Remote backlink match
16:44:26     INFO -  255 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtcp is outbound
16:44:26     INFO -  256 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtcp packetsSent
16:44:26     INFO -  257 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtcp bytesSent
16:44:26     INFO -  258 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Remote ssrc match
16:44:26     INFO -  259 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Coherent stats id
16:44:26     INFO -  260 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtp timestamp 1394239466641.3794 >= 1394239455433 (11208.37939453125 ms)
16:44:26     INFO -  261 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtp timestamp 1394239466641.3794 <= 1394239476586 (-9944.62060546875 ms)
16:44:26     INFO -  262 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Ssrc has length
16:44:26     INFO -  263 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Ssrc not lengthy
16:44:26     INFO -  264 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Ssrc numeric
16:44:26     INFO -  265 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Ssrc within limits
16:44:26     INFO -  266 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtp packetsSent
16:44:26     INFO -  267 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtp bytesSent
16:44:26     INFO -  268 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Remote is rtcp
16:44:26     INFO -  269 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Remote backlink match
16:44:26     INFO -  270 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtcp is inbound
16:44:26     INFO -  271 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtcp packetsReceived
16:44:26     INFO -  272 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtcp packetsLost
16:44:26     INFO -  273 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtcp bytesReceived
16:44:26     INFO -  274 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Rtcp jitter
16:44:26     INFO -  275 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Remote ssrc match
16:44:26     INFO -  276 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Coherent stats id
16:44:26     INFO -  277 INFO TEST-PASS | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394239477041.543 >= 1394239455433 (21608.54296875 ms)
16:44:26     INFO -  278 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394239477041.543 <= 1394239476588 (453.54296875 ms)
16:44:26     INFO -  3636[1300480]: Flow[e5645937eb581070:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  System JS : WARNING http://mochi.test:8888/tests/dom/media/tests/mochitest/pc.js:1662 - reference to undefined property counters2[res.type]
16:44:26     INFO -  3636[1300480]: Flow[239edd254a7c78d1:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  3636[1300480]: Flow[e5645937eb581070:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  3636[1300480]: Flow[239edd254a7c78d1:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  3636[1300480]: Flow[e5645937eb581070:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  3636[1300480]: Flow[239edd254a7c78d1:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  3636[1300480]: Flow[e5645937eb581070:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  3636[1300480]: Flow[239edd254a7c78d1:1,rtp(none)]; Layer[dtls]: Would have blocked
16:44:26     INFO -  0[28ab98]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1596: sipcc::PeerConnectionImpl::CloseInt: Closing PeerConnectionImpl 239edd254a7c78d1; ending call
16:44:26     INFO -  0[f448c68]: [CCAPP Task|def] ccapi.c:1445: SIPCC-CC_API: 1/20, cc_int_onhook: UI -> GSM: ONHOOK
16:44:26     INFO -  0[f448c68]: [CCAPP Task|def] ccapi.c:1447: (1/20) On-hook called from c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/media/webrtc/signaling/src/sipcc/core/ccapp/ccprovider.c:657
16:44:26     INFO -  0[c155850]: [GSM Task|def] dcsm.c:532: SIPCC-DCSM: dcsm_process_event: DCSM 11  :(DCSM_READY:ONHOOK )
16:44:26     INFO -  0[c155850]: [GSM Task|fsm_sm] sm.c:46: SIPCC-FSM: sm_process_event: DEF 20  : 01EF7B2Fx: sm entry: (STABLE:ONHOOK)
16:44:26     INFO -  0[28ab98]: [main|PeerConnectionImpl] PeerConnectionImpl.cpp:1596: sipcc::PeerConnectionImpl::CloseInt: Closing PeerConnectionImpl e5645937eb581070; ending call
16:44:26     INFO -  0[c155850]: [GSM Task|fsm_sm] fsmdef.c:6711: SIPCC-FSM: fsmdef_ev_onhook: Entered.
16:44:26     INFO -  0[f448c68]: [CCAPP Task|def] ccapi.c:1445: SIPCC-CC_API: 1/21, cc_int_onhook: UI -> GSM: ONHOOK
16:44:26     INFO -  0[c155850]: [GSM Task|fsm_sm] fsmdef.c:1986: SIPCC-FSM: 1/20, fsmdef_release: Entered. cause= NORMAL
16:44:26     INFO -  0[f448c68]: [CCAPP Task|def] ccapi.c:1447: (1/21) On-hook called from c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/media/webrtc/signaling/src/sipcc/core/ccapp/ccprovider.c:657
16:44:26     INFO -  0[c155850]: [GSM Task|fsm_sm] fsmdef.c:9197: SIPCC-FSM: fsmdef_notify_hook_event: Entered.
16:44:26     INFO -  0[c155850]: [GSM Task|def] ccapi.c:1445: SIPCC-CC_API: 1/20, cc_int_onhook: GSM -> SIP: ONHOOK
16:44:26     INFO -  0[c155850]: [GSM Task|def] ccapi.c:1447: (1/20) On-hook called from c:/builds/moz2_slave/m-in-w32-d-0000000000000000000/build/media/webrtc/signaling/src/sipcc/core/gsm/fsmdef.c:9206
16:44:26     INFO -  0[c155850]: [GSM Task|tnp] ui.c:1023: SIPCC-UI_API: ui_get_idle_prompt_string: called
16:44:26     INFO -  0[c155850]: [GSM Task|tnp] ui.c:654: SIPCC-UI_API: 1/20, ui_set_call_status: the stat string =
16:44:26     INFO -  0[c155850]: [GSM Task|tnp] ui.c:616: SIPCC-UI_API: 1/20, ui_set_call_status_display: the stat string =, timeout= 0, priority=0
16:44:26     INFO -  0[c155850]: [GSM Task|tnp] ui.c:104: SIPCC-UI_API: 1/20, ui_call_state: event=1
16:44:26     INFO -  0[c155850]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 1/20, fsm_change_state: DEF: STABLE -> CLOSED
16:44:26     INFO -  0[f448c68]: [CCAPP Task|def] ccprovider.c:1613: SIPCC-SIP_CC_PROV: ccappUpdateSessionData: Call_STATE:. state=1, attr=0, cause=5, instance=0
16:44:26     INFO -  0[c155850]: [GSM Task|def] dcsm.c:397: SIPCC-DCSM: dcsm_update_gsm_state: 20 : DCSM_READY --> DCSM_READY
16:44:26     INFO -  0[f448c68]: [CCAPP Task|CC_SIPCCService] CC_SIPCCService.cpp:724: onCallEvent(CCAPI_CALL_EV_STATE, 10014, [ONHOOK|]
16:44:26     INFO -  0[c155850]: [GSM Task|fsm_sm] fsmdef.c:1293: SIPCC-FSM: 0/0, fsmdef_init_dcb: call_not_counted_in_mnc_bt = FALSE
16:44:26     INFO -  0[f448c68]: [CCAPP Task|def] ccapi_call.c:57: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0F0D59B8: count=2
16:44:26     INFO -  0[c155850]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/20, fsm_change_state: DEF: CLOSED -> IDLE
16:44:26     INFO -  0[f448c68]: [CCAPP Task|def] ccapi_call.c:57: SIPCC-SIP_CC_PROV: CCAPI_Call_releaseCallInfo: ref=0DB80718: count=1
16:44:26     INFO -  0[c155850]: [GSM Task|def] dcsm.c:397: SIPCC-DCSM: dcsm_update_gsm_state: 20 : DCSM_READY --> DCSM_READY
16:44:26     INFO -  0[c155850]: [GSM Task|def] sm.c:65: SIPCC-GSM: 1/20, sm_process_event: DEF   :(STABLE:ONHOOK )
16:44:26     INFO -  0[c155850]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 20  : fsm_get_fcb_by_call_id_and_type    : fcb= 0C1595B0
16:44:26     INFO -  0[c155850]: [GSM Task|fsm_sm] fsm.c:157: SIPCC-GSM_DBG_PTR: FSM 20  : fsm_get_fcb_by_call_id_and_type    : fcb= 00000000
16:44:26     INFO -  0[c155850]: [GSM Task|def] fsm.c:346: SIPCC-FSM: 0/20, fsm_change_state: CNF: IDLE -> IDLE
...

https://tbpl.mozilla.org/php/getParsedLog.php?id=35826976&tree=Mozilla-Central
Windows XP 32-bit mozilla-central debug test mochitest-3 on 2014-03-07 18:40:46 PST for push 3bb690f96b92
slave: t-xp32-ix-126

241 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394246747147.9841 <= 1394246744899 (2248.984130859375 ms)

Comment 1

[Legacy TBPL/Treeherder Robot]

RyanVM
https://tbpl.mozilla.org/php/getParsedLog.php?id=36007870&tree=Fx-Team
Windows XP 32-bit fx-team debug test mochitest-3 on 2014-03-12 09:56:28
revision: 56ac183ad1e2
slave: t-xp32-ix-059

240 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394643655331.43 <= 1394643650673 (4658.429931640625 ms)
277 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394643655331.43 <= 1394643650720 (4611.429931640625 ms)
Return code: 1

Comment 2

[Legacy TBPL/Treeherder Robot]

KWierso
https://tbpl.mozilla.org/php/getParsedLog.php?id=36167381&tree=Mozilla-Inbound
Windows XP 32-bit mozilla-inbound debug test mochitest-3 on 2014-03-14 15:38:11
revision: f8a9b4867802
slave: t-xp32-ix-059

241 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394836959236.275 <= 1394836956581 (2655.27490234375 ms)
278 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394836959236.275 <= 1394836956611 (2625.27490234375 ms)
Return code: 1

Comment 3

[Legacy TBPL/Treeherder Robot]

KWierso
https://tbpl.mozilla.org/php/getParsedLog.php?id=36181316&tree=Mozilla-Inbound
Windows XP 32-bit mozilla-inbound opt test mochitest-3 on 2014-03-14 20:00:18
revision: 80fad01e53bb
slave: t-xp32-ix-120

873 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394852600596.97 <= 1394852600530 (66.969970703125 ms)
912 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1394852600596.97 <= 1394852600579 (17.969970703125 ms)
1021 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudioVideo.html | Valid rtcp timestamp 1394852601638.97 <= 1394852601490 (148.969970703125 ms)
1154 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1394852607017.97 <= 1394852606882 (135.969970703125 ms)
1193 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1394852607017.97 <= 1394852606930 (87.969970703125 ms)
1242 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1394852606986.97 <= 1394852606951 (35.969970703125 ms)
Return code: 1

Comment 4

[Jan-Ivar Bruaroey [:jib] (needinfo? me)]

All XP. Fixed by disabling test on XP in Bug 970686.

XP clock drift issue is Bug 979649.

Comment 5

[Legacy TBPL/Treeherder Robot]

Tomcat
https://tbpl.mozilla.org/php/getParsedLog.php?id=37267050&tree=Fx-Team
Windows 7 32-bit fx-team debug test mochitest-3 on 2014-04-04 03:07:31
revision: e8427c835367
slave: t-w732-ix-006

325 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1396606263078.25 >= 1396606264564 (-1485.75 ms)
367 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1396606263077.25 >= 1396606264564 (-1486.75 ms)
416 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1396606263046.25 >= 1396606264829 (-1782.75 ms)
525 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudioVideoCombined.html | Valid rtcp timestamp 1396606266513.25 >= 1396606267705 (-1191.75 ms)
587 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudioVideoCombined.html | Valid rtcp timestamp 1396606266513.25 >= 1396606267705 (-1191.75 ms)
710 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1396606270788.25 >= 1396606272506 (-1717.75 ms)
752 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1396606270787.25 >= 1396606272506 (-1718.75 ms)
801 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1396606270758.25 >= 1396606272930 (-2171.75 ms)
Return code: 1

Comment 6

[Legacy TBPL/Treeherder Robot]

philor
https://tbpl.mozilla.org/php/getParsedLog.php?id=37266803&tree=Mozilla-Aurora
Windows 7 32-bit mozilla-aurora debug test mochitest-3 on 2014-04-04 03:08:22
revision: e89c8841b036
slave: t-w732-ix-053

241 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1396606332372.603 >= 1396606334934 (-2561.39697265625 ms)
283 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1396606332372.603 >= 1396606334934 (-2561.39697265625 ms)
416 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1396606339665.603 >= 1396606343512 (-3846.39697265625 ms)
458 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1396606339665.603 >= 1396606343512 (-3846.39697265625 ms)
Return code: 1

Comment 7

[Legacy TBPL/Treeherder Robot]

edmorley
https://tbpl.mozilla.org/php/getParsedLog.php?id=37266796&tree=Mozilla-Aurora
WINNT 6.2 mozilla-aurora debug test mochitest-3 on 2014-04-04 03:08:32
revision: e89c8841b036
slave: t-w864-ix-025

241 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1396606360222.491 >= 1396606362408 (-2185.509033203125 ms)
283 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1396606360222.491 >= 1396606362408 (-2185.509033203125 ms)
332 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_basicAudio.html | Valid rtcp timestamp 1396606360195.491 >= 1396606362577 (-2381.509033203125 ms)
464 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1396606368906.491 >= 1396606370981 (-2074.509033203125 ms)
506 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1396606368906.491 >= 1396606370981 (-2074.509033203125 ms)
555 INFO TEST-UNEXPECTED-FAIL | /tests/dom/media/tests/mochitest/test_peerConnection_bug827843.html | Valid rtcp timestamp 1396606368877.491 >= 1396606371410 (-2532.509033203125 ms)
Return code: 1

Comment 8

[Jan-Ivar Bruaroey [:jib] (needinfo? me)]

Sigh, now Windows 7. This is the same serious multi-second growing "drift" we've only seen on XP till now.

Did some new webrtc test land around 2014-04-04 03am?

The test that fails is that rtcp timestamps must not be before connection opened, with a 1 second cushion. How well are tests isolated? 4 seconds is enough to make me think we're seeing rtcp packets from an 
earlier test (though it's curious why this would happen only on Windows).

Re-open or new bug?

Comment 9

[Phil Ringnalda (:philor)]

The way to answer "was that a code change?" is to open the link to the log in the comment in the bug, then click the "for push (cset hash)" link at the top of the log, which opens tbpl for that push, then hit the down-arrow on tbpl. But... that's going to give you fx-team in the middle of the night, plus mozilla-aurora about 70 seconds later. Extremely unlikely.

Better bets? A scheduled task, since 3am is the sort of time that Windows likes to schedule things; a failure to disable adjusting the clock while things are running; a timebomb in your code; an uncaught failure in a previous test. The latter is certainly likely: there's pretty much no outside enforcement of isolation between tests, so if your code doesn't have a way to synchronously shut everything down, or if it does but your tests don't use it at the end of every test, well, you're just being loaded in an iframe on top of the previous test.

Comment 10

[Randell Jesup [:jesup] (needinfo me)]

I agree: leave closed for now unless we get more reports.

Thanks philor