Empty recordings

[sebhki]

Hi

I realised yesterday one of the channels on Vu+ ultimo is consistently producing empty recordings (showing up 0:00 in the recording list, 0B in ftp).
It's France2 on Astra.
Other channels are recorded fine (even HD).
I use oscam to read a live card (TNTSAT).
VIX 3, build 569

Any idea ?

[JosFop4Sat]

I have the same issues several times last weeks on Dutch channels RTL5 HD and SBS6 HD on A23.5.
This problem occurs when those channels are recorded at the same time, but I cannot reproduce it each time.
I use ViX 3.0 build 600.

[Rob van der Does]

Maybe the debug logs show relevant info.
And what do the timers tell you (select the finished timer in the timerlist and press 'info')?

[Rob van der Does]

VIX 3
Hence moved to the ViX-section.

[sebhki]

Thanks Rob and sorry to post in wrong section. Made some tests.
- Tried to record on LAN location : same problem (other channel records fine)
- try to flash other image (POD) & record problematic channel : no problem, it records fine
- re-flashed with build 600, imported backup : problem is here.

How do I get debug logs ?
What about timer info ? the problem happens too with "direct record" ie no use of timer

[sebhki]

to better describe the problem & steps to reproduce :
- zap to France2 (it shows, so properly descrambled by oscam or cccam - local card)
- press record, start recording
- in recording list, recording being made (red dot) stays at 0:00 in length

Same problem with timers ...

[sebhki]

tried with following cams (same results for all) to read my local card
- oscam
- oscam + cccam
- hypercam

All setup descramble ( I can see the channel ), but recordings of this channel keeps empty

[Rob van der Does]

How do I get debug logs?
LogManagr --> enable debuglogs --> restart GUI


What about timer info ? the problem happens too with "direct record" ie no use of timer
Yes, but the info on finished timers might give a clue.

[sebhki]

oscam + cccam

[TIMER] record event 4
[TIMER] record event 6
[eDVBCIInterfaces] gotPMT
[eDVBCAService] don't build/send the same CA PMT twice
Timeout!
unimplemented: scale on non-accel surfaces
getting PCR failed!
[eDVBServiceRecord] now running: BOULEVARD DU PALAIS (6000 seconds)
[TIMER] record event 9
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 83(16)
[SoftcamManager] oscam is responding like it should
[SoftcamManager] CCcam2.3 already running
[SoftcamManager] Checking if CCcam2.3 is frozen
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 83(16)
[SoftcamManager] CCcam2.3 is responding like it should
main thread is non-idle! display spinner!
loading streaminfo for /hdd/movie/20130216 1746 - FRANCE 2 - Boulevard du palais.ts
[eMPEGStreamInformation] index 0 is past EOF
eMPEGStreamInformation::getFirstFrame - no data (yet?)
getStructureEntryFirst failed because file size is zero
read error
eMPEGStreamInformation::getLastFrame - no data (yet?)
[@ML] m_streaminfo.getLastFrame failed, fallback
loading streaminfo for /hdd/movie/20130216 1630 - FRANCE 2 - Boulevard du palais.ts
[eMPEGStreamInformation] index 0 is past EOF
eMPEGStreamInformation::getFirstFrame - no data (yet?)
getStructureEntryFirst failed because file size is zero
read error
eMPEGStreamInformation::getLastFrame - no data (yet?)
[@ML] m_streaminfo.getLastFrame failed, fallback

[sebhki]

with hypercam


[OpenWebif] page '/web/remotecontrol?command=352' ok (cheetah template)
[Timer] Record RecordTimerEntry(name=Boulevard du palais, begin=Sat Feb 16 18:08:09 2013, serviceref=1:0:1:20B6:432:1:C00000:0:0:0:, justplay=False)
[TIMER] activating state 1
[TIMER] Found enough free space to record
[TIMER] Filename calculated as: '/media/hdd/movie/20130216 1808 - FRANCE 2 - Boulevard du palais'
recording service: <enigma.eServiceReference; proxy of <Swig Object of type 'eServiceReference *' at 0x1d55650> >
use cached_channel
allocate Channel: res 0
[eDVBCIInterfaces] addPMTHandler 1:0:1:20B6:432:1:C00000:0:0:0:
ok ... now we start!!
RECORD service event 5
[TIMER] record event 6
[eDVBCAService] add demux 0 to slot 2 service 1:0:1:20B6:432:1:C00000:0:0:0:
[eDVBCAService] don't build/send the same CA PMT twice
RECORD service event 6
tuned..
[TIMER] record event 2
query epg event id 39488
found event.. store to disc
[TIMER] prepare ok, waiting for begin
[Trashcan] Recording in progress 2
[TIMER] activating state 2
[TIMER] start recording
[TIMER] record event 0
AddPopup, id = None
Recording to /media/hdd/movie/20130216 1808 - FRANCE 2 - Boulevard du palais.ts...
start recording...
RECORD: have 1 video stream(s) (00a7), and 1 audio stream(s) (006c), and the pcr pid is 00a7, and the text pid is 0031
ADD PID: 0000
ADD PID: 0031
ADD PID: 006c
ADD PID: 00a7
setIoPrio realtime level 7 ok
[eFilePushThreadRecorder] THREAD START
[TIMER] record event 4
PATready
use pmtpid 0518 for service_id 20b6
AiO Screengrabber 8.3.0

