Versions Compared

Key

  • This line was added.
  • This line was removed.
  • Formatting was changed.

...

Checking and processing content protection from fragmentCollector thread

2019 Jun 18 13:08:44.455920 arrisxg1v4 WPEWebProcess[16069]: [HHH]Comcast DRM Agnostic CENC system ID found!
2019 Jun 18 13:08:44.455947 arrisxg1v4 WPEWebProcess[16069]: _extractWVContentMetadataFromPssh:669 content meta data length : 1692
2019 Jun 18 13:08:44.455967 arrisxg1v4 WPEWebProcess[16069]: [HHH]Playready system ID found!
2019 Jun 18 13:08:44.456003 arrisxg1v4 WPEWebProcess[16069]: [HHH]Widevine system ID found!
2019 Jun 18 13:08:44.456023 arrisxg1v4 WPEWebProcess[16069]: _extractKeyIdFromPssh:602 wv keyid: 0cc84940-1541-ef5d-cc5f-1f5317cb417e keyIdlen: 36
2019 Jun 18 13:08:44.456034 arrisxg1v4 WPEWebProcess[16069]: _extractKeyIdFromPssh:642 KeyId : 0cc84940-1541-ef5d-cc5f-1f5317cb417e
...
2019 Jun 18 13:08:44.456177 arrisxg1v4 WPEWebProcess[16069]: [HHH]Comcast DRM Agnostic CENC system ID found!
2019 Jun 18 13:08:44.456204 arrisxg1v4 WPEWebProcess[16069]: _extractWVContentMetadataFromPssh:669 content meta data length : 1692
2019 Jun 18 13:08:44.456227 arrisxg1v4 WPEWebProcess[16069]: [HHH]Playready system ID found!
2019 Jun 18 13:08:44.456263 arrisxg1v4 WPEWebProcess[16069]: [HHH]Widevine system ID found!
2019 Jun 18 13:08:44.456281 arrisxg1v4 WPEWebProcess[16069]: _extractKeyIdFromPssh:602 wv keyid: 0cc84940-1541-ef5d-cc5f-1f5317cb417e keyIdlen: 36
2019 Jun 18 13:08:44.456292 arrisxg1v4 WPEWebProcess[16069]: _extractKeyIdFromPssh:642 KeyId : 0cc84940-1541-ef5d-cc5f-1f5317cb417e
...
2019 Jun 18 13:08:44.456303 arrisxg1v4 WPEWebProcess[16069]: PrivateStreamAbstractionMPD::StreamSelection 3687 > Media[audio] enabled
2019 Jun 18 13:08:44.456346 arrisxg1v4 WPEWebProcess[16069]: PrivateInstanceAAMP::SendEventAsync:797 event type 5
2019 Jun 18 13:08:44.456368 arrisxg1v4 WPEWebProcess[16069]: PrivateInstanceAAMP::SendEventAsync:797 event type 1

Prints indicating playback start position, mpd duration
2019 Jun 18 13:08:44.456513 arrisxg1v4 WPEWebProcess[16069]: SkipFragments:1643 Enter : Type[0] timeLineIndex 0 fragmentRepeatCount 0 skipTime 13.000000
2019 Jun 18 13:08:44.456538 arrisxg1v4 WPEWebProcess[16069]: SkipFragments:1710 [video] newPTS 40530.433711
2019 Jun 18 13:08:44.456554 arrisxg1v4 WPEWebProcess[16069]: SkipFragments:1714 [video] mFirstPTS 0.000000 -> 40530.433711
2019 Jun 18 13:08:44.456571 arrisxg1v4 WPEWebProcess[16069]: SkipFragments:1768 Exit :Type[0] timeLineIndex 0 fragmentRepeatCount 6 fragmentDescriptor.Number 779651997 fragmentTime 12.012000
2019 Jun 18 13:08:44.456590 arrisxg1v4 WPEWebProcess[16069]: SkipFragments:1643 Enter : Type[1] timeLineIndex 0 fragmentRepeatCount 0 skipTime 13.000000
2019 Jun 18 13:08:44.456626 arrisxg1v4 WPEWebProcess[16069]: SkipFragments:1710 [audio] newPTS 40528.449911
2019 Jun 18 13:08:44.456641 arrisxg1v4 WPEWebProcess[16069]: SkipFragments:1768 Exit :Type[1] timeLineIndex 5 fragmentRepeatCount 0 fragmentDescriptor.Number 779651996 fragmentTime 10.016000
2019 Jun 18 13:08:44.456657 arrisxg1v4 WPEWebProcess[16069]: Init:2682 offsetFromStart(13.000000) seekPosition(12.012000)
2019 Jun 18 13:08:44.458665 arrisxg1v4 WPEWebProcess[16069]: aamp: sending metadata event and duration update 28.000000
...
2019 Jun 18 13:08:44.458737 arrisxg1v4 WPEWebProcess[16069]: UpdateRefreshPlaylistInterval(): maxRefreshPlaylistIntervalSecs (4.032000)
2019 Jun 18 13:08:44.458782 arrisxg1v4 WPEWebProcess[16069]: TuneHelper:3810 Updated seek_pos_seconds 12.012000

