Android 5.1.1 new GTS failures on custom platform based off SABRE

[neerajsharma]

The latest GTS release for Lollipop (4.1_r1) has few new media tests:

com.google.android.media.gts.VideoPlayerTests#testL3Unsecure 

com.google.android.media.gts.WidevineH264PlaybackTests#testL3With1080P30 

com.google.android.media.gts.WidevineHEVCPlaybackTests#testClearWith1080P30 

com.google.android.media.gts.WidevineHEVCPlaybackTests#testClearWith720P30 

com.google.android.media.gts.WidevineHEVCPlaybackTests#testL3With1080P30 

com.google.android.media.gts.WidevineHEVCPlaybackTests#testL3With720P30 

com.google.android.media.gts.WidevineVP9WebMPlaybackTests#testClearWith720P30 

com.google.android.media.gts.WidevineVP9WebMPlaybackTests#testL3With720P30 

These tests fail on our platform based off the SabreSD imx6q reference platform with Lollipop L5.1.1_2.0.0 GA (LMY47V) running on it.  What we have observed is that the video playback withing the tests is hung at some point of time and leading to failures.

Below is the device logcat output for one of the tests:

01-10 14:02:22.819 D/SoftHEVC(11949): Number of CPU cores: 2
01-10 14:02:22.820 D/SoftHEVC(11949): Initializing decoder
01-10 14:02:22.821 D/SoftHEVC(11949): Set number of cores to 2
01-10 14:02:22.821 D/SoftHEVC(11949): Ittiam decoder version number: @(#)Id:HEVCDEC_production Ver:04.01 Released by ITTIAM Build: Sep 14 2016 @ 12:29:39
01-10 14:02:22.824 W/OMXNodeInstance(11949): [1:google.hevc.decoder] component does not support metadata mode; using fallback
01-10 14:02:22.824 E/ACodec (11949): [OMX.google.hevc.decoder] storeMetaDataInBuffers failed w/ err -1010
01-10 14:02:22.825 D/SoftHEVC(11949): Freeing codec memory
01-10 14:02:22.826 D/SoftHEVC(11949): Number of CPU cores: 2
01-10 14:02:22.826 D/SoftHEVC(11949): Initializing decoder
01-10 14:02:22.827 D/SoftHEVC(11949): Set number of cores to 2
01-10 14:02:22.827 D/SoftHEVC(11949): Ittiam decoder version number: @(#)Id:HEVCDEC_production Ver:04.01 Released by ITTIAM Build: Sep 14 2016 @ 12:29:39
01-10 14:02:22.829 W/WVCdm ( 168): BufferReader::Read<T> : Failure during parse: Not enough bytes (4)
01-10 14:02:22.829 W/WVCdm ( 168): CdmEngine::ExtractWidevinePssh: Unable to read PSSH atom size
01-10 14:02:22.829 W/WVCdm ( 168): BufferReader::Read<T> : Failure during parse: Not enough bytes (4)
01-10 14:02:22.829 W/WVCdm ( 168): CdmEngine::ExtractWidevinePssh: Unable to read PSSH atom size
01-10 14:02:22.829 I/WVCdm ( 168): CdmEngine::GenerateKeyRequest
01-10 14:02:22.835 D/WVCdm ( 168): PrepareKeyRequest: nonce=3464779943
01-10 14:02:22.901 I/art ( 1594): Explicit concurrent mark sweep GC freed 53340(2MB) AllocSpace objects, 35(560KB) LOS objects, 40% free, 11MB/18MB, paused 1.839ms total 218.858ms
01-10 14:02:25.588 I/WVCdm ( 168): CdmEngine::AddKey
01-10 14:02:28.153 I/SoftAAC2(11949): limiting to stereo output
01-10 14:02:28.157 I/SoftAAC2(11949): Reconfiguring decoder: 0->44100 Hz, 0->2 channels
01-10 14:02:28.190 I/audio_hw_thor( 168): ######[] start_output_stream()... -1249373184, device 2
01-10 14:02:28.191 D/audio_hw_thor( 168): select_output_device(), out_device=2, bt_on=0
01-10 14:02:28.191 I/audio_hw_thor( 168): select_output_device() - headphone 0 ,headset 0 ,speaker 2, earpiece 0,
01-10 14:02:28.313 D/audio_hw_thor( 168): get_card_for_device()
01-10 14:02:28.314 D/audio_hw_thor( 168): start_output_stream(primary): card 0, port 0 device 0x2
01-10 14:02:28.314 D/audio_hw_thor( 168): start_output_stream(primary): rate 48000, channel 2 period_size 0x1e0
01-10 14:02:28.314 D/audio_hw_thor( 168): start_output_stream(): not sco pcm_device 0x0, device 0x2
01-10 14:02:34.028 D/SoftHEVC(11949): In SoftHEVC::~SoftHEVC
01-10 14:02:34.028 D/SoftHEVC(11949): Freeing codec memory
01-10 14:02:47.818 I/art (11949): Background sticky concurrent mark sweep GC freed 6622(2MB) AllocSpace objects, 0(0B) LOS objects, 36% free, 4MB/6MB, paused 12.189ms total 60.372ms
01-10 14:02:49.938 D/SoftHEVC(11949): onReset called
01-10 14:02:49.939 D/SoftHEVC(11949): Set number of cores to 2
01-10 14:02:49.950 I/WVCdm ( 168): CdmEngine::CloseSession
01-10 14:02:49.955 I/WVCdm ( 168): L3 Terminate.
01-10 14:02:50.962 D/SoftHEVC(11949): In SoftHEVC::~SoftHEVC
01-10 14:02:50.962 D/SoftHEVC(11949): Freeing codec memory
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): =================================================================
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Test Name: L3 With HEVC 720P 30fps
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Test Description: Check that the device can play 30fps 720P HEVC content using L3. 720P content is the baseline for HD content. This test checks that the L3 implementation can play HD content with an insecure decoder.
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Test Results: Fail
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): =================================================================
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Name: Widevine Check
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Description: Test if Widevine DRM is supported
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Tags: precontent
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Notes: Proceed to run test on Widevine supported device
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Name: Provisioning Check
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Description: Test if a device is provisioned. If the device is not provisioned, it will attempt to provision. If that fails, the test will fail.
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Tags: precontent
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Notes: Device is provisioned
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Name: No Exceptions
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Description: Test that no exceptions were thrown during playback
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Tags: postplay
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Notes: No exceptions occurred during playback
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Name: Continous Video Progress
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Description: Check to see if the test gets stuck during playback. Once the stuck for more than 5 seconds, playback will be terminated.
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Tags: postplay
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Notes: Playback did not get stuck
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Name: Supports Format
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Description: Check that the device has a video codec to support the provided format. If a codec is not found, the test will skip. If a codec is found the test may run
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Tags: postcontent
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949): Check Notes: Found a codec for the current video format >>> {height=674, width=1280, max-input-size=1294080, mime=video/hevc, durationUs=90000000, csd-0=java.nio.ByteArrayBuffer[position=0,limit=1094,capacity=1094]}
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.027 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Name: Provisioning Check
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Description: Test if a device is provisioned. If the device is not provisioned, it will attempt to provision. If that fails, the test will fail.
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Tags: precontent
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Notes: Device is provisioned
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Name: Audio Reached Seconds
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Description: Check to see if the test plays past 20 seconds
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Tags: postplay
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Notes: The video successfuly played to 21014 ms
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Name: Wifi Check
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Description: Test that the device is connected to a wifi network and is capable of communicating with proxy.uat.widevine.com
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Tags: precontent
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Notes: Network connection confirmed. Here are the details: SIDS "ALPHA5WMM" FREQUENCY 5805 MHz RRSI -51 dBm LINK SPEED 54 Mbps
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Name: Wifi Check
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Description: Test that the device is connected to a wifi network and is capable of communicating with storage.googleapis.com
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Tags: precontent
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Notes: Network connection confirmed. Here are the details: SIDS "ALPHA5WMM" FREQUENCY 5805 MHz RRSI -50 dBm LINK SPEED 48 Mbps
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Name: Continous Audio Progress
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Description: Check to see if the test gets stuck during playback. Once the stuck for more than 5 seconds, playback will be terminated.
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Tags: postplay
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Notes: Playback did not get stuck
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Name: Wifi Check
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Description: Test that the device is connected to a wifi network and is capable of communicating with google.com
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Tags: precontent
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Notes: Network connection confirmed. Here are the details: SIDS "ALPHA5WMM" FREQUENCY 5805 MHz RRSI -52 dBm LINK SPEED 48 Mbps
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Name: Video Reached Seconds
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Description: Check to see if the test plays past 20 seconds
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Result: Fail
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Tags: postplay
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Result: Fail
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Notes: The video only played to 14791 ms instead of 20000 ms
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Name: Min Android Version
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Description: Require that the current OS has a version number greater or equal to 22
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Component Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Tags: precontent
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Result: Pass
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949): Check Notes: The min verion was met, this is version 22 and the min version is 22
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):
01-10 14:02:51.028 I/L3 With HEVC 720P 30fps(11949):

[Bio_TICFSL]

Hi neeraj,

Please check that you included freescale codecs in image, refer to document Table 1. Freescale enhanced codecs This document is included in IMX6_L500_100_ANDROID_DOCS  i.MX6 Android L5.0.0_1.0.0 BSP Documentation

HEVC mas resolution supported is 720x480@30fps not 720p.

regards