PDA

View Full Version : [VU+ Solo2] Recording issue with HD channels



rassi
21-01-13, 15:14
Anyone else seen recording issues with HD channels?
I got 0 sized recording now and then, at the moment i´ve noticed this only with HD channels.
Example from this morning, i did have 5 recordings almost in a row, first 4 recordings OK and then final record was just
0 bytes. Same thing on other days as well, some of the recordings do work as they should, but then suddenly 0 sized.

Channels which i have noted this for sure:
Cmore Series HD Freq: 10934
MTV3 HD: Freq: 10747

and other´s are the same for both channels:
S.rate: 25000
Pol: H
Fec: 3/4
Mod: 8PSK

Now using build 569

Debug log:




job Components.Task.Job name=AutoTimer #tasks=0 completed with [] in None
VIDEO_GET_EVENT - ok
[TIMER] activating state 1
[TIMER] Filename calculated as: '/media/hdd/movie/20130121 1100 - C More Series HD - Bedlam'
[TIMER] Found enough free space to record
recording service: <enigma.eServiceReference; proxy of <Swig Object of type 'eServiceReference *' at 0x26a89b0> >
allocate channel.. 000e:0046
available channel.. 0041:0046
opening frontend 1
(1)tune
RotorCmd ffffffff, lastRotorCmd ffffffff
prepare_sat System 1 Freq 10934000 Pol 0 SR 25000000 INV 2 FEC 3 orbpos 3592 system 1 modulation 2 pilot 2, rolloff 1
tuning to 1184 mhz
OURSTATE: tuning
allocate Channel: res 0
[eDVBCIInterfaces] addPMTHandler 1:0:19:584:E:46:E080000:0:0:0:
allocate demux
query epg event id 4394
[EPGC] event 112a not found in epgcache
query stime 11:0:0, etime 12:0:0, qtime 11:30:0
found event.. store to disc
[TIMER] prepare ok, waiting for begin
[Trashcan] probing folders
[SEC] set static current limiting
set sequence pos 3
set sequence pos 4
[SEC] setVoltage 2
[SEC] sleep 200ms
[Trashcan] found following trashcan's: ['/media/hdd/movie/.Trash']
[Trashcan] looking in trashcan /media/hdd/movie/.Trash
[Trashcan] /media/hdd/movie/.Trash: Size: 20259481334
[Trashcan] /media/hdd/movie/.Trash: Size now: 20259481334
job Components.Task.Job name=Cleaning Trashes #tasks=1 completed with [] in None
[SEC] invalidate current switch params
[SEC] sendDiseqc: e00000(DiSEqC reset)
[SEC] sleep 50ms
[SEC] sendDiseqc: e00003(DiSEqC peripherial power on)
[SEC] sleep 150ms
[SEC] sendDiseqc: e01038f2(?)
[SEC] sleep 50ms
set sequence pos 3
set sequence pos 3
[SEC] update current switch params
[SEC] startTuneTimeout 5000
[SEC] setFrontend 1
setting frontend 1
(1)fe event: status 0, inversion off, m_tuning 1
[SEC] sleep 500ms
(1)fe event: status 7, inversion off, m_tuning 2
(1)fe event: status 1f, inversion off, m_tuning 3
OURSTATE: ok
[eDVBLocalTimerHandler] channel 0x73308d78 running
[eEPGCache] channel 0x73308d78 running
stop release channel timer
ok ... now we start!!
[EPGC] next update in 2 sec
RECORD service event 5
[TIMER] record event 6
[eDVBCAService] new service 1:0:19:584:E:46:E080000:0:0:0:
[eDVBCAService] add demux 1 to slot 0 service 1:0:19:584:E:46:E080000:0:0:0:
RECORD service event 6
tuned..
[TIMER] record event 2
PATready
use pmtpid 0106 for service_id 0584
RECORD service event 5
[TIMER] record event 6
[eDVBCIInterfaces] gotPMT
[eDVBCAService] don't build/send the same CA PMT twice
[SEC] set dynamic current limiting
sdt update done!
[eDVBServiceRecord] now running: Weeds (1800 seconds)
[TIMER] record event 9
[EPGC] start caching events(1358758783)
[EPGC] abort non avail schedule reading
[EPGC] abort non avail schedule other reading
[EPGC] abort non avail netmed schedule reading
[EPGC] abort non avail netmed schedule other reading
[EPGC] abort non avail FreeSat schedule_other reading
[EPGC] abort non avail viasat reading
[EPGC] nownext finished(1358758790)
[EPGC] stop caching events(1358758790)
[EPGC] next update in 60 min
[TIMER] activating state 2
[TIMER] start recording
[TIMER] record event 0
AddPopup, id = None
Recording to /media/hdd/movie/20130121 1100 - C More Series HD - Bedlam.ts...
start recording...
RECORD: have 1 video stream(s) (0206), and 2 audio stream(s) (0298, 0299) (0260, 0261, 0262, 0263), and the pcr pid is 0206, and the text pid is ffffffff
ADD PID: 0000
ADD PID: 006a
ADD PID: 0106
ADD PID: 0206
ADD PID: 0260
ADD PID: 0261
ADD PID: 0262
ADD PID: 0263
ADD PID: 0298
ADD PID: 0299
setIoPrio realtime level 7 ok
[eFilePushThreadRecorder] THREAD START
[TIMER] record event 4
Timeout!
getting PCR failed!
[eDVBServiceRecord] now running: Bedlam (3600 seconds)
[TIMER] record event 9
VIDEO_GET_EVENT - ok
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 78(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None

IAmATeaf
21-01-13, 16:27
You're not starting 2 recordings at exactly the same time are you? If so then is one the failed recording one where there was another started at the same time?

rassi
21-01-13, 17:25
You're not starting 2 recordings at exactly the same time are you? If so then is one the failed recording one where there was another started at the same time?

Nope, just one and latest succesfull recording before this 0 byte has ended hour before it.

rassi
31-01-13, 06:46
oh no.

"WRITE ERROR on recording, disk full?" issue probably back with me...
I thought that I will make ultimate solutions for this (http://www.world-of-satellite.com/showthread.php?21029-quot-WRITE-ERROR-on-recording-disk-full-quot-messages-now-and-then), just swapping
Duo to Solo2, but no...

Using build 588 now, first time when noticed this Disk full issue with Solo2

Should be enough for one HD channel recording..:


Results for write throughput:
357.913 Mbit/s with udp,async,wsize=32768

Results for read throughput:
1073.741 Mbit/s with udp,async,rsize=32768




root@solo2:~# df
Filesystem 1K-blocks Used Available Use% Mounted on
ubi0:rootfs 201500 64912 136588 32% /
devtmpfs 285772 4 285768 0% /dev
tmpfs 64 0 64 0% /media
/dev/sda1 981660 723168 258492 74% /media/usb
tmpfs 285868 184 285684 0% /var/volatile
192.168.1.6:/mnt/video/dream/
7324175520 7186582912 137592608 98% /media/hdd




root@solo2:~# cat /proc/mounts
rootfs / rootfs rw 0 0
ubi0:rootfs / ubifs rw,sync,relatime 0 0
devtmpfs /dev devtmpfs rw,relatime,size=285772k,nr_inodes=71443,mode=755 0 0
proc /proc proc rw,relatime 0 0
sysfs /sys sysfs rw,relatime 0 0
tmpfs /media tmpfs rw,relatime,size=64k 0 0
/dev/sda1 /media/usb vfat rw,relatime,fmask=0000,dmask=0000,allow_utime=0022 ,codepage=cp437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro 0 0
tmpfs /var/volatile tmpfs rw,relatime 0 0
usbfs /proc/bus/usb usbfs rw,relatime 0 0
devpts /dev/pts devpts rw,relatime,gid=5,mode=620 0 0
192.168.1.6:/mnt/video/dream/ /media/hdd nfs rw,relatime,vers=3,rsize=32768,wsize=32768,namlen= 255,soft,nolock,proto=tcp,port=65535,timeo=14,retr ans=3,sec=sys,local_lock=all,addr=192.168.1.6 0 0




root@solo2:~# blkid
/dev/ubi0_0: UUID="d9983b67-9296-4320-b94a-c84c6a92b10c" TYPE="ubifs"
/dev/sda1: UUID="F886-6A81" TYPE="vfat"





poll: unhandled POLLERR/HUP/NVAL for fd 45(8)
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 80(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None
[eFilePushThreadRecorder] Warning: All write buffers busy
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eFilePushThreadRecorder] WRITE ERROR, aborting thread
[eDVBRecordFileThread] waiting for aio to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBServiceRecord] record write error
stop recording!
[eFilePushThreadRecorder] stopping thread.
[eDVBRecordFileThread] aio_suspend failed: Interrupted system call
[eDVBRecordFileThread] Waiting for I/O to complete
main thread is non-idle! display spinner!
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eDVBRecordFileThread] buffer usage histogram (20 buffers of 188 kB)
0: 3
1: 4440
2: 4243
3: 3
4: 3
5: 3
6: 3
7: 2
8: 2
9: 1
10: 1
11: 1
12: 1
13: 1
14: 1
15: 1
16: 1
17: 1
18: 1
19: 1
20: 1
[eFilePushThreadRecorder] THREAD STOP
thread joined 0
loading streaminfo for /media/hdd/movie/20130131 0230 - C More Series HD - Parks and Recreation.ts
fixed up 9bd56 to 228 (offset 0)
[TIMER] record event 5
[TIMER] record event 8
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
AddPopup, id = DiskFullMessage
ERROR reading PES (fd=45) - Value too large for defined data type
poll: unhandled POLLERR/HUP/NVAL for fd 45(8)
[EPGC] start caching events(1359593990)
[EPGC] abort non avail schedule reading
[EPGC] abort non avail schedule other reading
[EPGC] abort non avail netmed schedule reading
[EPGC] abort non avail netmed schedule other reading
[EPGC] abort non avail FreeSat schedule_other reading
[EPGC] abort non avail viasat reading
[EPGC] nownext finished(1359593997)
[EPGC] stop caching events(1359593997)
[EPGC] next update in 60 min
[TIMER] activating state 3
[TIMER] stop recording
stop recording!
(was not recording)
[TIMER] record event 5
[TIMER] record event 1
[eDVBCAService] free slot 1 demux 1 for service 1:0:19:584:E:46:E080000:0:0:0:
[eDVBCAService] free service 1:0:19:584:E:46:E080000:0:0:0:
start release channel timer
[Trashcan] probing folders
[Trashcan] found following trashcan's: ['/media/hdd/movie/.Trash']
[Trashcan] looking in trashcan /media/hdd/movie/.Trash
[Trashcan] /media/hdd/movie/.Trash: Size: 6376194765
[Trashcan] /media/hdd/movie/.Trash: Size now: 6376194765
job Components.Task.Job name=Cleaning Trashes #tasks=1 completed with [] in None
release cached channel (timer timeout)
[eDVBLocalTimerHandler] remove channel 0x15b1860
[eEPGCache] remove channel 0x15b1860
stop release channel timer
close frontend 0
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 36(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None
[AutoTimer] Auto Poll
[AutoTimer] Auto Poll Started
[AutoTimer] No changes in configuration, won't parse
[EPGC] event 258a not found in epgcache
[EPGC] event 2703 not found in epgcache

rassi
27-02-13, 13:42
And Disk full issue is still there. I did use AAF about month and then switched back to ViX, but this issue is there somewhere on ViX..
Haven´t seen this problem with any other image, only with ViX. I hope that someone has now lucky guess what´s wrong.. :)