Creating Gstreamer pipeline

Code Block
languagebash
themeMidnight
titleCreating Gstreamer pipeline
1613139308:107 : [AAMP-PLAYER]CreatePipeline(): Creating gstreamer pipeline 
1613139308:107 : [AAMP-PLAYER]AAMPGstPlayerPipeline buffering_enabled 1 
1613139308:107 : [AAMP-PLAYER]AAMPGstPlayer::Configure 2363 > Closing stream 0 old format = 0, new format = 2 
1613139308:107 : [AAMP-PLAYER]AAMPGstPlayer::Configure 2363 > Closing stream 1 old format = 0, new format = 2 
1613139308:107 : [AAMP-PLAYER]AAMPGstPlayer::TearDownStream: exit mediaType = 0 
1613139308:107 : [AAMP-PLAYER]AAMPGstPlayer_SetupStream - using playbin 
1613139308:116 : [AAMP-PLAYER]AAMPGstPlayer_SetupStream - using westerossink 
1613139308:127 : [AAMP-PLAYER]playbin flags1: 0x617 
1613139308:127 : [AAMP-PLAYER]AAMPGstPlayer::TearDownStream: exit mediaType = 1 
1613139308:127 : [AAMP-PLAYER]AAMPGstPlayer_SetupStream - using playbin 
1613139308:128 : [AAMP-PLAYER]playbin flags1: 0x617 
1613139308:128 : [AAMP-PLAYER]AAMPGstPlayerPipeline NULL -> READY (pending PAUSED)

DRM session creation "keysystem is" indicates which DRM is selected


