981886 - Android 2.3 /tests/content/media/webspeech/recognition/test/test_abort.html (and others) | unexpected event: error -- No speech detected (timeout)

Status: RESOLVED WORKSFORME

(Firefox for Android Graveyard :: Testing, defect)

Description

[Geoff Brown [:gbrown]]

On the Android 2.3 emulator, several tests in content/media/webspeech fail intermittently (sometimes frequently) and have been disabled for the initial run of these tests.

https://tbpl.mozilla.org/php/getParsedLog.php?id=35076815&tree=Ash&full=1#error0

15:37:49     INFO -  1538 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Aborting on speechstart
15:37:49     INFO -  1539 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_abort.html | unexpected event: error -- No speech detected (timeout)
15:37:49     INFO -  1540 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Sample stream ended, requesting queued events


15:37:49     INFO -  02-21 15:35:12.670 D/GeckoTabs(  563): handleMessage: SessionHistory:New
15:37:49     INFO -  02-21 15:35:12.700 D/GeckoTabs(  563): handleMessage: SessionHistory:Purge
15:37:49     INFO -  02-21 15:35:14.911 I/GeckoDump(  563): 1531 INFO TEST-START | /tests/content/media/webspeech/recognition/test/test_abort.html
15:37:49     INFO -  02-21 15:35:17.634 I/SUTAgentAndroid(  308): 10.0.2.2 : activity
15:37:49     INFO -  02-21 15:35:18.260 I/SUTAgentAndroid(  308): 10.0.2.2 : isdir /mnt/sdcard/tests/logs
15:37:49     INFO -  02-21 15:35:18.811 I/SUTAgentAndroid(  308): 10.0.2.2 : cd /mnt/sdcard/tests/logs
15:37:49     INFO -  02-21 15:35:19.230 I/SUTAgentAndroid(  308): 10.0.2.2 : ls
15:37:49     INFO -  02-21 15:35:19.801 I/SUTAgentAndroid(  308): 10.0.2.2 : pull /mnt/sdcard/tests/logs/mochitest.log 132197
15:37:49     INFO -  02-21 15:35:20.390 I/IdleService(  563): Get idle time: time since reset 4666 msec
15:37:49     INFO -  02-21 15:35:20.400 I/IdleService(  563): Idle timer callback: current idle time 4666 msec
15:37:49     INFO -  02-21 15:35:20.410 I/IdleService(  563): next timeout 10322 msec from now
15:37:49     INFO -  02-21 15:35:20.410 I/IdleService(  563): SetTimerExpiryIfBefore: next timeout 10321 msec from now
15:37:49     INFO -  02-21 15:35:20.410 I/IdleService(  563): reset timer expiry to 10327 msec from now
15:37:49     INFO -  02-21 15:35:22.971 I/GeckoDump(  563): 1532 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Aborting on start
15:37:49     INFO -  02-21 15:35:27.190 I/GeckoDump(  563): 1533 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_START
15:37:49     INFO -  02-21 15:35:27.380 I/GeckoDump(  563): 1534 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_AUDIO_DATA
15:37:49     INFO -  02-21 15:35:29.270 I/GeckoDump(  563): 1535 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Aborting on audiostart
15:37:49     INFO -  02-21 15:35:29.761 I/GeckoDump(  563): 1536 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_START
15:37:49     INFO -  02-21 15:35:29.841 I/GeckoDump(  563): 1537 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_AUDIO_DATA
15:37:49     INFO -  02-21 15:35:39.903 I/SUTAgentAndroid(  308): 10.0.2.2 : activity
15:37:49     INFO -  02-21 15:35:43.131 I/IdleService(  563): Get idle time: time since reset 19020 msec
15:37:49     INFO -  02-21 15:35:43.131 I/IdleService(  563): Idle timer callback: current idle time 19020 msec
15:37:49     INFO -  02-21 15:35:43.131 I/IdleService(  563): next timeout 280974 msec from now
15:37:49     INFO -  02-21 15:35:43.141 I/IdleService(  563): SetTimerExpiryIfBefore: next timeout 280970 msec from now
15:37:49     INFO -  02-21 15:35:43.151 I/IdleService(  563): reset timer expiry to 280970 msec from now
15:37:49     INFO -  02-21 15:35:43.151 I/IdleService(  563): Idle timer callback: tell observer 538500a0 user is idle
15:37:49     INFO -  02-21 15:35:44.430 I/GeckoDump(  563): 1538 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Aborting on speechstart
15:37:49     INFO -  02-21 15:35:44.902 I/GeckoDump(  563): 1539 ERROR TEST-UNEXPECTED-FAIL | /tests/content/media/webspeech/recognition/test/test_abort.html | unexpected event: error -- No speech detected (timeout)
15:37:49     INFO -  02-21 15:35:44.921 I/GeckoDump(  563): 1540 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Sample stream ended, requesting queued events
15:37:49     INFO -  02-21 15:35:44.971 I/GeckoDump(  563): 1541 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_START
15:37:49     INFO -  02-21 15:35:45.001 I/GeckoDump(  563): 1542 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_AUDIO_DATA
15:37:49     INFO -  02-21 15:35:49.812 I/GeckoDump(  563): 1543 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Sample stream ended, requesting queued events
15:37:49     INFO -  02-21 15:35:50.540 I/GeckoDump(  563): 1544 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Aborting on speechend
15:37:49     INFO -  02-21 15:35:50.721 I/GeckoDump(  563): 1545 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_START
15:37:49     INFO -  02-21 15:35:50.801 I/GeckoDump(  563): 1546 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_AUDIO_DATA
15:37:49     INFO -  02-21 15:35:50.882 I/GeckoDump(  563): 1547 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Sample stream ended, requesting queued events
15:37:49     INFO -  02-21 15:35:52.911 I/GeckoDump(  563): 1548 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Aborting on audioend
15:37:49     INFO -  02-21 15:35:52.951 I/GeckoDump(  563): 1549 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_START
15:37:49     INFO -  02-21 15:35:52.992 I/GeckoDump(  563): 1550 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | requesting EVENT_AUDIO_DATA
15:37:49     INFO -  02-21 15:35:53.101 I/GeckoDump(  563): 1551 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Sample stream ended, requesting queued events
15:37:49     INFO -  02-21 15:35:55.200 I/GeckoDump(  563): 1552 INFO TEST-INFO | MEMORY STAT vsize after test: 518840320
15:37:49     INFO -  02-21 15:35:55.220 I/GeckoDump(  563): 1553 INFO TEST-INFO | MEMORY STAT residentFast after test: 164052992
15:37:49     INFO -  02-21 15:35:55.241 I/GeckoDump(  563): 1554 INFO TEST-INFO | MEMORY STAT heapAllocated after test: 37094840
15:37:49     INFO -  02-21 15:35:55.370 I/GeckoDump(  563): 1555 INFO TEST-INFO | /tests/content/media/webspeech/recognition/test/test_abort.html | Sample stream ended, requesting queued events
15:37:49     INFO -  02-21 15:35:55.390 I/GeckoDump(  563): 1556 INFO TEST-END | /tests/content/media/webspeech/recognition/test/test_abort.html | finished in 40478ms
15:37:49     INFO -  02-21 15:35:56.193 D/GeckoTabs(  563): handleMessage: SessionHistory:New
15:37:49     INFO -  02-21 15:35:56.260 D/GeckoTabs(  563): handleMessage: SessionHistory:Purge
15:37:49     INFO -  02-21 15:35:57.390 I/GeckoDump(  563): 1557 INFO TEST-START | Shutdown

Comment 1

[Geoff Brown [:gbrown]]

Re-tested and found this is working consistently now. Enabled in bug 979921.