Is there a way to remove disk space check by myself?
I never run out of disk, so i can easily remove this check.




job Components.Task.Job name=AutoTimer #tasks=0 completed with [] in None
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 78(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None
[NTP]: Updating
child has terminated
pipes closed
[NTP]: setting E2 time: 1361941606.9
poll: unhandled POLLERR/HUP/NVAL for fd 78(16)
[EPGC] start caching events(1361941632)
[EPGC] abort non avail schedule reading
[EPGC] abort non avail schedule other reading
[EPGC] nownext finished(1361941639)
[EPGC] stop caching events(1361941639)
[EPGC] next update in 60 min
pcr of eit change: 1384e9e64
[eDVBServiceRecord] now running: PIKKU KAKKONEN (32 seconds)
[TIMER] record event 9
pcr of eit change: 13874d9e4
[eDVBServiceRecord] now running: Unelmajuttu (369 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 78(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 78(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None
pcr of eit change: 13a9f0e74
[eDVBServiceRecord] now running: Kasper ja Liisa (S) (668 seconds)
[TIMER] record event 9
[eMPEGStreamInformationWriter] aio_return returned failure: Interrupted system call
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eFilePushThreadRecorder] WRITE ERROR, aborting thread
[eDVBServiceRecord] record write error
stop recording!
[eDVBRecordFileThread] waiting for aio to complete
[eDVBRecordFileThread] buffer usage histogram (20 buffers of 188 kB)
0: 98
1: 4464
2: 126
3: 1
4: 1
5: 1
6: 1
[eFilePushThreadRecorder] stopping thread.
[eFilePushThreadRecorder] THREAD STOP
thread joined 0
main thread is non-idle! display spinner!
loading streaminfo for /media/hdd/movie/20130227 0649 - Yle TV2 - PIKKU KAKKONEN.ts
fixed up 1325b4124 to 78bd60 (offset 0)
fixed up 1328dd8b4 to ab54f0 (offset 0)
fixed up 1338e98d4 to 1ac1510 (offset 0)
fixed up 1369889f4 to 4b60630 (offset 0)
fixed up 1384e9e64 to 66c1aa0 (offset 0)
fixed up 13874d9e4 to 6925620 (offset 0)
fixed up 13a9f0e74 to 8bc8ab0 (offset 0)
[TIMER] record event 5
[TIMER] record event 8
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
AddPopup, id = DiskFullMessage
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 75(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None
[eDVBServiceRecord] now running: PIKKU KAKKONEN (167 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 75(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None
[eDVBServiceRecord] now running: Monot mäkeen: Iso mäki ja taidot testissä (395 seconds)
[TIMER] record event 9

rassi
28-02-13, 09:43
Earlier post was with SD recording and HD recording from this morning



[eFilePushThreadRecorder] stopping thread.
[eFilePushThreadRecorder] THREAD STOP
thread joined 0
[eMPEGStreamInformationWriter] Waiting for I/O to complete
main thread is non-idle! display spinner!
loading streaminfo for /media/hdd/movie/20130228 0500 - C More Series HD - XIII - The Series.ts
fixed up b85ff4d5 to 8d36b (offset 0)
[TIMER] record event 5
[TIMER] record event 8
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
AddPopup, id = DiskFullMessage
ERROR reading PES (fd=52) - Value too large for defined data type
poll: unhandled POLLERR/HUP/NVAL for fd 52(8)
[NTP]: Updating
child has terminated
pipes closed
[NTP]: setting E2 time: 1362021222.38
poll: unhandled POLLERR/HUP/NVAL for fd 80(16)
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 80(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen

rassi
04-03-13, 10:19
No one has any new tip for this issue?
Two recordings from this morning, SD and HD, both recordings has started normal and recorded approx 18minutes before "Disk full" issue.




add 146 464 144 44
disp width 720, disp height 576
add 146 508 144 48
disp width 720, disp height 576
[eDVBServiceRecord] record write error
stop recording!
[eMPEGStreamInformationWriter] aio_return returned failure: Success
main thread is non-idle! display spinner!
loading streaminfo for /media/hdd/movie/20130304 0600 - MTV3 Juniori - Unelmatarha (S) - In the Night Garden.ts
fixed up 1695079b7 to ba507 (offset 0)
[TIMER] record event 5
[TIMER] record event 8
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
AddPopup, id = DiskFullMessage
[SoftcamManager] oscam is responding like it should
[eDVBServiceRecord] record write error
stop recording!
[eMPEGStreamInformationWriter] aio_return returned failure: Success
loading streaminfo for /media/hdd/movie/20130304 0600 - C More Series HD - Banshee.ts
fixed up 9a10aad to 8c404 (offset 0)
[TIMER] record event 5
[TIMER] record event 8
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
(found in current notifications)
AddPopup, id = DiskFullMessage
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 86(16)
setPage
setPage
add 146 508 350 48

Trial
04-03-13, 10:32
Hi,
can you log into your box with telnet and post the results of?

mount

df

ciao

Rob van der Does
04-03-13, 14:31
Is there a way to remove disk space check by myself?
I never run out of disk, so i can easily remove this check.
The check on diskspace has been introduced for a very good reason: to prevent recordings from going to flash and thus crashing your box (with the subsequent need to flash).

If the message pops up I would suggest to do what Trial asked: see what's wrong with a mount.

Sicilian
04-03-13, 16:16
Have you intalled any plugins? If so which? Do you have TSPanel installed, I've found that it casues mount/hdd issues with ViX.

rassi
04-03-13, 17:29
Hi,
can you log into your box with telnet and post the results of?

mount

df

ciao

Sure thing.



root@solo2:~# mount
rootfs on / type rootfs (rw)
ubi0:rootfs on / type ubifs (rw,sync,relatime)
devtmpfs on /dev type devtmpfs (rw,relatime,size=285772k,nr_inodes=71443,mode=755 )
proc on /proc type proc (rw,relatime)
sysfs on /sys type sysfs (rw,relatime)
tmpfs on /media type tmpfs (rw,relatime,size=64k)
/dev/sda1 on /media/usb type vfat (rw,relatime,fmask=0000,dmask=0000,allow_utime=002 2,codepage=cp437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro)
tmpfs on /var/volatile type tmpfs (rw,relatime)
usbfs on /proc/bus/usb type usbfs (rw,relatime)
devpts on /dev/pts type devpts (rw,relatime,gid=5,mode=620)
192.168.1.6:/mnt/video/dream/ on /media/hdd type nfs (rw,relatime,vers=3,rsize=32768,wsize=32768,namlen =255,soft,nolock,proto=tcp,port=65535,timeo=14,ret rans=3,sec=sys,local_lock=all,addr=192.168.1.6)




root@solo2:~# df -h
Filesystem Size Used Available Use% Mounted on
ubi0:rootfs 196.8M 76.9M 119.9M 39% /
devtmpfs 279.1M 4.0K 279.1M 0% /dev
tmpfs 64.0K 0 64.0K 0% /media
/dev/sda1 958.7M 463.3M 495.3M 48% /media/usb
tmpfs 279.2M 160.0K 279.0M 0% /var/volatile
192.168.1.6:/mnt/video/dream/
6.8T 6.5T 370.0G 95% /media/hdd

rassi
04-03-13, 17:37
The check on diskspace has been introduced for a very good reason: to prevent recordings from going to flash and thus crashing your box (with the subsequent need to flash).

Yes of course it is good thing, but there´s always but :)
I just want remove this check to test, if it will fix my issues as my NAS is "bullet proof" and this is only image which
cause this issue. No problems with BH or AAF and i really, REALLY want use ViX :)




If the message pops up I would suggest to do what Trial asked: see what's wrong with a mount.

Here is my older thread, "disk full" message with DUO. Tested almost every mount setup etc, without an success.

http://www.world-of-satellite.com/showthread.php?21029-quot-WRITE-ERROR-on-recording-disk-full-quot-messages-now-and-then



Have you intalled any plugins? If so which? Do you have TSPanel installed, I've found that it casues mount/hdd issues with ViX.

Nope, ViX is clean as it includes all what i need straight away :)
oh, Sundtek Control Center is installed for two Sundtek USB DVB-C/DVB-T sticks and Oscam. Yesterday installed HbbTV plugin to test, theres all.

Sicilian
04-03-13, 17:48
Enable system logs, if the issue happens again please post the log.

rassi
04-03-13, 18:07
Enable system logs, if the issue happens again please post the log.

Is there some other log than this?

Create more detailed crash log and Enable debug log has set to Yes.




[eDVBServiceRecord] record write error
stop recording!
[eMPEGStreamInformationWriter] aio_return returned failure: Success
main thread is non-idle! display spinner!
loading streaminfo for /media/hdd/movie/20130304 0600 - MTV3 Juniori - Unelmatarha (S) - In the Night Garden.ts
fixed up 1695079b7 to ba507 (offset 0)
[TIMER] record event 5
[TIMER] record event 8
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
AddPopup, id = DiskFullMessage
[SoftcamManager] oscam is responding like it should
[eDVBServiceRecord] record write error
stop recording!
[eMPEGStreamInformationWriter] aio_return returned failure: Success
loading streaminfo for /media/hdd/movie/20130304 0600 - C More Series HD - Banshee.ts
fixed up 9a10aad to 8c404 (offset 0)
[TIMER] record event 5
[TIMER] record event 8
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
(found in current notifications)
AddPopup, id = DiskFullMessage
child has terminated
pipes closed


And small addition and clarification with example.

I have set recording to start 06:00-06:30 and 06:30-07:00, first recording stops like after 18 minutes (06:18) with Disk Full message,
but recording at 06:30 can work without an issue till end.

rassi
07-03-13, 15:05
And again. Is there way to increase "Write buffers"?
this one does not be the same than rsize/wsize, i pressume?
Using now build 623.



[eFilePushThreadRecorder] Warning: All write buffers busy
[eDVBRecordFileThread] Waiting for I/O to complete
[eFilePushThreadRecorder] Warning: All write buffers busy
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eFilePushThreadRecorder] WRITE ERROR, aborting thread
[eDVBRecordFileThread] waiting for aio to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBServiceRecord] record write error




Full log:



[TIMER] record event 9
RECORD service event 5
start recording...
RECORD: have 1 video stream(s) (05ed), and 1 audio stream(s) (0c56) (19e8), and the pcr pid is 05ed, and the text pid is 1791
[TIMER] record event 4
[TIMER] record event 6
[eDVBCIInterfaces] gotPMT
[eFilePushThreadRecorder] Warning: All write buffers busy
[eDVBRecordFileThread] Waiting for I/O to complete
[eFilePushThreadRecorder] Warning: All write buffers busy
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eFilePushThreadRecorder] WRITE ERROR, aborting thread
[eDVBRecordFileThread] waiting for aio to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBServiceRecord] record write error
stop recording!
[eFilePushThreadRecorder] stopping thread.
[eDVBRecordFileThread] aio_suspend failed: Interrupted system call
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eFilePushThreadRecorder] WRITE ERROR, aborting thread
[eDVBRecordFileThread] waiting for aio to complete
[eDVBRecordFileThread] Waiting for I/O to complete
main thread is non-idle! display spinner!
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eDVBRecordFileThread] buffer usage histogram (20 buffers of 188 kB)
0: 29
1: 7407
2: 7096
3: 1
4: 1
5: 1
6: 1
7: 1
8: 1
9: 1
10: 1
11: 1
[eDVBRecordFileThread] Waiting for I/O to complete
12: 1
13: 1
14: 1
15: 1
16: 1
17: 1
18: 1
19: 1
20: 1
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eDVBRecordFileThread] buffer usage histogram (20 buffers of 188 kB)
0: 19
1: 3683
2: 3536
3: 1
4: 1
5: 1
6: 1
7: 1
8: 1
9: 1
10: 1
11: 1
12: 1
13: 1
14: 1
15: 1
16: 1
17: 1
18: 1
19: 1
20: 1
[eFilePushThreadRecorder] THREAD STOP
[eFilePushThreadRecorder] THREAD STOP
thread joined 0
[eMPEGStreamInformationWriter] aio_return returned failure: Success
loading streaminfo for /media/hdd/movie/20130307 0800 - C More Series HD - XIII - The Series_001.ts
[TIMER] record event 5
[TIMER] record event 8
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
AddPopup, id = DiskFullMessage
[eDVBServiceRecord] record write error
stop recording!
loading streaminfo for /media/hdd/movie/20130307 0649 - Yle TV2 - PIKKU KAKKONEN.ts
fixed up 599485 to 376 (offset 0)
fixed up 150122c to 376 (offset 0)
fixed up 3402f87 to 376 (offset 0)
fixed up 483e82d to 376 (offset 0)
fixed up 6732b77 to 376 (offset 0)
fixed up 9aadb66 to 376 (offset 0)
fixed up ed4b192 to 376 (offset 0)
fixed up 12b14610 to 376 (offset 0)
fixed up 154684d5 to 376 (offset 0)
fixed up 1cff31c3 to 376 (offset 0)
fixed up 23c1cded to 376 (offset 0)
fixed up 26f9408d to 376 (offset 0)
[TIMER] record event 5
[TIMER] record event 8
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
(found in current notifications)
AddPopup, id = DiskFullMessage
[AutoTimer] Auto Poll
[AutoTimer] Auto Poll Started
[AutoTimer] No changes in configuration, won't parse
[EPGC] event 258a not found in epgcache
[EPGC] event 2c49 not found in epgcache
[EPGC] event ccf0 not found in epgcache
[EPGC] event dd58 not found in epgcache
[EPGC] event e910 not found in epgcache
[EPGC] event 2433 not found in epgcache
[EPGC] event fe28 not found in epgcache
[EPGC] event f9b0 not found in epgcache
[EPGC] event ff31 not found in epgcache
[EPGC] event ff32 not found in epgcache
[EPGC] event ff33 not found in epgcache
[EPGC] event 2c29 not found in epgcache
[EPGC] event 2703 not found in epgcache
[EPGC] event ff53 not found in epgcache
[EPGC] event 28bc not found in epgcache
[EPGC] event 28bf not found in epgcache
[EPGC] event 1cb2 not found in epgcache
[EPGC] event 0000 not found in epgcache
[EPGC] event 17f3 not found in epgcache
[EPGC] event 28e0 not found in epgcache
[EPGC] event 193e not found in epgcache
[EPGC] event 247a not found in epgcache
[EPGC] event 6d4c not found in epgcache
[EPGC] event 2699 not found in epgcache
[EPGC] event d4a0 not found in epgcache
[EPGC] event 7dec not found in epgcache
[EPGC] event 150e not found in epgcache
[EPGC] event 0000 not found in epgcache
job Components.Task.Job name=AutoTimer #tasks=0 completed with [] in None
[SoftcamManager] oscam already running
[SoftcamManager] Checking if oscam is frozen
child has terminated
pipes closed
poll: unhandled POLLERR/HUP/NVAL for fd 62(16)
[SoftcamManager] oscam is responding like it should
job Components.Task.Job name=SoftcamCheck #tasks=1 completed with [] in None
[NTP]: Updating
child has terminated