Code Block
languagebash
themeMidnight
titleChecking and processing content protection from fragmentCollector thread
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]aamp ipv4=1 enabled
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]aamp ipv4=1 enabled
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]aamp url:3,3,0,0.000000,https://content.uplynk.com/224ac8717e714b68831997ab6cea4015.mpd
Mar 18 05:35:42 HP40ARDK WPEFramework[6790]: 11:05:42:174 INFO  av_sync_destroy:198: begin
Mar 18 05:35:42 HP40ARDK WPEFramework[6790]: 11:05:42:174 INFO  tsync_send_video_stop:112: video stop
Mar 18 05:35:42 HP40ARDK WPEFramework[6790]: 11:05:42:177 INFO  av_sync_destroy:212: done
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]HttpRequestEnd: 3,3,200,0.2310,0.2302,0.0702,0.2293,0.0287,0.1787,0.1788,0.0000,8442,287,https://content.uplynk.com/224ac8717e714b68831997ab6cea4015.mpd
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]ClearPlaylistCache:145 : cache size 1
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER][InsertToPlaylistCache][76]  Inserted. url https://content.uplynk.com/224ac8717e714b68831997ab6cea4015.mpd
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::Init:3724 - MPD duration str PT9M56.479999S val 596 seconds
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::GetPeriodStartTime:3326 - MPD periodIndex 0 periodStart 0.000000
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::GetPeriodDuration:3358 - MPD periodIndex:0 periodDuration 596.479000
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]GetPeriodEndTime:3437 :  Period startTime required to calculate period duration not present in MPD
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::GetPeriodEndTime:3445 - MPD periodIndex:0 periodEndTime 596.479000
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]Selected Period index 0, id c-0-000-146
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]GetBestAudioTrackByLanguage: aamp->language en, aamp->noExplicitUserLanguageSelection false, aamp->preferredLanguages "en"
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::GetDesiredCodecIndex 1051  > SelectedRepIndex : 0 ,selectedCodecType : 2, selectedRepBandwidth: 24860
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::StreamSelection 5590 > lang[en] AudioType[2]
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]IsIframeTrack:5191 - skipping name SupplementalProperty
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]IsIframeTrack:5191 - skipping name InbandEventStream
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]IsIframeTrack:5191 - skipping name SupplementalProperty
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]IsIframeTrack:5191 - skipping name InbandEventStream
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::StreamSelection 5852 > Media[video] Adaptation set[2] RepIdx[-1] TrackCnt[1]
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]CreateDrmHelper:2948 (video) got schemeID empty at ContentProtection node-0
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]CreateDrmHelper:3009 (video) Created DRM helper for UUID edef8ba9-79d6-4ace-a3c8-27dcd51d21ed and best to use
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::StreamSelection 5865 > Media[video] enabled
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::StreamSelection() 5766 Audio Track - lang:en, group:main, name:0, codec:mp4a.40.5, bandwidth:24860
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::StreamSelection 5852 > Media[audio] Adaptation set[0] RepIdx[0] TrackCnt[2]
Code Block
languagebash
themeMidnight
titleDRM session creation "keysystem is" indicates which DRM is selected
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]QueueProtectionEvent:1656 Queueing protection event for type(0) keysystem(edef8ba9-79d6-4ace-a3c8-27dcd51d21ed) initData(0xac08b548) initDataSize(114)
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]createDrmSession:918 StreamType :0 keySystem is com.widevine.alpha
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getDrmSession:1035  Selected slot 1 for keyId 0x6132303038633166343735663439313739616366366230613531636162633938
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]AAMPOCDMSessionAdapter :: enter 
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]initAampDRMSystem :: enter 
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]CreateDrmHelper:2948 (audio) got schemeID empty at ContentProtection node-0
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]initAampDRMSystem :: exit 
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]AAMPOCDMSessionAdapter :: exit 
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getDrmSession:1115 Created new DrmSession for DrmSystemId com.widevine.alpha
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]generateAampDRMSession:91: at 0xac0abf18, with 0xac0611c0, (nil)

...


Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]CreateDrmHelper:3009 (audio) Created DRM helper for UUID edef8ba9-79d6-4ace-a3c8-27dcd51d21ed

...

 and best to use
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]processOCDMChallenge:145: at 0xac0abf18, with 0xac0611c0, (nil)
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16[ 1160.165234@1]s [TEE] OEMCrypto_GetOEMPublicCertificate: Provisioning method = 2.
[ 1160.171989@1]s [TEE] OEMCrypto_GetOEMPublicCertificate: Provisioning method = 2.


Creating Gstreamer pipeline

FragmentCollector downloading and injecting init headers

Code Block
languagebash
themeMidnight
titleFragmentCollector downloading and injecting init headersCreating Gstreamer pipeline
1613139308:1071613139307:613 : [AAMP-PLAYER]PrivateStreamAbstractionMPD::Init:4281 - fetch initialization fragments 
1613139307:614CreatePipeline(): Creating gstreamer pipeline 
1613139308:107 : [AAMP-PLAYER]aamp url:2,7,1,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/de_init.m4s 
1613139307:614AAMPGstPlayerPipeline buffering_enabled 1 
1613139308:107 : [AAMP-PLAYER]aamp url:2,6,0,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/480p_init.m4s 
1613139307:858AAMPGstPlayer::Configure 2363 > Closing stream 0 old format = 0, new format = 2 
1613139308:107 : [AAMP-PLAYER]HttpRequestEnd: 2,6,200,0.2440,0.2442,0.0002,0.2441,0.0002,0.0002,0.0003,0.0000,811,288,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/480p_init.m4s 
1613139308:106AAMPGstPlayer::Configure 2363 > Closing stream 1 old format = 0, new format = 2 
1613139308:107 : [AAMP-PLAYER]HttpRequestEnd: 2,7,200,0.4920,0.4918,0.2455,0.4916,0.0001,0.0000,0.2457,0.0000,741,286,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/de_init.m4s

Gstreamer layer detects encryption for Audio and Video and suitable Decryptor plugin and initialized