Detected STB: Brcm7405
Grabbing 32bit Framebuffer ...
640
... Framebuffer-Size: 1280 x 720
Grabbing Video ...
... converting Video from YUV to RGB color space
... Video-Size: 720 x 576
Resizing Video to 1280 x 720 ...
Merge Video with Framebuffer ...
Saving 24 bit /tmp/screenshot.jpg ...
... Done !
RECORD service event 5
start recording...
RECORD: have 1 video stream(s) (00a7), and 3 audio stream(s) (006c, 006d, 006b) (0031, 0031), and the pcr pid is 00a7, and the text pid is 0031
ADD PID: 006b
ADD PID: 006d
ADD PID: 0518
ADD PID: 0816
ADD PID: 15d6
ADD PID: 15d7
ADD PID: 15d8
ADD PID: 15d9
ADD PID: 15da
ADD PID: 15db
ADD PID: 19bf
ADD PID: 19c1
ADD PID: 19c2
ADD PID: 19c3
ADD PID: 19c4
ADD PID: 19c5
ADD PID: 19c6
[TIMER] record event 4
[TIMER] record event 6
[eDVBCIInterfaces] gotPMT
[eDVBCAService] don't build/send the same CA PMT twice
pcr of eit change: 12fc7ef11
[eDVBServiceRecord] now running: BOULEVARD DU PALAIS (6000 seconds)
[TIMER] record event 9
Timeout!
unimplemented: scale on non-accel surfaces
action -> InfobarShowHideActions hide
[OpenWebif] page '/web/remotecontrol?command=174' ok (cheetah template)
AiO Screengrabber 8.3.0

Detected STB: Brcm7405
Grabbing 32bit Framebuffer ...
640
... Framebuffer-Size: 1280 x 720
Grabbing Video ...
... converting Video from YUV to RGB color space
... Video-Size: 720 x 576
Resizing Video to 1280 x 720 ...
Merge Video with Framebuffer ...
Saving 24 bit /tmp/screenshot.jpg ...
... Done !
[OpenWebif] page '/web/powerstate' ok (cheetah template)
action -> InfobarChannelSelection ChannelMinusPressed
playing 1:0:1:20AE:432:1:C00000:0:0:0:
[eFilePushThreadRecorder] stopping thread.
[eDVBRecordFileThread] waiting for aio to complete
[eDVBRecordFileThread] buffer usage histogram (20 buffers of 188 kB)
0: 87
1: 426
2: 52
[eFilePushThreadRecorder] THREAD STOP
thread joined 0
remove timeshift file
old thread joined 0
setIoPrio best-effort level 7 ok
[eBackgroundFileEraser] deleting '/media/usb/timeshift.l3w0dX.del'
[eDVBCAService] free slot 0 demux 0 for service 1:0:1:20B6:432:1:C00000:0:0:0:
[eDVBCAService] don't build/send the same CA PMT twice
[eBackgroundFileEraser] deleting '/media/usb/timeshift.l3w0dX.sc.del'
decoder state: play, vpid=-1, apid=-1
DEMUX_STOP - pcr - ok
DEMUX_STOP - video - ok
VIDEO_STOP - ok
AUDIO_STOP - ok
DEMUX_STOP - audio - ok
AUDIO_CONTINUE - ok
DEMUX_STOP - ttx - ok
RemovePopup, id = ZapError
use cached_channel
allocate Channel: res 0
[eDVBCIInterfaces] addPMTHandler 1:0:1:20AE:432:1:C00000:0:0:0:
ok ... now we start!!
eventNewProgramInfo 0 0
have 1 video stream(s) (00ab), and 1 audio stream(s) (007c), and the pcr pid is 00ab, and the text pid is 0035
decoder state: play, vpid=171, apid=124
DMX_SET_PES_FILTER(0xab) - pcr - ok
DEMUX_START - pcr - ok
DMX_SET_PES_FILTER(0x7c) - audio - ok
DEMUX_START - audio - ok
AUDIO_SET_BYPASS(1) - ok
AUDIO_PAUSE - ok
AUDIO_PLAY - ok
Video Device: /dev/dvb/adapter0/video0
demux device: /dev/dvb/adapter0/demux0
VIDEO_SET_STREAMTYPE 0 - ok
DMX_SET_PES_FILTER(0xab) - video - ok
DEMUX_START - video - ok
VIDEO_FREEZE - ok
VIDEO_PLAY - ok
DMX_SET_PES_FILTER(0x35) - ttx - ok
DEMUX_START - ttx - ok
VIDEO_SLOWMOTION(0) - ok
VIDEO_FAST_FORWARD(0) - ok
VIDEO_CONTINUE - ok
AUDIO_CONTINUE - ok
disable teletext subtitles
Send OK!! : OP_HBBTV_UNLOAD_AIT
[eDVBCAService] new service 1:0:1:20AE:432:1:C00000:0:0:0:
[eDVBCAService] add demux 0 to slot 0 service 1:0:1:20AE:432:1:C00000:0:0:0:
[OpenWebif] page '/web/remotecontrol?command=403' ok (cheetah template)
unimplemented: scale on non-accel surfaces
Send OK!! : OP_HBBTV_UNLOAD_AIT
PATready
use pmtpid 050f for service_id 20ae
eventNewProgramInfo 0 0
have 1 video stream(s) (00ab), and 3 audio stream(s) (007c, 007d, 007e), and the pcr pid is 00ab, and the text pid is 0035
decoder state: play, vpid=171, apid=124
Send OK!! : OP_HBBTV_UNLOAD_AIT
[eDVBCIInterfaces] gotPMT
AiO Screengrabber 8.3.0