rassi
07-03-13, 15:30
https://github.com/OpenViX/enigma2/blob/master/lib/dvb/demux.cpp



Could this one help?
If we increase buffers? There´s now 20 of them for 512MB system, but Solo2 has 1GB so if there´s added 40 buffers for the 1GB systems?-)




//#define SHOW_WRITE_TIME
static int determineBufferCount()
{
struct sysinfo si;
if (sysinfo(&si) != 0)
{
return 6; // Default to small
}
unsigned int megabytes = si.totalram >> 20;
int result;
if (megabytes > 200)
result = 20; // 512MB systems: Use 4MB IO buffers (et9x00, vuultimo, ...)
else if (megabytes > 100)
result = 16; // 256MB systems: Use 3MB demux buffers (dm8000, et5x00, vuduo)
else
result = 8; // Smaller boxes: Use 1.5MB buffer (dm7025)
return result;
}

static int recordingBufferCount = determineBufferCount();

rassi
28-03-13, 21:37
Changed NFS mount UDP and no issues in 4 weeks :eek:
It didn´t help with the Duo but Solo2 working great now.

ndesorden
20-04-13, 01:15
Hi rassi! Well, you're lucky. I was tired with this issue so i plugged a hdd usb and no problems.
i had new tries recording over nfs this week with differents nfs servers...but.....nothing...