Code Block
languagebash
themeMidnight
titleGstreamer layer detects encryption for Audio and Video and suitable Decryptor plugin and initialized
Mar 18 05:35:44 HP40ARDK WPEWebProcess[16788]AAMPGstPlayer::TearDownStream: exit mediaType = 0 
1613139308:107 : [AAMP-PLAYER]AAMPGstPlayer_SendPendingEventsSetupStream pushing- protection event! mediatype: 1
Mar 18 05:35:44 HP40ARDK WPEWebProcess[16788]using playbin 
1613139308:116 : [AAMP-PLAYER]SettingAAMPGstPlayer_SetupStream PlayReady- asusing preferredwesterossink drm
Mar 18 05:35:44 HP40ARDK WPEWebProcess[16788]1613139308:127 : [AAMP-PLAYER]playbin flags1: 0x617 
1613139308:127 : [AAMP-PLAYER]AAMPGstPlayer::TearDownStream: settingexit aampmediaType instance= for aampwidevinedecryptor21 decryptor
Mar 18 05:35:44 HP40ARDK WPEFramework[6790]: gst_qtdemux_handle
1613139308:127 : [AAMP-PLAYER]AAMPGstPlayer_SetupStream - using playbin 
1613139308:128 : [AAMP-PLAYER]playbin flags1: 0x617 
1613139308:128 : [AAMP-PLAYER]AAMPGstPlayerPipeline NULL -> READY (pending PAUSED)


DRM session creation "keysystem is" indicates which DRM is selected

Code Block
languagebash
themeMidnight
titleDRM session creation "keysystem is" indicates which DRM is selected
_sink_event:2320 - aamp_override - enabled = 0, player = 1, rate  1.000000
Mar 18 05:35:44 HP40ARDK WPEFramework[6790]: (WPEWebProcess:16788): GStreamer-WARNING **: ../../gstreamer-1.10.4/gst/gstpad.c:5079:store_sticky_event:<typefind:sink> Sticky event misordering, got 'segment' before 'caps'
Mar 18 05:35:4442 HP40ARDK WPEFrameworkWPEWebProcess[679016788]: (WPEWebProcess:16788): GStreamer-WARNING **: ../../gstreamer-1.10.4/gst/gstpad.c:5079:store_sticky_event:<decodebin3:sink> Sticky event misordering, got 'segment' before 'caps'[AAMP-PLAYER]QueueProtectionEvent:1656 Queueing protection event for type(0) keysystem(edef8ba9-79d6-4ace-a3c8-27dcd51d21ed) initData(0xac08b548) initDataSize(114)
Mar 18 05:35:4442 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]Setting PlayReady as preferred drmcreateDrmSession:918 StreamType :0 keySystem is com.widevine.alpha
Mar 18 05:35:4442 HP40ARDK WPEFrameworkWPEWebProcess[679016788]: ** (WPEWebProcess:16788): WARNING **: ####### gst_aampwidevinedecryptor_init : 90 ####[AAMP-PLAYER]getDrmSession:1035  Selected slot 1 for keyId 0x6132303038633166343735663439313739616366366230613531636162633938
Mar 18 05:35:4442 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]AAMPGstPlayerAAMPOCDMSessionAdapter setting aamp instance for aampwidevinedecryptor3 decryptor:: enter 
Mar 18 05:35:4442 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]createDrmSession:918 StreamType :1 keySystem is com.widevine.alphainitAampDRMSystem :: enter 
Mar 18 05:35:4442 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getDrmSessionCreateDrmHelper:9972948 Session created/inprogress with same keyID 0x6237336265306534616239653433613238336531663130316336373836303832 at slot 2(audio) got schemeID empty at ContentProtection node-0
Mar 18 05:35:4442 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getDrmSessioninitAampDRMSystem ::1075 Foundexit drm
Mar session READY with same keyID 0x6237336265306534616239653433613238336531663130316336373836303832 - Reusing drm session18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]AAMPOCDMSessionAdapter :: exit 
Mar 18 05:35:4442 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]createDrmSessiongetDrmSession:9181115 Created StreamTypenew :0DrmSession keySystemfor isDrmSystemId com.widevine.alpha

