If you think a bug might affect users in the 57 release, please set the correct tracking and status flags for Release Management.

Intermittent testVideoControls | testVideoControls.js - Bar 1 is white

NEW
Unassigned

Status

()

Firefox for Android
General
P3
normal
3 years ago
a year ago

People

(Reporter: RyanVM, Unassigned)

Tracking

({intermittent-failure})

Firefox Tracking Flags

(Not tracked)

Details

(Whiteboard: [test disabled on android 4.3])

(Reporter)

Description

3 years ago
07:42:36     INFO -  SimpleTest START
07:42:36     INFO -  TEST-START | testVideoControls
07:42:36     INFO -  TEST-PASS | testVideoControls | Robocop tests need the test device screen to be powered on. -
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"type":"Gecko:Ready"} - Gecko:Ready should equal Gecko:Ready
07:42:36     INFO -  EventExpecter: no longer listening for Gecko:Ready
07:42:36     INFO -  Registered listener for Robocop:JS
07:42:36     INFO -  Loading JavaScript test from http://mochi.test:8888/tests/robocop/robocop_javascript.html?slug=1415864544443&path=testVideoControls.js
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 1 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  (xpcshell/head.js) | test 1 pending
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  (xpcshell/head.js) | test 2 pending
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Starting setup_browser","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  testVideoControls.js | Starting setup_browser
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 finished\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  (xpcshell/head.js) | test 2 finished
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [startTests : 45] Found the video element\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [startTests : 45] Found the video element
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  (xpcshell/head.js) | test 2 pending
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 finished\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  (xpcshell/head.js) | test 2 finished
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  (xpcshell/head.js) | test 2 pending
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Starting test_webm","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  testVideoControls.js | Starting test_webm
07:42:36     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 finished\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:36     INFO -  (xpcshell/head.js) | test 2 finished
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testLoad : 86] Play button is paused\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testLoad : 86] Play button is paused
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPlay : 96] Play button is not paused\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPlay : 96] Play button is not paused
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 110] Play button is paused again\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 110] Play button is paused again
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Color at (45, 10): {\"r\":255,\"g\":255,\"b\":255,\"a\":255}\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | Color at (45, 10): {"r":255,"g":255,"b":255,"a":255}
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 132] Bar 1 is white\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 132] Bar 1 is white
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Color at (135, 10): {\"r\":255,\"g\":255,\"b\":0,\"a\":255}\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | Color at (135, 10): {"r":255,"g":255,"b":0,"a":255}
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 136] Bar 2 is yellow\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 136] Bar 2 is yellow
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Color at (225, 10): {\"r\":0,\"g\":255,\"b\":255,\"a\":255}\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | Color at (225, 10): {"r":0,"g":255,"b":255,"a":255}
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 140] Bar 3 is Cyan\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 140] Bar 3 is Cyan
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Color at (315, 10): {\"r\":0,\"g\":255,\"b\":0,\"a\":255}\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | Color at (315, 10): {"r":0,"g":255,"b":0,"a":255}
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 144] Bar 4 is Green\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 144] Bar 4 is Green
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Color at (405, 10): {\"r\":255,\"g\":0,\"b\":255,\"a\":255}\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | Color at (405, 10): {"r":255,"g":0,"b":255,"a":255}
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 148] Bar 5 is Purple\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 148] Bar 5 is Purple
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Color at (495, 10): {\"r\":255,\"g\":0,\"b\":0,\"a\":255}\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | Color at (495, 10): {"r":255,"g":0,"b":0,"a":255}
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 152] Bar 6 is Red\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 152] Bar 6 is Red
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Color at (585, 10): {\"r\":0,\"g\":0,\"b\":255,\"a\":255}\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | Color at (585, 10): {"r":0,"g":0,"b":255,"a":255}
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 156] Bar 7 is Blue\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 156] Bar 7 is Blue
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  (xpcshell/head.js) | test 2 pending
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 finished\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  (xpcshell/head.js) | test 2 finished
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  (xpcshell/head.js) | test 2 pending
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Starting test_ogg","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | Starting test_ogg
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 finished\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  (xpcshell/head.js) | test 2 finished
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testLoad : 86] Play button is paused\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testLoad : 86] Play button is paused
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPlay : 96] Play button is not paused\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPlay : 96] Play button is not paused
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 110] Play button is paused again\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 110] Play button is paused again
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Color at (45, 10): {\"r\":0,\"g\":0,\"b\":0,\"a\":0}\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | Color at (45, 10): {"r":0,"g":0,"b":0,"a":0}
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-UNEXPECTED-FAIL | testVideoControls.js | Bar 1 is white - See following stack:\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37  WARNING -  TEST-UNEXPECTED-FAIL | testVideoControls | testVideoControls.js - Bar 1 is white - See following stack:
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"JS frame :: robocop_head.js :: do_throw :: line 472\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | JS frame :: robocop_head.js :: do_throw :: line 472
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"JS frame :: robocop_head.js :: do_report_result :: line 574\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | JS frame :: robocop_head.js :: do_report_result :: line 574
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"JS frame :: testVideoControls.js :: ok :: line 14\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | JS frame :: testVideoControls.js :: ok :: line 14
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"JS frame :: testVideoControls.js :: testPause :: line 132\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | JS frame :: testVideoControls.js :: testPause :: line 132
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  testVideoControls.js | native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0
07:42:37     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | exiting test\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
07:42:37     INFO -  (xpcshell/head.js) | exiting test
07:42:37     INFO -  EventExpecter: no longer listening for Robocop:JS
07:42:37     INFO -  Unregistered listener for Robocop:JS
07:42:37     INFO -  TEST-OK | testVideoControls | took 12956ms
07:42:37     INFO -  TEST-START | Shutdown
07:42:37     INFO -  Passed: 59
07:42:37     INFO -  Failed: 1
07:42:37     INFO -  Todo: 0
07:42:37     INFO -  SimpleTest FINISHED
Flags: needinfo?(mark.finkle)
Comment hidden (Treeherder Robot)
When did this start failing? Let's treat it as a real failure.
Flags: needinfo?(mark.finkle)
(Reporter)

Comment 3

3 years ago
First instance I've seen.
                     testVideoControls.js | Color at (45, 10): {"r":0,"g":0,"b":0,"a":0}
07:42:37  WARNING -  TEST-UNEXPECTED-FAIL | testVideoControls | testVideoControls.js - Bar 1 is white

The OGG video frame is showing as black.
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
This failure is also seen on Android 4.3 (coming soon!) -- often enough that I'm going to disable the test on 4.3 initially.

http://ftp.mozilla.org/pub/mozilla.org/mobile/try-builds/gbrown@mozilla.com-ab5e06e63b6e/try-android-api-9/try_ubuntu64_vm_mobile_test-robocop-4-bm115-tests1-linux64-build88.txt.gz

21:14:15     INFO -  TEST-START | testVideoControls
21:14:15     INFO -  TEST-PASS | testVideoControls | Robocop tests need the test device screen to be powered on. - 
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"type":"Gecko:Ready"} - Gecko:Ready should equal Gecko:Ready
21:14:15     INFO -  EventExpecter: no longer listening for Gecko:Ready
21:14:15     INFO -  Registered listener for Robocop:JS
21:14:15     INFO -  Loading JavaScript test from http://mochi.test:8888/tests/robocop/robocop_javascript.html?slug=1426822062750&path=testVideoControls.js
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 1 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  (xpcshell/head.js) | test 1 pending
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  (xpcshell/head.js) | test 2 pending
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Starting setup_browser","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  testVideoControls.js | Starting setup_browser
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 finished\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  (xpcshell/head.js) | test 2 finished
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [startTests : 45] Found the video element\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [startTests : 45] Found the video element
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  (xpcshell/head.js) | test 2 pending
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 finished\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  (xpcshell/head.js) | test 2 finished
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 pending\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  (xpcshell/head.js) | test 2 pending
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Starting test_webm","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  testVideoControls.js | Starting test_webm
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | test 2 finished\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  (xpcshell/head.js) | test 2 finished
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testLoad : 86] Play button is paused\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testLoad : 86] Play button is paused
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPlay : 96] Play button is not paused\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPlay : 96] Play button is not paused
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-PASS | testVideoControls.js | [testPause : 110] Play button is paused again\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  TEST-PASS | testVideoControls | testVideoControls.js - [testPause : 110] Play button is paused again
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | testVideoControls.js | Color at (45, 10): {\"r\":0,\"g\":0,\"b\":0,\"a\":0}\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  testVideoControls.js | Color at (45, 10): {"r":0,"g":0,"b":0,"a":0}
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-UNEXPECTED-FAIL | testVideoControls.js | Bar 1 is white - See following stack:\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15  WARNING -  TEST-UNEXPECTED-FAIL | testVideoControls | testVideoControls.js - Bar 1 is white - See following stack:
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"JS frame :: robocop_head.js :: do_throw :: line 471\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  testVideoControls.js | JS frame :: robocop_head.js :: do_throw :: line 471
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"JS frame :: robocop_head.js :: do_report_result :: line 573\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  testVideoControls.js | JS frame :: robocop_head.js :: do_report_result :: line 573
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"JS frame :: testVideoControls.js :: ok :: line 14\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  testVideoControls.js | JS frame :: testVideoControls.js :: ok :: line 14
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"JS frame :: testVideoControls.js :: testPause :: line 132\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  testVideoControls.js | JS frame :: testVideoControls.js :: testPause :: line 132
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  testVideoControls.js | native frame :: <unknown filename> :: <TOP_LEVEL> :: line 0
21:14:15     INFO -  TEST-PASS | testVideoControls | Given message occurred for registered event: {"message":"\nTEST-INFO | (xpcshell\/head.js) | exiting test\n","innerType":"progress","type":"Robocop:JS"} - Robocop:JS should equal Robocop:JS
21:14:15     INFO -  (xpcshell/head.js) | exiting test
21:14:15     INFO -  EventExpecter: no longer listening for Robocop:JS
21:14:15     INFO -  Unregistered listener for Robocop:JS
21:14:15     INFO -  TEST-OK | testVideoControls | took 97644ms
21:14:15     INFO -  TEST-START | Shutdown
21:14:15     INFO -  Passed: 27
21:14:15     INFO -  Failed: 1
Whiteboard: [test disabled on android 4.3]
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
Comment hidden (Treeherder Robot)
In bug 1184186, I re-implemented this as a mochitest-chrome test, but ran into the same failures, on try and locally. Locally, things happen quickly, but it looked like the failure happened before the video was painted. Increasing the timeout at http://hg.mozilla.org/mozilla-central/annotate/512c7e8f0030/mobile/android/tests/browser/robocop/testVideoControls.js#l98 seemed to reduce the frequency of failure, but we cannot increase that timeout very much, since the videos are only 4 seconds long. Increasing the video duration + increasing the timeout might work; perhaps a better fix would be to delay before playing...but I'm not sure what to wait for.

Comment 31

a year ago
Bulk assigning P3 to all open intermittent bugs without a priority set in Firefox components per bug 1298978.
Priority: -- → P3
You need to log in before you can comment on or make changes to this bug.