vu solo2 latest build (649)



ok ... now we start!!
RECORD service event 5
[eDVBCAService] add demux 0 to slot 1 service 1:0:19:7852:41A:1:C00000:0:0:0:
RECORD service event 6
tuned..
query epg event id 8025
found event.. store to disc
[TIMER] prepare ok, waiting for begin
[Trashcan] Recording in progress 1
[TIMER] activating state 2
[TIMER] start recording
AddPopup, id = None
Recording to /media/net/NaujEDisk/20130413 1622 - C+ LIGA HD - VALLADOLID - GETAFE.ts...
start recording...
RECORD: have 1 video stream(s) (00a2), and 1 audio stream(s) (005c), and the pcr pid is 00a2, and the text pid is ffffffff
ADD PID: 0000
ADD PID: 005c
ADD PID: 00a2
setIoPrio realtime level 7 ok
[eFilePushThreadRecorder] THREAD START
PATready
use pmtpid 0402 for service_id 7852
RECORD service event 5
start recording...
RECORD: have 1 video stream(s) (00a2), and 4 audio stream(s) (005c, 005d, 005e, 005f), and the pcr pid is 00a2, and the text pid is ffffffff
ADD PID: 005d
ADD PID: 005e
ADD PID: 005f
ADD PID: 0402
[eDVBCIInterfaces] gotPMT
[eDVBCAService] don't build/send the same CA PMT twice
Timeout!
pcr of eit change: 115ee3f19
[eDVBServiceRecord] now running: VALLADOLID - GETAFE (7200 seconds)
[EPGC] abort non avail schedule reading
[EPGC] abort non avail schedule other reading
[EPGC] abort non avail netmed schedule reading
[EPGC] abort non avail netmed schedule other reading
[EPGC] abort non avail FreeSat schedule_other reading
[EPGC] abort non avail viasat reading
[EPGC] nownext finished(1365862963)
[EPGC] stop caching events(1365862963)
[EPGC] next update in 60 min
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eFilePushThreadRecorder] WRITE ERROR, aborting thread
[eDVBRecordFileThread] waiting for aio to complete
[eDVBRecordFileThread] buffer usage histogram (20 buffers of 188 kB)
1: 54
2: 30
[eDVBServiceRecord] record write error
stop recording!
[eFilePushThreadRecorder] THREAD STOP
loading streaminfo for /media/net/NaujEDisk/20130413 1622 - C+ LIGA HD - VALLADOLID - GETAFE.ts
fixed up 115ee3f19 to 36591 (offset 0)
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
AddPopup, id = DiskFullMessage
action -> MsgBoxActions ok
action -> InfobarMenuActions mainMenu
loading mainmenu XML...