2019 Jun 18 13:08:44.570753 arrisxg1v4 WPEWebProcess[16069]: AAMPGstPlayer_SendPendingEvents pushing protection event! mediatype: 1
2019 Jun 18 13:08:44.572607 arrisxg1v4 WPEWebProcess[16069]: Setting WideWine as preferred drm
2019 Jun 18 13:08:44.575963 arrisxg1v4 WPEWebProcess[16069]: AAMPGstPlayer setting aamp instance for aampwidevinedecryptor2 decryptor

...


Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]generateAampDRMSession:91: at 0xac0abf18, with 0xac0611c0, (nil)
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]CreateDrmHelper:3009 (audio) Created DRM helper for UUID edef8ba9-79d6-4ace-a3c8-27dcd51d21ed

...

 and best to use
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]processOCDMChallenge:145: at 0xac0abf18, with 0xac0611c0, (nil)
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16[ 1160.165234@1]s [TEE] OEMCrypto_GetOEMPublicCertificate: Provisioning method = 2.
[ 1160.171989@1]s [TEE] OEMCrypto_GetOEMPublicCertificate: Provisioning method = 2.
788]: [86B blob data]
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]processOCDMChallenge destUrl = http://widevine-proxy.appspot.com/proxy


FragmentCollector downloading and injecting init headersFragmentCollector proceeds with fragment download/injection

Code Block
languagebash
themeMidnight
titleFragmentCollector proceeds with fragment download/injectiondownloading and injecting init headers
1613139307:6131613139308:151 : [AAMP-PLAYER]fragment injector started. track audio 
1613139308:151 : [AAMP-PLAYER]fragment injector started. track video 
1613139308:158PrivateStreamAbstractionMPD::Init:4281 - fetch initialization fragments 
1613139307:614 : [AAMP-PLAYER]aamp: ready to collect fragments. mpd 0x9d75f0 
1613139308:158 : [AAMP-PLAYER]MPD has 1 periods current period index 0 
1613139308:158 : [AAMP-PLAYER]PushNextFragment:1811 Type[1] update startTime to 0 
1613139308:158 url:2,7,1,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/de_init.m4s 
1613139307:614 : [AAMP-PLAYER]aamp url:02,16,10,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/de480p_001init.mp3m4s 
16131393081613139307:158858 : [AAMP-PLAYER]PushNextFragmentHttpRequestEnd:1811 Type[0] update startTime to 0 
1613139308:158 : [AAMP-PLAYER]aamp url:0,0,0, 2,6,200,0.2440,0.2442,0.0002,0.2441,0.0002,0.0002,0.0003,0.0000,811,288,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/480p_001.m4sinit.m4s 
1613139308:106 : [AAMP-PLAYER]HttpRequestEnd: 2,7,200,0.4920,0.4918,0.2455,0.4916,0.0001,0.0000,0.2457,0.0000,741,286,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/de_init.m4s


Gstreamer layer detects encryption for Audio and Video and suitable Decryptor plugin and initializedDRM license request in progress