Detected STB: Brcm7405
Grabbing 32bit Framebuffer ...
640
... Framebuffer-Size: 1280 x 720
Grabbing Video ...
... converting Video from YUV to RGB color space
... Video-Size: 720 x 0
Resizing Video to 1280 x 720 ...
Merge Video with Framebuffer ...
Saving 24 bit /tmp/screenshot.jpg ...
... Done !
Send OK!! : OP_HBBTV_UNLOAD_AIT
[TimeShift] Erasing stranded timeshift pts_livebuffer.1
old thread joined 0
setIoPrio best-effort level 7 ok
[eBackgroundFileEraser] deleting '/media/usb/pts_livebuffer.1.del'
PTS: IO-Error while cleaning Timeshift Folder ...
Start timeshift!
recording to /media/usb/timeshift.455dr0
setIoPrio realtime level 7 ok
[eFilePushThreadRecorder] THREAD START
[EITSave] query epg event id 36840, 1:0:1:20AE:432:1:C00000:0:0:0:
[EPGC] event 8fe8 not found in epgcache
[EITSave] no event found...
old thread joined 0
setIoPrio best-effort level 7 ok
[eBackgroundFileEraser] deleting '/media/usb/pts_livebuffer.1.meta.del'
[eBackgroundFileEraser] deleting '/media/usb/pts_livebuffer.1.del'
[EITSave] query epg event id 36840, 1:0:1:20AE:432:1:C00000:0:0:0:
[EPGC] event 8fe8 not found in epgcache
[EITSave] no event found...
action -> InfobarInstantRecord instantRecord
[OpenWebif] page '/web/remotecontrol?command=167' ok (cheetah template)
AiO Screengrabber 8.3.0

Detected STB: Brcm7405
Grabbing 32bit Framebuffer ...
640
... Framebuffer-Size: 1280 x 720
Grabbing Video ...
... converting Video from YUV to RGB color space
... Video-Size: 720 x 576
Resizing Video to 1280 x 720 ...
Merge Video with Framebuffer ...
Saving 24 bit /tmp/screenshot.jpg ...
... Done !
action -> WizardActions down
[OpenWebif] page '/web/remotecontrol?command=108' ok (cheetah template)
AiO Screengrabber 8.3.0

[sebhki]

this recording/channel worked ok