192.168.1.5:/shares/internal/Multimedia/ on /media/net/BackupMultimedia type nfs (rw,relatime,vers=3,rsize=8192,wsize=8192,namlen=2 55,soft,nolock,proto=udp,port=65535,timeo=90,retra ns=3,sec=sys,local_lock=all,addr=192.168.1.5)


fstab


192.168.1.5:/shares/internal/Multimedia /media/net/BackupMultimedia nfs _netdev,rw,nolock,proto=udp,nfsvers=3,rsize=32768, wsize=32768,timeo=90,fg,soft,intr 0 0


And rsize/wsize from fstab are ignored...

2am...and i'm tired again....with nfs recordings ;)

ndesorden
22-05-13, 17:23
The fuc**ng switch

I had a switch (router as switch) between vu & nas. VU connected to switch and nas conected to router.
If i connect vu straight to router no problem with nfs recordings.

Sorry, but vix rules with nfs :).........................and maybe not with switch? :p

I think its a question of lost packets... but i'm not sure

Regards!

andyblac
23-05-13, 16:08
The fuc**ng switch

I had a switch (router as switch) between vu & nas. VU connected to switch and nas conected to router.
If i connect vu straight to router no problem with nfs recordings.

Sorry, but vix rules with nfs :).........................and maybe not with switch? :p