Code Block
languagebash
themeMidnight
titleDRM license request in progressGstreamer layer detects encryption for Audio and Video and suitable Decryptor plugin and initialized
Mar 18 05:35:4244 HP40ARDK WPEWebProcessWPEFramework[167886790]: [AAMP-PLAYER]generateAampDRMSession:91: at 0xac0abf18, with 0xac0611c0, (nil) ** (WPEWebProcess:16788): WARNING **: ####### gst_aampwidevinedecryptor_init : 90 ####
Mar 18 05:35:4244 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]CreateDrmHelper:3009 (audio) Created DRM helper for UUID edef8ba9-79d6-4ace-a3c8-27dcd51d21ed and best to useSending segment event for mediaType[1]. start 0 stop 18446744073709551615 rate 1.000000 applied_rate 1.000000
Mar 18 05:35:4244 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]processOCDMChallenge:145: at 0xac0abf18, with 0xac0611c0, (nil)AAMPGstPlayer_SendPendingEvents pushing protection event! mediatype: 1
Mar 18 05:35:4244 HP40ARDK WPEWebProcess[16[ 1160.165234@1]s [TEE] OEMCrypto_GetOEMPublicCertificate: Provisioning method = 2.
[ 1160.171989@1]s [TEE] OEMCrypto_GetOEMPublicCertificate: Provisioning method = 2.
788]: [86B blob data]16788]: [AAMP-PLAYER]Setting PlayReady as preferred drm
Mar 18 05:35:4244 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]processOCDMChallenge destUrl = http://widevine-proxy.appspot.com/proxyAAMPGstPlayer setting aamp instance for aampwidevinedecryptor2 decryptor
Mar 18 05:35:4244 HP40ARDK WPEWebProcessWPEFramework[167886790]: [AAMP-PLAYER]acquireLicense:1186 Request to generate license challenge to the aampDRMSession(CDM)
Mar 18gst_qtdemux_handle_sink_event:2320 - aamp_override - enabled = 0, player = 1, rate  1.000000
Mar 18 05:35:4244 HP40ARDK WPEFramework[6790]: (WPEWebProcess[16788]: [AAMP-PLAYER]aampGenerateKeyRequest:198: at 0xac0abf18, with 0xac0611c0, 0xac0a3ff0:16788): GStreamer-WARNING **: ../../gstreamer-1.10.4/gst/gstpad.c:5079:store_sticky_event:<typefind:sink> Sticky event misordering, got 'segment' before 'caps'
Mar 18 05:35:4244 HP40ARDK WPEFramework[6790]: (WPEWebProcess[:16788]): [AAMP-PLAYER]destination url is http://widevine-proxy.appspot.com/proxyGStreamer-WARNING **: ../../gstreamer-1.10.4/gst/gstpad.c:5079:store_sticky_event:<decodebin3:sink> Sticky event misordering, got 'segment' before 'caps'
Mar 18 05:35:4244 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]acquireLicense:1263 Request License from the Drm Server https://content.uplynk.com/wvSetting PlayReady as preferred drm
Mar 18 05:35:44 HP40ARDK WPEFramework[6790]: ** (WPEWebProcess:16788): WARNING **: ####### gst_aampwidevinedecryptor_init : 90 ####
Mar 18 05:35:4244 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]AAMPGstPlayer setting aamp ipv4=1 enabledinstance for aampwidevinedecryptor3 decryptor
Mar 18 05:35:4244 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getLicense:668 Sending license request to server : https://content.uplynk.com/wv createDrmSession:918 StreamType :1 keySystem is com.widevine.alpha
Mar 18 05:35:44 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getDrmSession:997 Session created/inprogress with same keyID 0x6237336265306534616239653433613238336531663130316336373836303832 at slot 2
Mar 18 05:35:4244 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getLicensegetDrmSession:7681075 DRMFound Sessiondrm Managersession ReceivedREADY licensewith datasame fromkeyID server;0x6237336265306534616239653433613238336531663130316336373836303832 Curl- totalReusing timedrm session
Mar = 0.4

2019 Jun 18 13:08:44.745282 arrisxg1v4 WPEWebProcess[16069]: generateAampDRMSession :: sessionId : ksidE173EC32
2019 Jun 18 13:08:44.745336 arrisxg1v4 WPEWebProcess[16069]: destination url is https://mds.ccp.xcal.tv
2019 Jun 18 13:08:44.745364 arrisxg1v4 WPEWebProcess[16069]: createDrmSession:1045 [HHH]contentMetaData length=1692
2019 Jun 18 13:08:44.745424 arrisxg1v4 WPEWebProcess[16069]: createDrmSession:1078 MDS server spcific conent metadata found in initdata
2019 Jun 18 13:08:44.745480 arrisxg1v4 WPEWebProcess[16069]: createDrmSession:1108 access token is available
2019 Jun 18 13:08:44.745555 arrisxg1v4 WPEWebProcess[16069]: [HHH] Before calling SecClient_AcquireLicense-----------
2019 Jun 18 13:08:44.745569 arrisxg1v4 WPEWebProcess[16069]: destinationURL is mds.ccp.xcal.tv
2019 Jun 18 13:08:44.745582 arrisxg1v4 WPEWebProcess[16069]: MoneyTrace[trace-id=e5de25f4-b903-42ee-a872-3f6b95a3b29f;parent-id=2473532010524596290;span-id=1560863324745]
2019 Jun 18 13:08:44.745594 arrisxg1v4 WPEWebProcess[16069]: keySystem is com.widevine.alpha
2019 Jun 18 13:08:44.765810 arrisxg1v4 WPEWebProcess[16069]: AAMPGstPlayer Found --> brcmvideodecoder = 0xb232ee78
2019 Jun 18 13:08:44.766483 arrisxg1v4 Receiver[15906]: Thread-15906 [XREApplication] - xre transaction complete: 1ca60ae4-00ba-4dd3-9293-9c48207edcdb,1088
2019 Jun 18 13:08:44.768226 arrisxg1v4 WPEWebProcess[16069]: createDrmSession:821 systemId is edef8ba9-79d6-4ace-a3c8-27dcd51d21ed
2019 Jun 18 13:08:44.768247 arrisxg1v4 WPEWebProcess[16069]: createDrmSession:838 [HHH]systemId is Widevine
2019 Jun 18 13:08:44.768266 arrisxg1v4 WPEWebProcess[16069]: keysystem is com.widevine.alpha
2019 Jun 18 13:08:44.768290 arrisxg1v4 WPEWebProcess[16069]: _extractKeyIdFromPssh:602 wv keyid: 0cc84940-1541-ef5d-cc5f-1f5317cb417e keyIdlen: 36
2019 Jun 18 13:08:44.768309 arrisxg1v4 WPEWebProcess[16069]: _extractKeyIdFromPssh:642 KeyId : 0cc84940-1541-ef5d-cc5f-1f5317cb417e
2019 Jun 18 13:08:44.768329 arrisxg1v4 WPEWebProcess[16069]: createDrmSession:876 Session created/inprogress with same keyID 0cc84940-1541-ef5d-cc5f-1f5317cb417e at slot 1, can reuse same for video