[Timer] Record RecordTimerEntry(name=Tous ensemble, begin=Sat Feb 16 18:10:51 2013, serviceref=1:0:1:20AE:432:1:C00000:0:0:0:, justplay=False)
[TIMER] activating state 1
[TIMER] Found enough free space to record
[TIMER] Filename calculated as: '/media/hdd/movie/20130216 1810 - TF1 - Tous ensemble'
recording service: <enigma.eServiceReference; proxy of <Swig Object of type 'eServiceReference *' at 0x1d55998> >
use cached_channel
allocate Channel: res 0
[eDVBCIInterfaces] addPMTHandler 1:0:1:20AE:432:1:C00000:0:0:0:
ok ... now we start!!
RECORD service event 5
[TIMER] record event 6
[eDVBCAService] add demux 0 to slot 1 service 1:0:1:20AE:432:1:C00000:0:0:0:
RECORD service event 6
tuned..
[TIMER] record event 2
query epg event id 43988
found event.. store to disc
[TIMER] prepare ok, waiting for begin
[Trashcan] Recording in progress 3
[TIMER] activating state 2
[TIMER] start recording
[TIMER] record event 0
AddPopup, id = None
Recording to /media/hdd/movie/20130216 1810 - TF1 - Tous ensemble.ts...
start recording...
RECORD: have 1 video stream(s) (00ab), and 1 audio stream(s) (007c), and the pcr pid is 00ab, and the text pid is 0035
ADD PID: 0000
ADD PID: 0035
ADD PID: 007c
ADD PID: 00ab
setIoPrio realtime level 7 ok
[eFilePushThreadRecorder] THREAD START
[TIMER] record event 4
PATready
use pmtpid 050f for service_id 20ae
AiO Screengrabber 8.3.0

Detected STB: Brcm7405
Grabbing 32bit Framebuffer ...
640
... Framebuffer-Size: 1280 x 720
Grabbing Video ...
... converting Video from YUV to RGB color space
... Video-Size: 720 x 576
Resizing Video to 1280 x 720 ...
Merge Video with Framebuffer ...
Saving 24 bit /tmp/screenshot.jpg ...
... Done !
RECORD service event 5
start recording...
RECORD: have 1 video stream(s) (00ab), and 3 audio stream(s) (007c, 007d, 007e) (0035, 0035), and the pcr pid is 00ab, and the text pid is 0035
ADD PID: 007d
ADD PID: 007e
ADD PID: 050f
ADD PID: 07f8
ADD PID: 168a
ADD PID: 168b
ADD PID: 168c
ADD PID: 168d
ADD PID: 168e
ADD PID: 1a74
ADD PID: 1a75
ADD PID: 1a77
ADD PID: 1a78
ADD PID: 1a79
ADD PID: 1a7a
[TIMER] record event 4
[TIMER] record event 6
[eDVBCIInterfaces] gotPMT
[eDVBCAService] don't build/send the same CA PMT twice
pcr of eit change: 598231c6
[eDVBServiceRecord] now running: TOUS ENSEMBLE (3300 seconds)
[TIMER] record event 9
Timeout!

[sebhki]

Timer on said channel says : done!

[Rob van der Does]

This problems looks like a driver issue. You could be of great help by trying:
1- First install the latest drivers from
http://archive.vuplus.com/download/drivers/ Unzip the files to /lib/modules/3.1.1/extra , overwriting the existing ones, and see if this makes any difference for the issue?
2- If that doesn't help, please install VU's Original 2.1 Image from
http://code.vuplus.com/ or the VTi image based on that from
http://www.vuplus-support.org/wbb2/thread.php?threadid=43247

[sebhki]

Thanks for the advices Rob.
>unzip the files to /lib/modules/3.1.1/extra , overwriting the existing ones
Done, reboot, same problem ...

As for installing other image, what do you suggest exactly ? Install it and revert to ViX ? Would that update the drivers somehow ?
Because it's interesting to note here that I tried the following yesterday ( I was running build 567 I think)
- I flashed to POD image
- problem did NOT reproduce (= I could record the channel giving me the problem at hand correctly )
- Flashed with ViX build 600 & imported backup
- problem reproduces now

[sebhki]

I managed to fix the issue with the solution in
http://www.world-of-satellite.com/showthread.php?26386-Problem-with-playback-when-still-recording-same-channel-using-OSCam/page2

[Mosher00]

Hi All,
have exactly the same issue (zero length recordings), had it on older builds and have it latest build. Seems to happen randomly on some freesat channels but almost always happens on the sky movie channels.
Default recording is on normal, tried different recording settings (e.g. record ECM) but made no difference. Timer info shows no errors and start and stop time in the log is also correct. So recording runs for the full programmed time but there is no resulting file (well file is there but has zero bytes).
Any suggestions how to solve this?
Regards
Markus

[Burnham]

Zero recordings are often caused when you can't decrypt the channel.

Check your decryption method, but be careful with anything you post here and follow the forum rules.

[Mosher00]

yes, I can see how that could cause this, I use cccam with my sky card which has a valid subscription and decodes fine when watching live TV.
So I am not sure what I could try change in the setup to get it to work. If anyone has any suggestions don't hesitate :)