I think its a question of lost packets... but i'm not sure

Regards!

time to upgrade your router ?, is it gigabit switch or 10/100 solo2 supports gigabit.


also can i suggest you delete your mounts and recreate them, as the code was tweaked some time ago, and no-longer uses 'rsize=32768' etc.

ndesorden
27-05-13, 10:40
Well, i'm confused :confused:

A friend of mine give a switch for testing and the same error on recordings. After 5-15 min crash.
And again, if I plug solo2 directly to router no problem (recording test about 90minutes)

It is not a packet lost:

Log from just now:


[eDVBServiceRecord] now running: MOONRISE KINGDOM (HD) (5700 seconds)
[eDVBRecordFileThread] wait: aio_return returned failure: Interrupted system call
[eFilePushThreadRecorder] WRITE ERROR, aborting thread
[eDVBRecordFileThread] waiting for aio to complete
[eDVBRecordFileThread] Waiting for I/O to complete
[eDVBServiceRecord] record write error
stop recording!
[eDVBRecordFileThread] buffer usage histogram (20 buffers of 188 kB)
0: 5
1: 115
2: 88
[eFilePushThreadRecorder] THREAD STOP
loading streaminfo for /media/net/BackupMultimedia/Recordings/20130527 1123 - CANAL+1 HD - MOONRISE KINGDOM (HD).ts
fixed up cb41ce52 to 36cf8 (offset 0)
WRITE ERROR on recording, disk full?
RemovePopup, id = DiskFullMessage
AddPopup, id = DiskFullMessage