18 05:35:44 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]createDrmSession:918 StreamType :0 keySystem is com.widevine.alpha

FragmentCollector proceeds with fragment download/injection


Code Block
languagebash
themeMidnight
titleFragmentCollector proceeds with fragment download/injection
1613139308:151 : [AAMP-PLAYER]fragment injector started. track audio 
1613139308:151 : [AAMP-PLAYER]fragment injector started. track video 
1613139308:158 : [AAMP-PLAYER]aamp: ready to collect fragments. mpd 0x9d75f0 
1613139308:158 : [AAMP-PLAYER]MPD has 1 periods current period index 0 
1613139308:158 : [AAMP-PLAYER]PushNextFragment:1811 Type[1] update startTime to 0 
1613139308:158 : [AAMP-PLAYER]aamp url:0,1,1,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/de_001.mp3 
1613139308:158 : [AAMP-PLAYER]PushNextFragment:1811 Type[0] update startTime to 0 
1613139308:158 : [AAMP-PLAYER]aamp url:0,0,0,http://cpetestutility.stb.r53.xcal.tv/VideoTestStream/dash/480p_001.m4s


DRM license request in progressLicense acquisition success

Code Block
languagebash
themeMidnight
titleLicense acquisition successDRM license request in progress
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getLicensegenerateAampDRMSession:91:769 acquireLicenseat SUCCESS!0xac0abf18, licensewith request attempt 1; response code : http 2000xac0611c0, (nil)
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]HttpRequestEndCreateDrmHelper: 1,0,200,0.3520,0.3522,0.0352,0.1695,0.0043,0.1409,0.1410,0.0000,713,144,https://content.uplynk.com/wv3009 (audio) Created DRM helper for UUID edef8ba9-79d6-4ace-a3c8-27dcd51d21ed and best to use
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]handleLicenseResponse:1349 license acquisition completedprocessOCDMChallenge:145: at 0xac0abf18, with 0xac0611c0, (nil)
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]processLicenseResponse:1397 Updating the license response to the aampDRMSession(CDM)[16[ 1160.165234@1]s [TEE] OEMCrypto_GetOEMPublicCertificate: Provisioning method = 2.
[ 1160.171989@1]s [TEE] OEMCrypto_GetOEMPublicCertificate: Provisioning method = 2.
788]: [86B blob data]
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]aampDRMProcessKey:229: at 0xac0abf18, with 0xac0611c0, 0xac0a3ff0processOCDMChallenge destUrl = http://widevine-proxy.appspot.com/proxy
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]Calling opencdm_session_update, key length=713acquireLicense:1186 Request to generate license challenge to the aampDRMSession(CDM)
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]keyUpdateOCDMaampGenerateKeyRequest:178198: at 0xac0abf18, with 0xac0611c0, 0xac0a3ff0
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]keysUpdatedOCDM:192: at 0xac0abf18, with 0xac0611c0, 0xac0a3ff0destination url is http://widevine-proxy.appspot.com/proxy
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]Key Status updatedacquireLicense:1263 Request License from the Drm Server https://content.uplynk.com/wv
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]processKey: Key Usable!aamp ipv4=1 enabled
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getLicense:668 Sending DRMMetaDat] license request to server : https://content.uplynk.com/wv 
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]getLicense:768 DRM Session Manager Received license data from server; Curl total time  = 0.4

