Skip to main content

Pausing a normal selected service triggers EOS; in turn switches the player rate to 1.0.

15 replies [Last post]
ssathish
Offline
Joined: 2011-05-26
Points: 0

Scenario:
- Select a service with default minimum duration.
- Change the player rate to 0.0 (pause rate).
- Wait for 20 seconds to let grow the buffer.
- Change the player rate to 30.0.

Issue:
This scenario will work fine as expected on first attempt.
On second attempt it fails due to the reason as described below:
- After the player rate set to pause (0.0), EOS being triggered which causes pipeline.bus_call() to initiate "beginning of file encountered" error.
- Now, the rate is changed from 0.0 (pause) to 1.0 (live mode) due to EOS.
- Since, the player rate is currently set to 1.0, again changing the player rate to 30.0 fails.

Attached RI log for the same <<RILog.txt>>.

In addition to the aforesaid issue, few errors also observed stating "mpeg2video @ 0xcce7a70]ac-tex damaged at 0 12". Attached log <<Console_mpeg_error.txt>>

The buffer reaches EOS within very few seconds after starting the service, isn't due to GStreamer fault? Please comment.

Note: Two log files are attached in logs.zip

AttachmentSize
log.zip35.63 KB

Reply viewing options

Select your preferred way to display the comments and click "Save settings" to activate your changes.
ssathish
Offline
Joined: 2011-05-26
Points: 0

Please let me know comments.

mkorzen
Offline
Joined: 2008-03-05
Points: 0

Which OCAP application are you using to exercise this scenario? Is it DVR Exerciser or something else?

Best -

Marcin

ssathish
Offline
Joined: 2011-05-26
Points: 0

I have used TDK application [Test case id: TC0076] to verify the forward rate 30x speed scenario.

ssathish
Offline
Joined: 2011-05-26
Points: 0

Hi,

Please let me know your comments.

Thanks

mkorzen
Offline
Joined: 2008-03-05
Points: 0

Please file a bug in issue tracker...

Best -

Marcin

ssathish
Offline
Joined: 2011-05-26
Points: 0

Filed an issue tracker OCAP_RI-597

landerson
Offline
Joined: 2008-12-17
Points: 0

The RI on PC Platform does not support a rate of 30. Can you test with a rate of 32? Can a different rate be used to demonstrate the issue? I tried performing the steps listed in OCAP_RI-597 using DvrExerciser and canned streams. I was unable to reproduce the problem with +2x. I saw video at 2x rate, when EOS was reached it automatically switched back to 1x playback at current live point. I did not see any issues with subsequent rate change attempts, it always succeeded - fast forward at 2x to EOS and then switched to 1x playback at live point. Can you reproduce this issue with DvrExerciser and canned streams?

ssathish
Offline
Joined: 2011-05-26
Points: 0

Hi,

I have tried with 32x speed rate and observed the same issue as 30x speed reported. <<Attached RILog_32xSpeed_debug.txt>>
The EOS been triggered after pause rate and before 32x rate change.
Please confirm whether you are able to get the expected result consistently, because the issue is reproducible for second/third attempt.

Meanwhile, I am trying to execute DvrExcerciser xlet to depict the same scenario.

landerson
Offline
Joined: 2008-12-17
Points: 0

I reran TC0076 3 times restarting RI in between each run and as expected the test passed everytime.

I reran the same test 4 times without restarting RI and the test passes ever other time (passes 1st and 3rd time while fails 2nd and 4th run).

ssathish
Offline
Joined: 2011-05-26
Points: 0

As suggested in IT597, "time to reach EOS" formula is implemented in trickplay scenario [TDK test case TC0582].

When PAUSE rate is applied with sufficient buffer availability, the rate was set successfully.
Meanwhile, EOS is getting triggered twice from GStreamer.

While receiving RateChangeEvent for 0.0, the rate is changed from 0.0 to 1.0 due to DVR.START_OF_FILE asyncEvent from platform (EOS -> BeginningOfContentEvent -> DVR.START_OF_FILE).
The GStreamer triggers EOS even though sufficient buffer is available in platform.

This issue is observed from RI 122 REL B.1 version (till latest release RI 122 REL E) and it is reproducible with set of test cases execution which is attached in the zip file [Set of test cases to execute.txt]

From the attached RI log:

.....
[TrickModePerformanceOnBufferedContentDcli.setPlayerRate: 131] - Duration 2 :: 5.9375
.....
20120730 14:38:47.971 DEBUG RI.Stack- 564329 [Thread-99] DEBUG player.AbstractDVRServicePlayer - Id: 0xE9A9962D: DVR.setRate(0.0)
.....
20120730 14:38:48.080 INFO RI.Pipeline- bus_call -- flushing pipeline due to EOS
.....
20120730 14:38:48.096 INFO RI.Pipeline- bus_call -- beginning of file encountered
.....
20120730 14:38:48.174 DEBUG RI.Stack- 564533 [ThreadPool-System-6] DEBUG mpe.DVRAPIImpl - setRate(dvr=0x188c50b8, rate=1.0)
.....
RI.Stack.StdOut- [DCLI-CLIENT] [INFO] [TrickModePerformanceOnBufferedContentDcli.setPlayerRate: 136] - Duration 3 :: 6.203125
.....
20120730 14:38:51.112 INFO RI.Pipeline- bus_call -- flushing pipeline due to EOS
.....
20120730 14:38:51.112 INFO RI.Pipeline- bus_call -- end of file encountered
.....

Scenario (TDK TC0582):
- Select a service and receive TIMESHIFT event.
- Wait 2 seconds for buffering and set pause rate (0.0).
- Wait for 3 seconds and set rate to 0.5 slow forward speed.

Please refer the attached log [TC0582_RIlog_24Aug2012.zip] and let me know whether GStreamer is the root cause of the issue.

ssathish
Offline
Joined: 2011-05-26
Points: 0

Thanks for the confirmation.
Same behavior is observed intermittently with 32x speed without RI restart.

ssathish
Offline
Joined: 2011-05-26
Points: 0

Please let me know comments for reply-comment#10

landerson
Offline
Joined: 2008-12-17
Points: 0

I have looked at the description and logs provide. Is the issue that when you pause broadcast and switch to TSB playback, it doesn't pause but goes to 1x playback due to end of content event? If so, can you file a new Issue Tracker issue to report this issue? Are the list of tests failing? If so, also include the list of tests in the issue tracker.

ssathish
Offline
Joined: 2011-05-26
Points: 0

Yes, when player is in pause rate, EOS been triggered from GStreamer which in turn switched to BOC event and player rate is moved to live speed. Again, one more EOS been triggered from GStreamer, in turn switched to EOC event.

There is an issue tracker#OCAP_RI-597 already existed and closed with few formula suggestion. Please confirm whether the same IT can be reopened to report the current issue.

ssathish
Offline
Joined: 2011-05-26
Points: 0

Raised a new issue tracker OCAP_RI-677.