At the same time from vu i had a ping to hdd



root@vusolo2:~# ping 192.168.1.5
PING 192.168.1.5 (192.168.1.5): 56 data bytes
64 bytes from 192.168.1.5: seq=0 ttl=64 time=0.425 ms
64 bytes from 192.168.1.5: seq=1 ttl=64 time=0.320 ms
...
....
64 bytes from 192.168.1.5: seq=54 ttl=64 time=0.516 ms
64 bytes from 192.168.1.5: seq=55 ttl=64 time=0.399 ms
^C
--- 192.168.1.5 ping statistics ---
56 packets transmitted, 56 packets received, 0% packet loss
round-trip min/avg/max = 0.294/0.401/1.151 ms



Ok, my question are:

¿what means "aio_return returned failure: Interrupted system call"?
¿Can I config debug level output to have more info?

Thanks!

PS My router is gigabit....but switchs are not. But...10/100MB is enought for recording...i think
PS1 Yes, another test is buy a gigabit switch...but i'd like have more info before expend money ;)
PS2 And yes, another test is flash another image like blackhole...but I hate "messing" my vu :p

Trial
27-05-13, 10:48
Hi ndesorden,
connect with telnet to the box and type in the command ifconfig. See if there are dropped packets with/without switch. On another board there are some people complaining about dropped packets and CRC errors when they use a switch.

I never had any problems.

ciao

andyblac
27-05-13, 11:21
Hi ndesorden,
connect with telnet to the box and type in the command ifconfig. See if there are dropped packets with/without switch. On another board there are some people complaining about dropped packets and CRC errors when they use a switch.

I never had any problems.

ciao

i have my whole house networked, but using gigabit switches (3 of them) not had any issues, since i tweaked he network code a month or so back.

Trial
27-05-13, 11:42
Hi Andy,
same for me. I also have 3GB switches working and not a single problem with the network. Most of the complaining guys also wanted to show off with their managed switches. Just for a test I also ordered a managed switch for me.

ciao

andyblac
27-05-13, 16:40
Hi Andy,
same for me. I also have 3GB switches working and not a single problem with the network. Most of the complaining guys also wanted to show off with their managed switches. Just for a test I also ordered a managed switch for me.

ciao

just be careful to allow enough bandwidth for the necessary ports.