1446120 - Intermittent dom/media/webaudio/test/test_gainNodeInLoop.html | maxDifference: 0.5, first bad index: 0 with test-data offset 0 and expected-data offset 0; corresponding values 1 and 0.5 --- differences - got 96, expected +0

Status: RESOLVED INCOMPLETE

(Core :: Web Audio, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ccoroiu [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=168273691&repo=autoland

https://queue.taskcluster.net/v1/task/SOGUZuluSjGlEN6e2uvY7Q/runs/0/artifacts/public/logs/live_backing.log

18:20:21     INFO -  2177 INFO TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | Correct number of expected frames
18:20:21     INFO -  Buffered messages finished
18:20:21    ERROR -  2178 INFO TEST-UNEXPECTED-FAIL | dom/media/webaudio/test/test_gainNodeInLoop.html | maxDifference: 0.5, first bad index: 0 with test-data offset 0 and expected-data offset 0; corresponding values 1 and 0.5 --- differences - got 96, expected +0
18:20:21     INFO -      SimpleTest.is@SimpleTest/SimpleTest.js:312:5
18:20:21     INFO -      compareChannels@dom/media/webaudio/test/webaudio.js:78:3
18:20:21     INFO -      compareBuffers@dom/media/webaudio/test/webaudio.js:103:5
18:20:21     INFO -      testOutput/sp.onaudioprocess@dom/media/webaudio/test/webaudio.js:214:11
18:20:21     INFO -      EventHandlerNonNull*testOutput@dom/media/webaudio/test/webaudio.js:211:9
18:20:21     INFO -      runTestOnContext@dom/media/webaudio/test/webaudio.js:202:9
18:20:21     INFO -      testOnNormalContext@dom/media/webaudio/test/webaudio.js:222:7
18:20:21     INFO -      runTestFunction@dom/media/webaudio/test/webaudio.js:253:5
18:20:21     INFO -      rval@SimpleTest/SimpleTest.js:146:17
18:20:21     INFO -      EventHandlerNonNull*this.addLoadEvent@SimpleTest/SimpleTest.js:171:13
18:20:21     INFO -      @SimpleTest/SimpleTest.js:1434:5
18:20:21     INFO -  2179 INFO TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | Correct number of channels for expected buffer 0
18:20:21     INFO -  2180 INFO TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | Correct number of expected frames
18:20:21     INFO -  2181 INFO TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | Correct number of input buffer channels
18:20:21     INFO -  2182 INFO TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | maxDifference: 0, first bad index: -1 with test-data offset 0 and expected-data offset 0; corresponding values undefined and undefined --- differences
18:20:21     INFO -  2183 INFO TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | Correct number of channels for expected buffer 0
18:20:21     INFO -  2184 INFO TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | Correct number of expected frames
18:20:21     INFO -  2185 INFO TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | Correct number of input buffer channels
18:20:21     INFO -  2186 INFO TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | maxDifference: 0, first bad index: -1 with test-data offset 0 and expected-data offset 0; corresponding values undefined and undefined --- differences
18:20:21     INFO -  GECKO(7444) | MEMORY STAT | vsize 2098775MB | vsizeMaxContiguous 127886041MB | residentFast 131MB | heapAllocated 23MB

Comment 1

[Intermittent Failures Robot]

5 failures in 814 pushes (0.006 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 3
* try: 1
* mozilla-central: 1

Platform breakdown:
* windows10-64-qr: 2
* windows7-32: 1
* windows10-64: 1
* linux64-stylo-disabled: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1446120&startday=2018-03-12&endday=2018-03-18&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 4
* mozilla-inbound: 3
* mozilla-central: 2

Platform breakdown:
* linux64: 4
* windows7-32: 2
* windows10-64-ccov: 1
* osx-10-10: 1
* android-4-3-armv7-api16: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1446120&startday=2018-03-20&endday=2018-03-26&tree=trunk

Comment 3

[Intermittent Failures Robot]

22 failures in 161 pushes (0.137 failures/push) were associated with this bug yesterday.    

Repository breakdown:
* autoland: 11
* mozilla-inbound: 6
* mozilla-central: 5

Platform breakdown:
* linux64-qr: 6
* windows10-64-qr: 4
* linux64: 3
* linux32: 3
* windows7-32: 2
* windows10-64: 2
* macosx64-nightly: 1
* linux64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1446120&startday=2018-03-28&endday=2018-03-28&tree=trunk

Comment 4

[Tiberius Oros[:tiberius_oros]]

There have been 42 failures in the last week.
Summary: Intermittent dom/media/webaudio/test/test_gainNodeInLoop.html | maxDifference: 0.5, first bad index: 0 with test-data offset 0 and expected-data offset 0; corresponding values 1 and 0.5 --- differences - got 96, expected +0

Affected platforms and builds:
- Linux x64 / debug & opt & pgo & asan: 11
- windows10-64-qr / debug & opt : 6
- linux64-qr / opt & debug: 6
- Linux / opt: 5
- windows10-64 / pgo & opt: 4
- Windows 7 / debug & opt: 4
- OS X 10.10 /debug & opt: 2
- macosx64-nightly /opt : 1
- linux64-nightly /opt: 1
- windows10-64-ccov / debug: 1
- android-4-3-armv7-api16 / opt: 1

Recent log file and snippet with the failure:
https://treeherder.mozilla.org/logviewer.html#?repo=mozilla-inbound&job_id=170944144&lineNumber=16690

[task 2018-03-29T01:18:29.986Z] 01:18:29     INFO - TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | Correct number of expected frames 
[task 2018-03-29T01:18:29.987Z] 01:18:29     INFO - TEST-PASS | dom/media/webaudio/test/test_gainNodeInLoop.html | Correct number of input buffer channels 
[task 2018-03-29T01:18:29.989Z] 01:18:29     INFO - Buffered messages finished
[task 2018-03-29T01:18:29.990Z] 01:18:29     INFO - TEST-UNEXPECTED-FAIL | dom/media/webaudio/test/test_gainNodeInLoop.html | maxDifference: 0.5, first bad index: 0 with test-data offset 0 and expected-data offset 0; corresponding values 1 and 0.5 --- differences - got 4, expected +0
[task 2018-03-29T01:18:29.992Z] 01:18:29     INFO -     SimpleTest.is@SimpleTest/SimpleTest.js:312:5
[task 2018-03-29T01:18:29.993Z] 01:18:29     INFO -     compareChannels@dom/media/webaudio/test/webaudio.js:78:3

Comment 5

[Intermittent Failures Robot]

38 failures in 718 pushes (0.053 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:
* autoland: 21
* mozilla-inbound: 9
* mozilla-central: 8

Platform breakdown:
* linux64-qr: 8
* linux64: 8
* windows10-64-qr: 7
* linux32: 5
* windows10-64: 4
* windows7-32: 3
* osx-10-10: 1
* macosx64-nightly: 1
* linux64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1446120&startday=2018-03-26&endday=2018-04-01&tree=trunk

Comment 6

[Arthur Iakab [arthur_iakab]]

:drno Do you have any updates on this ? Could be related to Bug 1446456 which has a high failure rate.

Comment 7

[Nils Ohlmeier [:drno]]

Asked Alex over in bug 1446346 to see if he can find a shared root cause for all the webaudio intermittents.

Comment 8

[Alex Chronopoulos [:achronop]]

Logs from a try run with webaudio flag enabled:

https://taskcluster-artifacts.net/PwvkC2JcR_-PF9GoW8JdFA/0/public/logs/live_backing.log

The problem at the time value of method SetValueAtTime:
[task 2018-04-10T15:11:06.569Z] 15:11:06     INFO - TEST-START | dom/media/webaudio/test/test_gainNodeInLoop.html
...
[task 2018-04-10T15:11:06.752Z] 15:11:06     INFO - GECKO(2160) | [2210:Main Thread]: D/WebAudioAPI 0.000100: gain for 1934 SetValueAtTime value=0.5 time=0.000100 constant=0
...
[task 2018-04-10T15:11:07.317Z] 15:11:07     INFO - TEST-UNEXPECTED-FAIL | dom/media/webaudio/test/test_gainNodeInLoop.html | maxDifference: 0.5, first bad index: 0 with test-data offset 0 and expected-data offset 0; corresponding values 1 and 0.5 --- differences - got 4, expected +0

On a working case: https://taskcluster-artifacts.net/IfHGQwb4RgKLUpu9e26aaQ/0/public/logs/live_backing.log
[task 2018-04-10T15:07:51.316Z] 15:07:51     INFO - GECKO(2148) | [2201:Main Thread]: D/WebAudioAPI 0.000000: gain for 1934 SetValueAtTime value=0.5 time=0.000000 constant=0

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* android-4-3-armv7-api16: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1446120&startday=2018-04-09&endday=2018-04-15&tree=trunk

Comment 10

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup