2005-10-18, 06:05 PM
I was watching a show that was still recording last night and found a problem at one point when I tried to skip forward during a commercial break.
I was trying to do a basic 1s skip forward, but it ended up skipping quite a bit further. When I reviewed the log file, it looks like it failed to call mvpVideoDuration before it computed the skip distance so it skipped to the wrong location.
Here are the relevant log sections:
I was trying to do a basic 1s skip forward, but it ended up skipping quite a bit further. When I reviewed the log file, it looks like it failed to call mvpVideoDuration before it computed the skip distance so it skipped to the wrong location.
Here are the relevant log sections:
Code:
... here is the last skip from the previous commercial break
... note that the stream duration is set at 2869
10/17/2005 8:46:50 PM.093 VERBOSE [57] Request 200000 bytes (file size:2166824896 file location: 1547143240)
10/17/2005 8:46:50 PM.093 VERBOSE [57] about to check
10/17/2005 8:46:50 PM.093 VERBOSE [57] ...livetv mode
10/17/2005 8:46:50 PM.109 VERBOSE [56] MVP data read: 1 (4)
10/17/2005 8:46:50 PM.109 VERBOSE [56] User pressed: 15
10/17/2005 8:46:50 PM.109 VERBOSE [56] Skipping forward: 1
10/17/2005 8:46:50 PM.109 VERBOSE [56] mvpVideoDuration: 2869
10/17/2005 8:46:50 PM.109 VERBOSE [56] MVPSetPlaybackPosition() request to set position to: 2057 seconds
10/17/2005 8:46:50 PM.109 VERBOSE [56] MVP not sending frame
10/17/2005 8:46:50 PM.109 VERBOSE [56] MVP request processed
10/17/2005 8:46:50 PM.171 VERBOSE [56] MVP data read: 1 (9)
10/17/2005 8:46:50 PM.171 VERBOSE [56] PACK9: 0000005C3A9588000000
10/17/2005 8:46:50 PM.171 VERBOSE [56] MVP request processed
10/17/2005 8:46:50 PM.281 VERBOSE [57] Stream Length: 2166939584
10/17/2005 8:46:50 PM.281 VERBOSE [57] Stream Duration: 2869
10/17/2005 8:46:50 PM.281 VERBOSE [57] requestedPositionInSeconds: 2057
10/17/2005 8:46:50 PM.281 VERBOSE [57] Set position to:1553640545
10/17/2005 8:46:50 PM.296 VERBOSE [57] Received request to seek to pos:1553640545
10/17/2005 8:46:50 PM.328 VERBOSE [57] Request 200000 bytes (file size:2166958016 file location: 1553640545)
... 5 minutes of regular show viewing log data omitted here
10/17/2005 8:51:49 PM.796 VERBOSE [57] Request 200000 bytes (file size:2392690624 file location: 1782840545)
10/17/2005 8:51:49 PM.796 VERBOSE [57] about to check
10/17/2005 8:51:49 PM.796 VERBOSE [57] ...livetv mode
10/17/2005 8:51:49 PM.875 VERBOSE [56] MVP data read: 1 (9)
10/17/2005 8:51:49 PM.875 VERBOSE [56] PACK9: 0000006A470A21000000
10/17/2005 8:51:49 PM.875 VERBOSE [56] MVP request processed
10/17/2005 8:51:50 PM.031 VERBOSE [57] Request 200000 bytes (file size:2392862656 file location: 1783040545)
10/17/2005 8:51:50 PM.031 VERBOSE [57] about to check
10/17/2005 8:51:50 PM.031 VERBOSE [57] ...livetv mode
10/17/2005 8:51:50 PM.062 VERBOSE [56] MVP data read: 1 (4)
... here is a skip forward at the beginning of the next commercial break
... note that we don't see a call to mvpVideoDuration
... and that the Stream Duration value is the same as it was
... 5 minutes earlier although Stream Length has updated to
... reflect newly recorded material
10/17/2005 8:51:50 PM.062 VERBOSE [56] User pressed: 43
10/17/2005 8:51:50 PM.062 VERBOSE [56] Skipping forward: 1
10/17/2005 8:51:50 PM.062 VERBOSE [56] MVPSetPlaybackPosition() request to set position to: 2390 seconds
10/17/2005 8:51:50 PM.062 VERBOSE [56] MVP not sending frame
10/17/2005 8:51:50 PM.062 VERBOSE [56] MVP request processed
10/17/2005 8:51:50 PM.359 VERBOSE [56] MVP data read: 1 (9)
10/17/2005 8:51:50 PM.359 VERBOSE [56] PACK9: 0000006A4A1761000000
10/17/2005 8:51:50 PM.359 VERBOSE [56] MVP request processed
10/17/2005 8:51:50 PM.437 VERBOSE [57] Stream Length: 2393169856
10/17/2005 8:51:50 PM.437 VERBOSE [57] Stream Duration: 2869
10/17/2005 8:51:50 PM.437 VERBOSE [57] requestedPositionInSeconds: 2390
10/17/2005 8:51:50 PM.437 VERBOSE [57] Set position to:1993613090
10/17/2005 8:51:50 PM.453 VERBOSE [57] Received request to seek to pos:1993613090
10/17/2005 8:51:50 PM.484 VERBOSE [57] Request 200000 bytes (file size:2393206720 file location: 1993613090)
10/17/2005 8:51:50 PM.484 VERBOSE [57] about to check
10/17/2005 8:51:50 PM.484 VERBOSE [57] ...livetv mode
10/17/2005 8:51:50 PM.546 VERBOSE [57] Request 200000 bytes (file size:2393288640 file location: 1993813090)
10/17/2005 8:51:50 PM.546 VERBOSE [57] about to check
10/17/2005 8:51:50 PM.546 VERBOSE [57] ...livetv mode
10/17/2005 8:51:50 PM.546 VERBOSE [56] MVP data read: 1 (9)
10/17/2005 8:51:50 PM.546 VERBOSE [56] PACK9: 00000076D72C62000000
10/17/2005 8:51:50 PM.546 VERBOSE [56] MVP request processed
10/17/2005 8:51:50 PM.578 VERBOSE [56] MVP data read: 1 (7)
10/17/2005 8:51:50 PM.578 VERBOSE [56] MVP request processed
10/17/2005 8:51:50 PM.640 VERBOSE [56] MVP data read: 1 (9)
10/17/2005 8:51:50 PM.640 VERBOSE [56] PACK9: 00000076DA39A2000000
10/17/2005 8:51:50 PM.640 VERBOSE [56] MVP request processed
10/17/2005 8:51:50 PM.640 VERBOSE [57] Request 200000 bytes (file size:2393405376 file location: 1994013090)
... Request 200000 bytes entries removed to shorten log
... At this point I realized we had skipped forward too far
... and requested a back skip
... Note that mvpVideoDuration is called this time, and that
... the duration is now correct
10/17/2005 8:51:53 PM.406 VERBOSE [57] Request 200000 bytes (file size:2395647936 file location: 1998013090)
10/17/2005 8:51:53 PM.406 VERBOSE [57] about to check
10/17/2005 8:51:53 PM.406 VERBOSE [57] ...livetv mode
10/17/2005 8:51:53 PM.625 VERBOSE [56] MVP data read: 1 (4)
10/17/2005 8:51:53 PM.625 VERBOSE [56] User pressed: 14
10/17/2005 8:51:53 PM.625 VERBOSE [56] Skipping back: 1
10/17/2005 8:51:53 PM.625 VERBOSE [56] mvpVideoDuration: 3173
10/17/2005 8:51:53 PM.625 VERBOSE [56] MVPSetPlaybackPosition() request to set position to: 2636 seconds
10/17/2005 8:51:53 PM.625 VERBOSE [56] MVP not sending frame
10/17/2005 8:51:53 PM.625 VERBOSE [56] MVP request processed
10/17/2005 8:51:53 PM.765 VERBOSE [56] MVP data read: 1 (9)
10/17/2005 8:51:53 PM.765 VERBOSE [56] PACK9: 000000771A4FE2000000
10/17/2005 8:51:53 PM.765 VERBOSE [56] MVP request processed
10/17/2005 8:51:53 PM.859 VERBOSE [57] Stream Length: 2395971520
10/17/2005 8:51:53 PM.859 VERBOSE [57] Stream Duration: 3173
10/17/2005 8:51:53 PM.859 VERBOSE [57] requestedPositionInSeconds: 2636
10/17/2005 8:51:53 PM.859 VERBOSE [57] Set position to:1990476182
10/17/2005 8:51:53 PM.875 VERBOSE [57] Received request to seek to pos:1990476182
10/17/2005 8:51:53 PM.906 VERBOSE [57] Request 200000 bytes (file size:2395987904 file location: 1990476182)