License acquisition success

Code Block
languagebash
themeMidnight
titleLicense acquisition success
PrivateInstanceAAMP::SendEventAsync:2656 Failed to send event type  25
Mar 18 05:35:42 HP40ARDK WPEFrameworkWPEWebProcess[679016788]: http://cpetestutility.stb.r53.xcal.tv/players/version10/js/src/Mediaplayer.js:914:32: CONSOLE LOG AAMP: (state) playback state changed: {"state":2}[AAMP-PLAYER]getLicense:769 acquireLicense SUCCESS! license request attempt 1; response code : http 200
Mar 18 05:35:42 HP40ARDK WPEFrameworkWPEWebProcess[679016788]: http://cpetestutility.stb.r53.xcal.tv/players/version10/js/src/Mediaplayer.js:932:40: CONSOLE LOG State not expected[AAMP-PLAYER]HttpRequestEnd: 1,0,200,0.3520,0.3522,0.0352,0.1695,0.0043,0.1409,0.1410,0.0000,713,144,https://content.uplynk.com/wv
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]handleLicenseResponse:1349 license acquisition completed
Mar 18 05:35:42 HP40ARDK WPEFrameworkWPEWebProcess[679016788]: http://cpetestutility.stb.r53.xcal.tv/players/version10/js/src/Mediaplayer.js:914:32: CONSOLE LOG AAMP: (state) playback state changed: {"state":3}[AAMP-PLAYER]processLicenseResponse:1397 Updating the license response to the aampDRMSession(CDM)
Mar 18 05:35:42 HP40ARDK WPEFrameworkWPEWebProcess[679016788]: http://cpetestutility.stb.r53.xcal.tv/players/version10/js/src/Mediaplayer.js:932:40: CONSOLE LOG State not expected[AAMP-PLAYER]aampDRMProcessKey:229: at 0xac0abf18, with 0xac0611c0, 0xac0a3ff0
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]SendDRMMetaData name =  value = 0Calling opencdm_session_update, key length=713
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPDkeyUpdateOCDM:178:StreamSelection 5865 > Media[audio] enabled

...

 at 0xac0abf18, with 0xac0611c0, 0xac0a3ff0
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]keysUpdatedOCDM:192: at 0xac0abf18, with 0xac0611c0, 0xac0a3ff0
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]Key Status updated
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]processKey: Key Usable!
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]Sending DRMMetaDat]
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateInstanceAAMP::SendEventAsync:2656 Failed to send event type  25
Mar 18 05:35:42 HP40ARDK WPEFramework[6790]: http://cpetestutility.stb.r53.xcal.tv/players/version10/js/src/Mediaplayer.js:914:32: CONSOLE LOG AAMP: (state) playback state changed: {"state":2}
Mar 18 05:35:42 HP40ARDK WPEFramework[6790]: http://cpetestutility.stb.r53.xcal.tv/players/version10/js/src/Mediaplayer.js:932:40: CONSOLE LOG State not expected
Mar 18 05:35:42 HP40ARDK WPEFramework[6790]: http://cpetestutility.stb.r53.xcal.tv/players/version10/js/src/Mediaplayer.js:914:32: CONSOLE LOG AAMP: (state) playback state changed: {"state":3}
Mar 18 05:35:42 HP40ARDK WPEFramework[6790]: http://cpetestutility.stb.r53.xcal.tv/players/version10/js/src/Mediaplayer.js:932:40: CONSOLE LOG State not expected
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]SendDRMMetaData name =  value = 0
Mar 18 05:35:42 HP40ARDK WPEWebProcess[16788]: [AAMP-PLAYER]PrivateStreamAbstractionMPD::StreamSelection 5865 > Media[audio] enabled

Finally tune succeeded and tune time print will have the statistics

...