WL-HDD

[tuxianer]

Hallo,

ist es noch möglich mit einer WL-HDD ARD aufzunehmen? Wenn ja, welches Image auf der DBox nutzt ihr, und welche Firmware auf der WL-HDD und wo wleche Einstellungen.

 

Danke

[merkwuerden]

Sollte via NFS-Freigabe nach wie vor möglich sein, wenn das Netzwerk ordentlich funktioniert.

 

Ich hab auf der WL-HDD die letzte Oleg-Firmware (1.9.2.7-9) und als Image das Keywelt September 2009. Schreibdurchsatz 8677 mit Worschters Netztest, das reicht auch noch, wenn man alle Tonspuren mit aufnehmen will. Unter Windows Server 2003 mit SFU komme ich auch nur ca. 200 höher, das reißt es nicht raus.

 

Andersrum zum Abspielen sieht's bei mir aber nicht ganz so rosig aus, da komme ich lediglich auf 7876 laut Worschters Test, das könnte dann schon arg knapp werden und zum einen oder anderen Ruckler führen.

 

Alles andere außer NFS kannst Du allerdings gleich vergessen, und Du solltest auch zwischen WL-HDD und Box einen guten Switch einsetzen und die WL-HDD fest auf 100 Half Duplex setzen. rsize/wsize in den Mountoptionen der Box gehört auf 32768.

[tuxianer]

Hallo,

also mein Setup ist fast wie deines. auch die neuste Oleg. Allerdings habe ich noch ein Keywelt Image von 2008. Mein Switch ist allerdings nicht der beste denke ich. ARD bricht immer nach 2Mb ab. Hier meine Werte:

32768, 32768
8192+0 records in
8192+0 records out
real	1m 1.36s
user	0m 0.29s
sys	0m 19.10s
8258
8192+0 records in
8192+0 records out
real	1m 7.07s
user	0m 0.27s
sys	0m 12.60s
7641
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=32768,wsize=32768,soft,udp,nolock,addr=192.168.2.33)

[merkwuerden]

Nicht gerade berauschend Deine Werte. Sollte aber zumindest momentan zum Aufnehmen von ARD reichen, derzeitige Bitrate laut techn. Info der Box zwischen 6000 und 7300.

 

ARD bricht immer nach 2Mb ab.

Da ist dann eher was anderes faul, irgendwie Komplettstau im Netzwerk zu Beginn der Aufnahme, so dass erst mal gar keine Daten abgenommen werden. Vermute ich mal so.

 

Überprüfe bitte die Direktaufnahme-Einstellungen. Dort sollten als Ringpuffer 30 stehen, entspricht 2MB, bei mir zumindest reicht das. Kannst auch mal höher gehen, aber besser nicht über 60, sonst geht der Box ganz fix der RAM aus, was dann auch zum Fehlschlagen von Aufnahmen bis hin zum Totalabsturz von Neutrino führt. 30 entspricht wie geschrieben 2MB, 60 sind 4MB, darüber 8MB (so viel Luft hat die Box nach einiger Laufzeit mit Sicherheit nicht mehr). Die Werte dazwischen bringen übrigens nichts.

 

Des weiteren sollten unbedingt die beiden "Synchrones Schreiben" Optionen deaktiviert sein. Sind die aktiv, bremst das nach meiner Erfahrung merklich.

[tuxianer]

So ich habe mal das neuste Image drauf gemacht hier die neuen Werte:

32768, 32768
umount: can't umount /mnt/filme: Invalid argument
8192+0 records in
8192+0 records out
real	1m 0.64s
user	0m 0.31s
sys	0m 12.51s
8393
8192+0 records in
8192+0 records out
real	1m 4.80s
user	0m 0.22s
sys	0m 8.60s
7876
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=32768,wsize=32768,soft,udp,nolock,addr=192.168.2.33)

Mit dem Ringpuffer probiere ich mal ein wenig rum.

 

Edit.

Ard bricht immer nocg bei ca. 3,9MB ab. Puffer ist auf 2MB synchrones Schreiben ist aus.

[merkwuerden]

An Deinem Netzwerkdurchsatz hat sich auch mit dem aktuellen Image nichts geändert, obwohl Dir das Ergebnis das glauben machen will.

Liegt an der für den Test verwendeten Filegröße von 64MB, da sind Schwankungen bis zu ca. 300 von einem Test zum anderen durchaus drin. Erst ab mindestens 192MB, besser 256MB erhältst Du nachvollziehbare und wiederholbare Resultate.

 

Tut aber nicht viel zur Sache, irgendwas verursacht da in Deinem Netzwerk wohl einen Verkehrsstau. Bei 4MB darf die Aufnahme nicht abbrechen, wenn das Netzwerk die Daten direkt und ohne Verzögerung abnimmt. Derzeitige Datenrate von ARD bei der Sportschau im Bereich 7000 bis 7300 (alle heilige Zeit mal eine kurze Spitze bis 7800), Schnitt ist langfristig 7200. Das ist keine Herausforderung, da dürfte der Ringpuffer der Box bei einer Abnahmefähigkeit im Netz von ca. 8200 selbst mit allen Tonspuren praktisch überhaupt nichts zu puffern bekommen, überquellen ("Daten konnten nicht schnell genug geschrieben werden") erst recht nicht, schon gar nicht nach 4MB. Wenn das so ist, bedeutet das knallhart, dass am Anfang überhaupt nicht (bzw. viel zu spät) Daten abgenommen werden.

 

Beobachte mal beim NFS-Geschwindigkeitstest, ob der sofort startet oder erst irgendwelche Gedächtnissekunden hat, bevor im Netzwerk was passiert. Siehst Du an den Kontroll-LEDs des Switches.

[tuxianer]

Also alleine kann ich leider nicht gucken, wenns los blinkt. Aber ich habe mir jemanden zur Hilfe geholt und er rief unmittelbar, als 32768, 32768 erschien. Also viel Verzögerung war da nicht.

[merkwuerden]

Dann hilft nur ein Bootlog auf der dBox mitlaufen zu lassen. Und bei der WL-HDD auch mal ins Logfile sehen, ob da eventuell irgendwas hakt, speziell beim Aufnahmestart. Müsste in (nach meiner Installation gegangen) /tmp/syslog.log (Symlink, die Datei selber liegt bei mir unter /opt/var/log/syslog.log) zu finden sein, wenn ich jetzt nicht wirr bin. Sonst musst Du das Ding mal suchen.

Weiter hätte ich jetzt erst mal auch keine Idee mehr.

[tuxianer]

Also hier mal das von der Wl-HDD...ich seh nichts verdächtiges:

 

Nov  7 16:58:15 statd[76]: Version 1.0.7 Starting                                                                                   
Nov  7 16:58:15 statd[76]: statd running as root. chown /var/lib/nfs/sm to choose different user                                    
Nov  7 16:58:18 kernel: kjournald starting.  Commit interval 5 seconds                                                              
Nov  7 16:58:18 kernel: EXT3 FS 2.4-0.9.19, 19 August 2002 on ide0(3,2), internal journal                                           
Nov  7 16:58:18 kernel: EXT3-fs: recovery complete.                                                                                 
Nov  7 16:58:18 kernel: EXT3-fs: mounted filesystem with ordered data mode.                                                         
Nov  7 16:58:27 ntp client: Synchronizing time with time.nist.gov ...                                                               
Nov  7 16:58:29 kernel: kjournald starting.  Commit interval 5 seconds                                                              
Nov  7 16:58:29 kernel: EXT3-fs warning: maximal mount count reached, running e2fsck is recommended                                 
Nov  7 16:58:29 kernel: EXT3 FS 2.4-0.9.19, 19 August 2002 on ide0(3,3), internal journal                                           
Nov  7 16:58:29 kernel: EXT3-fs: recovery complete.                                                                                 
Nov  7 16:58:29 kernel: EXT3-fs: mounted filesystem with ordered data mode.                                                         
Nov  7 16:58:29 USB storage: ext3 fs at /dev/discs/disc0/part3 mounted to /tmp/mnt/disc0_3                                          
Nov  7 16:58:57 kernel: Adding Swap: 506008k swap-space (priority -1)                                                               
Nov  7 17:01:49 syslog: authenticated mount request from 192.168.2.44:1020 for /tmp/mnt/disc0_3 (/tmp/mnt/disc0_3)                  
Nov  7 17:01:49 kernel: nfsd: request from insecure port (c0a8022c:49533)!                                                          
Nov  7 17:02:02 login[244]: root login  on `pts/0'                                                                                  
Nov  7 17:03:11 syslog: authenticated mount request from 192.168.2.37:671 for /tmp/mnt/disc0_3/Filme (/tmp/mnt/disc0_3)             
Nov  7 17:29:28 login[248]: root login  on `pts/0'                                                                                  
Nov  7 18:58:28 ntp client: Synchronizing time with time.nist.gov ...                                                               
Nov  7 19:22:36 syslog: authenticated mount request from 192.168.2.37:805 for /tmp/mnt/disc0_3/Filme (/tmp/mnt/disc0_3)             
Nov  7 19:26:17 syslog: authenticated mount request from 192.168.2.37:827 for /tmp/mnt/disc0_3/Filme (/tmp/mnt/disc0_3)             
Nov  7 20:01:20 login[255]: root login  on `pts/0'         

 

 

Bootlog über Netzwerk kann ich mal machen.

 

Hier der Bootlog:

/var # 20:16:27.946 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
20:16:30.235 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
20:16:33.441 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
20:16:35.877 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
20:16:39.032 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
20:16:41.159 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
20:16:44.397 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
20:16:46.842 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
RECDIR=/mnt/filme - HDD aufwachen!
kill: you need to specify whom to kill
Record channel_id: 44d00016dca epg: 44d00016dca95ba, apids 0x0 mode 1
fsk:0, Genre:16, Dauer: 90
[stream2file]: ringbuffersize 2097152
[stream2file] allocated ringbuffer size: 2097151
[stream2file] filename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.001.ts'
           myfilename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649'
20:16:50.481 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
20:16:54.104 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
PANIC: not enough space in ringbuffer, available 42887, needed 93225
20:17:00.346 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
[stream2file]: pthreads exit code: -1, dir: '/mnt/filme', filename: 'Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649' myfilename: '/mnt/filme'
20:17:02.359 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
RECDIR=/mnt/filme - HDD aufwachen!
kill: you need to specify whom to kill
Record channel_id: 44d00016dca epg: 0, apids 0x0 mode 1
no response from sectionsd
no response from sectionsd
20:17:05.671 [stream2file] INFO: /mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.xml already exists, not overwriting
[stream2file]: ringbuffersize 2097152
[stream2file] allocated ringbuffer size: 2097151
[sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
[stream2file] filename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.001.ts'
           myfilename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649'
[stream2file] /mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.001.ts exists, retrying...
[stream2file] filename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.002.ts'
           myfilename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649'
20:17:09.173 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
20:17:13.163 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
PANIC: not enough space in ringbuffer, available 55471, needed 80641
[sectionsd] DMX::readNbytes read: Value too large for defined data type
[stream2file]: pthreads exit code: -1, dir: '/mnt/filme', filename: 'Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649' myfilename: '/mnt/filme'
20:17:20.067 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
RECDIR=/mnt/filme - HDD aufwachen!
kill: you need to specify whom to kill
Record channel_id: 44d00016dca epg: 0, apids 0x0 mode 1
no response from sectionsd
no response from sectionsd
[stream2file] INFO: /mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.xml already exists, not overwriting
[stream2file]: ringbuffersize 2097152
[stream2file] allocated ringbuffer size: 2097151
[stream2file] filename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.001.ts'
           myfilename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649'
20:17:22.894 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
[stream2file] /mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.001.ts exists, retrying...
[stream2file] filename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.002.ts'
           myfilename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649'
[stream2file] /mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.002.ts exists, retrying...
[stream2file] filename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.003.ts'
           myfilename: '/mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649'
Stop
[timerd] timer_wakeup = false; a.time: 0 now: 1257621445
[timerd] not scheduling shutdown event
record time: 5 
20:17:27.686 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
[mi] saveXml: /mnt/filme/Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.xml
20:17:30.956 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
saveFile:1082 saving TS movieinfo: Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649.xml
20:17:33.609 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[3] filter 0x60 mask 0xf1
PANIC: not enough space in ringbuffer, available 55471, needed 80641
20:17:41.907 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x61 mask 0xf1
[stream2file]: pthreads exit code: 1, dir: '/mnt/filme', filename: 'Das_Erste_Donna_Leon_-_Die_dunkle_Stunde_der_Serenissima_2009-11-07_201649' myfilename: '/mnt/filme'
kill: you need to specify whom to kill

 

Und hier der Kernel Log:

/var # dmesg
Linux version 2.4.37.6-dbox2 (image@Server) (gcc version 3.4.6) #26 Sa Sep 26 14:37:12 CEST 2009
On node 0 totalpages: 8192
zone(0): 8192 pages.
zone(1): 0 pages.
zone(2): 0 pages.
Kernel command line: root=/dev/mtdblock2 rootfstype=squashfs console=ttyS0,9600 dbox_duplex=0
Decrementer Frequency = 252000000/60
m8xx_wdt: active wdt found (SWTC: 0xFFFF, SWP: 0x1)
m8xx_wdt: keep-alive trigger installed (PITC: 0x1000)
Console: colour dummy device 80x25
Calibrating delay loop... 66.96 BogoMIPS
Memory: 30824k available (1144k kernel code, 340k data, 60k init, 0k highmem)
Dentry cache hash table entries: 4096 (order: 3, 32768 bytes)
Inode cache hash table entries: 2048 (order: 2, 16384 bytes)
Mount cache hash table entries: 512 (order: 0, 4096 bytes)
Buffer cache hash table entries: 1024 (order: 0, 4096 bytes)
Page-cache hash table entries: 8192 (order: 3, 32768 bytes)
POSIX conformance testing by UNIFIX
Linux NET4.0 for Linux 2.4
Based upon Swansea University Computer Society NET3.039
Initializing RT netlink socket
Starting kswapd
devfs: v1.12c (20020818) Richard Gooch (rgooch@atnf.csiro.au)
devfs: boot_options: 0x1
JFFS2 version 2.2. (NAND) (C) 2001-2003 Red Hat, Inc.
squashfs: version 3.0 (2006/03/15) Phillip Lougher
i2c-core.o: i2c core module version 2.6.1 (20010830)
i2c-dev.o: i2c /dev entries driver module version 2.6.1 (20010830)
i2c-core.o: driver i2c-dev dummy driver registered.
CPM UART driver version 0.04
ttyS0 at 0x0280 is on SMC1 using BRG1
ttyS1 at 0x0380 is on SMC2 using BRG2
pty: 256 Unix98 ptys configured
half-duplex mode enabled
eth0: CPM ENET Version 0.2.dbox2 on SCC2, 00:50:9c:42:98:65
loop: loaded (max 8 devices)
D-Box 2 flash driver (size->0x800000 mem->0x10000000)
D-Box 2 flash memory: Found 2 x16 devices at 0x0 in 32-bit bank
Intel/Sharp Extended Query Table at 0x0035
cfi_cmdset_0001: Erase suspend on write enabled
0: offset=0x0,size=0x4000,blocks=8
1: offset=0x20000,size=0x20000,blocks=63
Creating 6 MTD partitions on "D-Box 2 flash memory":
0x00000000-0x00020000 : "BR bootloader"
0x00020000-0x00040000 : "FLFS (U-Boot)"
0x00040000-0x005e0000 : "root (squashfs+lzma)"
0x005e0000-0x00800000 : "var (jffs2)"
0x00020000-0x00800000 : "Flash without bootloader"
0x00000000-0x00800000 : "Complete Flash"
Linux video capture interface: v1.00
mice: PS/2 mouse device common for all mice
NET4: Linux TCP/IP 1.0 for NET4.0
IP Protocols: ICMP, UDP, TCP
IP: routing cache hash table of 512 buckets, 4Kbytes
TCP: Hash tables configured (established 2048 bind 4096)
NET4: Unix domain sockets 1.0/SMP for Linux NET4.0.
VFS: Mounted root (squashfs filesystem) readonly.
Mounted devfs on /dev
Freeing unused kernel memory: 60k init
event: $Id: event.c,v 1.12 2003/09/30 05:45:38 obi Exp $
tuxbox: no IDE-Interface detected
[i2c-8xx]: mpc 8xx i2c init
i2c-dev.o: Registered 'PowerPC 8xx I2C adapter' as minor 0
i2c-core.o: adapter PowerPC 8xx I2C adapter registered as adapter 0.
[i2c-8xx]: adapter: 0
i2c-core.o: driver DBox2 Frontprocessor driver registered.
i2c-core.o: client [DBox2 Frontprocessor client] registered to adapter [PowerPC 8xx I2C adapter](pos. 0).
i2c-core.o: driver i2c audio/video switch driver registered.
i2c-core.o: client [CXA2092] registered to adapter [PowerPC 8xx I2C adapter](pos. 1).
i2c-core.o: driver saa7126 registered.
i2c-core.o: client [saa7126] registered to adapter [PowerPC 8xx I2C adapter](pos. 2).
$Id: cam.c,v 1.30 2004/01/10 16:36:34 alexw Exp $
i2c-core.o: driver DBox2-CAM registered.
i2c-core.o: client [DBox2-CAM] registered to adapter [PowerPC 8xx I2C adapter](pos. 3).
$Id: avia_napi.c,v 1.18 2003/11/24 09:53:01 obi Exp $
DVB: registering new adapter (C-Cube AViA GTX/eNX with AViA 500/600).
i2c-core.o: driver DVB I2C bridge registered.
i2c-core.o: client [DVB I2C bridge] registered to adapter [PowerPC 8xx I2C adapter](pos. 4).
dvb_i2c_bridge: enabled DVB i2c bridge to PowerPC 8xx I2C adapter
$Id: cam_napi.c,v 1.8 2003/09/30 05:45:34 obi Exp $
avia_av: $Id: avia_av_core.c,v 1.100 2009/09/11 05:58:41 rhabarber1848 Exp $
avia_av_core: Starting avia_av_wdt thread.
avia_av: AVIA 600L found.
avia_av_event: $Id: avia_av_event.c,v 1.11 2003/10/26 16:32:51 obi Exp $
avia_av_proc: $Id: avia_av_proc.c,v 1.15 2007/07/29 18:14:12 papst Exp $
avia_gt_core: $Id: avia_gt_core.c,v 1.48 2004/12/20 01:01:22 carjay Exp $
avia_gt_core: autodetecting chip type... GTX
avia_gt_gtx: $Id: avia_gt_gtx.c,v 1.24 2003/09/30 05:45:35 obi Exp $
avia_gt_accel: $Id: avia_gt_accel.c,v 1.19 2003/09/30 05:45:35 obi Exp $
avia_gt_dmx: $Id: avia_gt_dmx.c,v 1.210 2004/06/26 16:08:15 carjay Exp $
avia_gt_core: masking unhandled irq reg 1 bit 11
avia_gt_core: masking unhandled irq reg 1 bit 13
avia_gt_ucode: loaded ucode v0014
avia_gt_ucode: ucode section filters disabled.
avia_gt_dmx: warning, misaligned queue 0 (is 0xFD200, size 65536), aligning...
avia_gt_gv: $Id: avia_gt_gv.c,v 1.39 2004/08/28 16:44:56 carjay Exp $
avia_gt_gv: set_input_size (width=720, height=576)
avia_gt_pcm: $Id: avia_gt_pcm.c,v 1.29 2004/01/29 19:38:20 zwen Exp $
avia_gt_pcm_set_rate(44100)
avia_gt_capture: $Id: avia_gt_capture.c,v 1.32 2003/09/30 05:45:35 obi Exp $
avia_gt_pig: $Id: avia_gt_pig.c,v 1.40 2003/09/30 05:45:35 obi Exp $
avia_gt_vbi: $Id: avia_gt_vbi.c,v 1.26 2003/08/01 17:31:22 obi Exp $
avia_gt_core: Loaded AViA eNX/GTX driver
avia_gt_fb: $Id: avia_gt_fb_core.c,v 1.54 2004/03/17 18:42:18 zwen Exp $
avia_gt_gv: set_input_mode (mode=2)
avia_gt_gv: set_input_size (width=720, height=576)
avia_gt_gv: set_input_mode (mode=2)
avia_gt_gv: set_input_size (width=720, height=576)
avia_gt_gv: set_input_mode (mode=2)
avia_gt_gv: set_input_size (width=720, height=576)
Console: switching to colour frame buffer device 82x32
avia_gt_fb: fb0: AViA eNX/GTX Framebuffer frame buffer device
lcd.o: init lcd driver module
lcd.o: found KS0713/SED153X lcd interface
avia_gt_lirc: $Id: avia_gt_lirc.c,v 1.14 2003/09/30 05:45:35 obi Exp $
avia_gt_ir: $Id: avia_gt_ir.c,v 1.30 2003/09/30 05:45:35 obi Exp $
avia_oss: $Id: avia_gt_oss.c,v 1.28 2009/03/22 12:37:58 rhabarber1848 Exp $
avia_gt_pcm_set_rate(44100)
avia_gt_v4l2: $Id: avia_gt_v4l2.c,v 1.12 2003/09/30 04:54:03 obi Exp $
DVB: registering frontend 0:0 (VES1893)...
avia_av_napi.c: $Id: avia_av_napi.c,v 1.33 2004/03/11 15:30:27 derget Exp $
avia_gt_napi: $Id: avia_gt_napi.c,v 1.203 2005/01/05 05:49:56 carjay Exp $
DBox2 Multicam Driver v1.01 skars & doz21 
avia_gt_gv: set_input_mode (mode=2)
avia_gt_gv: set_input_size (width=720, height=576)
freq=1716000000 ratio=125000 div=35a0 pe=0
avia_gt_core: masking unhandled irq reg 0 bit 6
PES, queue 0 normal.
avia_gt_core: masking unhandled irq reg 0 bit 7
avia_gt_core: masking unhandled irq reg 1 bit 15
SPTS, queue 0 extended.
avia_gt_dmx: queue 3 overflow (count: 1)
avia_gt_dmx: queue 3 overflow (count: 1)
avia_gt_dmx: queue 3 overflow (count: 1)
avia_gt_dmx: queue 0 overflow (count: 1)
avia_gt_dmx: queue 3 overflow (count: 1)
avia_gt_dmx: queue 3 overflow (count: 1)
avia_gt_dmx: queue 3 overflow (count: 1)
avia_gt_dmx: queue 3 overflow (count: 1)
avia_gt_dmx: queue 3 overflow (count: 1)
avia_gt_dmx: queue 3 overflow (count: 1)
avia_gt_dmx: queue 3 overflow (count: 1)
SPTS, queue 0 extended.

[merkwuerden]

Sehe da in Deinem Log auch nix, was auf ein Problem hinweisen könnte.

 

Bootlog über Netzwerk kann ich mal machen.

Mache ein serielles Log. Ich bin mir nicht sicher, ob über telnet und "setconsole" wirklich auch alles ausgegeben wird. Oft hab ich den Verdacht, dass da Ereignisse "unter den Tisch fallen". Zum seriellen Log schaust Du hier: Bootlog erstellen ganz einfach

[tuxianer]

Also ich hab oben den Post noch mal bearbeitet. Seriell kann ich auch mal machen mit Notebook und USB-Seriell Adapter.

[tuxianer]

Ich habe noch mal probiert. Also wenn ich die Direktaufnahme starte dauert es vlt 5s, bis da ordentlich was blinkt. Ist dir im Log was aufgefallen?

[merkwuerden]

Ich habe noch mal probiert. Also wenn ich die Direktaufnahme starte dauert es vlt 5s, bis da ordentlich was blinkt.

Dann dauert das bereits zu lange, und der Ringpuffer in der Box quillt bereits über, weil die Daten erst verzögert begonnen werden abzuholen -> Abbruch.

 

Ist dir im Log was aufgefallen?

Nichts wirklich Kritisches. Allerdings eine Meldung, die da insgesamt 3x auftaucht:

PANIC: not enough space in ringbuffer, available 42887, needed 93225
...
PANIC: not enough space in ringbuffer, available 55471, needed 80641
...
PANIC: not enough space in ringbuffer, available 55471, needed 80641

Hier hat der Ringpuffer das Problem, dass ihm zu wenig Speicher (freier RAM) zur Verfügung steht. Initialisiert wird er aber ohne Fehlermeldung. Ich nehme mal an, dass diese PANIC-Meldungen den Zeitpunkt des Überquellens des Ringpuffers bedeuten sollen. Mit Angabe des jetzt noch vorhandenen, aber insgesamt benötigten Speichers, womit der Puffer gerade noch nicht überquellen würde.

 

Fakt ist, dass die Daten zu spät von der Box abgeholt werden. Ein korrekter Aufnahmeversuch (ab Start bis Stop) sollte im Log so aussehen:

kill: you need to specify whom to kill
RECDIR=/var/autofs/record - HDD aufwachen!
Record channel_id: 40085000e epg: 40085000e88a6, apids 0x0 mode 1
fsk:0, Genre:144, Dauer: 50
[stream2file]: ringbuffersize 2097152
[stream2file] allocated ringbuffer size: 2097151
[stream2file] filename: '/var/autofs/record/Discovery_Channel_Next_World_-_Das_Leben_von_morgen_2009-11-08_173337.001.ts'
           myfilename: '/var/autofs/record/Discovery_Channel_Next_World_-_Das_Leben_von_morgen_2009-11-08_173337'
Stop
record time: 27 
[timerd] timer_wakeup = false; a.time: 0 now: 1257698044
[timerd] not scheduling shutdown event
[mi] saveXml: /var/autofs/record/Discovery_Channel_Next_World_-_Das_Leben_von_morgen_2009-11-08_173337.xml
saveFile:1082 saving TS movieinfo: Discovery_Channel_Next_World_-_Das_Leben_von_morgen_2009-11-08_173337.xml
[stream2file]: pthreads exit code: 1, dir: '/var/autofs/record', filename: 'Discovery_Channel_Next_World_-_Das_Leben_von_morgen_2009-11-08_173337' myfilename: '/var/autofs/record'
kill: you need to specify whom to kill

Erhöhe mal den Ringpuffer auf 4MB (Wert 60 bei einem älteren Image). Das wären ca. 4 Sekunden bei 8Mbit Datenrate. Versuche dann mal, ob das damit funktioniert. Sonst mal 8MB einstellen (Wert größer 60 bei älteren Images).

 

Vorsicht bei 8MB Ringpuffer! Box unbedingt vor Aufnahmeversuch neu starten und möglichst nicht herumzappen, damit der Speicher frei bleibt und der sectionsd nicht unnötig Daten sammelt (und den RAM damit füllt). Oder mein Plugin sectionsdcontrol per Online-Update nachinstallieren, erst "sectionsd anhalten", dann "alle EPG-Daten im RAM löschen". Und nach der Aufnahme das manuelle Starten des sectionsd nicht vergessen, sonst wartest Du vergeblich auf neue EPG-Daten.

Was möglich ist, zeigt Dir das Plugin. Hinweis: sectionsdcontrol beendet sich nach jedem Vorgang von selbst, das ist normal und von mir bewusst so gewollt.

 

Mir geht's jetzt erst mal drum, den Fehler beim Starten der Aufnahme abzuwürgen. Läuft die Aufnahme erst mal an, dürfte laut Deinen Schreibwerten eigentlich so schnell kein Abbruch auftreten. Schaumer mal.

 

Wenn's partout nicht will, muss ich mal meine WL-HDD wieder einsatzfähig machen. Hab vorhin gerade gesehen, dass ich bei der letzten Optimierung die dBox2-Mountverzeichnisse mit entsorgt hatte.

[tuxianer]

Es ist wie verhext. Mit höherem Puffer bricht es bei 9 bzw. 18 MB ab. Ich kann nur mal nen anderen Switch probieren.

[merkwuerden]

Dann werden die Daten tatsächlich zu langsam abgeholt. Aber wie zum Teufel kommst Du dann beim Geschwindigkeitstest auf Schreibwerte von um die 8200?

Das widerspricht sich irgendwo.

 

So wie das aussieht, werden die Daten tatsächlich zu langsam abgeholt. Nach Deinen Aussagen

Mit höherem Puffer bricht es bei 9 bzw. 18 MB ab.

wäre Deine Netzwerkgeschwindigkeit unter 5Mbit/s (konstante Datenrate von 8Mbit/s der Aufnahme vorausgesetzt). Da müsste irgendwo was gewaltig bremsen.

 

Würde mich mal interessieren, was mit einem anderen Switch raus kommt.

[tuxianer]

Also ich habe mal meine 2 anderen Switches probiert. Da geht es auch nicht. Sind aber auch beide mehr oder weniger NoName.

[merkwuerden]

Was mir gerade noch einfällt: wie hast Du die Mountoptionen auf der WL-HDD in der exports gesetzt? Das dürfte auch noch Einfluss auf Funktion oder Nichtfunktion des Konstruktes haben.

 

/tmp/harddisk/dbox2/record 192.168.178.1/24(rw,async,no_root_squash,no_subtree_check)

So sieht das bei mir aus, die Optionsparameter sind wichtig, speziell das async kann wenn nicht vorhanden Probleme veranstalten. Bei mir lief es eigentlich so. Muss meine WL doch mal wieder herrichten, dann teste ich das selber nochmal.

[tuxianer]

Das hab habe ich:

/tmp/mnt/disc0_3/ *(rw,async,no_root_squash)

sollte eig. ausreichen.

 

 

Was mir gerade noch einfällt: wie hast Du die Mountoptionen auf der WL-HDD in der exports gesetzt? Das dürfte auch noch Einfluss auf Funktion oder Nichtfunktion des Konstruktes haben.

 

/tmp/harddisk/dbox2/record 192.168.178.1/24(rw,async,no_root_squash,no_subtree_check)

So sieht das bei mir aus, die Optionsparameter sind wichtig, speziell das async kann wenn nicht vorhanden Probleme veranstalten. Bei mir lief es eigentlich so. Muss meine WL doch mal wieder herrichten, dann teste ich das selber nochmal.

[merkwuerden]

Das hab habe ich:

/tmp/mnt/disc0_3/ *(rw,async,no_root_squash)

sollte eig. ausreichen.

Ja, reicht aus. Die no_subtree_check Option ist nicht relevant für die Geschwindigkeit.

Allerdings gibst Du das gesamte Netz frei für Zugriff. Willst Du das wirklich? Ich würde nur das Segment des lokalen Netzwerks zulassen.

[tuxianer]

Naja es ist ja noch der Router dazwischen also sollte da keiner rankommen. Aber ist schon klar man muss es ja nicht darauf anlegen. Ich habe mir jetzt erst einmal einen Linksys Switch bestellt. Wenn es damit nicht geht weiß ich auch nicht mehr.

[tuxianer]

so hier mal die Ergebnisse mit dem Linksys Switch:

 

/var # /tmp/netztest 192.168.2.33 /tmp/mnt/disc0_3/Filme /mnt/filme 

4096, 4096
umount: can't umount /mnt/filme: Invalid argument
8192+0 records in
8192+0 records out
real	1m 5.05s
user	0m 0.21s
sys	0m 13.66s
7876
8192+0 records in
8192+0 records out
real	1m 15.50s
user	0m 0.26s
sys	0m 12.63s
6826
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=4096,wsize=4096,soft,udp,nolock,addr=192.168.2.33)

6144, 6144
8192+0 records in
8192+0 records out
real	1m 5.05s
user	0m 0.25s
sys	0m 13.55s
7757
8192+0 records in
8192+0 records out
real	1m 15.98s
user	0m 0.17s
sys	0m 12.95s
6736
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=4096,wsize=4096,soft,udp,nolock,addr=192.168.2.33)

8192, 8192
8192+0 records in
8192+0 records out
real	1m 1.02s
user	0m 0.31s
sys	0m 13.08s
8393
8192+0 records in
8192+0 records out
real	1m 8.38s
user	0m 0.22s
sys	0m 8.45s
7529
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=8192,wsize=8192,soft,udp,nolock,addr=192.168.2.33)

9216, 9216
8192+0 records in
8192+0 records out
real	1m 3.02s
user	0m 0.38s
sys	0m 12.94s
8126
8192+0 records in
8192+0 records out
real	1m 7.73s
user	0m 0.24s
sys	0m 8.67s
7529
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=8192,wsize=8192,soft,udp,nolock,addr=192.168.2.33)

10240, 10240
8192+0 records in
8192+0 records out
real	1m 1.40s
user	0m 0.24s
sys	0m 13.21s
8258
8192+0 records in
8192+0 records out
real	1m 7.99s
user	0m 0.21s
sys	0m 8.56s
7529
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=8192,wsize=8192,soft,udp,nolock,addr=192.168.2.33)

11264, 11264
8192+0 records in
8192+0 records out
real	1m 1.39s
user	0m 0.42s
sys	0m 12.77s
8258
8192+0 records in
8192+0 records out
real	1m 7.88s
user	0m 0.22s
sys	0m 8.68s
7529
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=8192,wsize=8192,soft,udp,nolock,addr=192.168.2.33)

12288, 12288
8192+0 records in
8192+0 records out
real	1m 1.44s
user	0m 0.21s
sys	0m 13.01s
8258
8192+0 records in
8192+0 records out
real	1m 7.25s
user	0m 0.24s
sys	0m 8.43s
7641
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=8192,wsize=8192,soft,udp,nolock,addr=192.168.2.33)

16384, 16384
8192+0 records in
8192+0 records out
real	0m 59.40s
user	0m 0.24s
sys	0m 12.93s
8677
8192+0 records in
8192+0 records out
real	1m 5.31s
user	0m 0.23s
sys	0m 8.61s
7757
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=16384,wsize=16384,soft,udp,nolock,addr=192.168.2.33)

24576, 24576
8192+0 records in
8192+0 records out
real	0m 59.82s
user	0m 0.27s
sys	0m 12.64s
8533
8192+0 records in
8192+0 records out
real	1m 5.03s
user	0m 0.25s
sys	0m 8.74s
7757
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=16384,wsize=16384,soft,udp,nolock,addr=192.168.2.33)

32768, 32768
8192+0 records in
8192+0 records out
real	0m 58.76s
user	0m 0.27s
sys	0m 13.37s
8677
8192+0 records in
8192+0 records out
real	1m 6.89s
user	0m 0.13s
sys	0m 7.69s
7641
192.168.2.33:/tmp/mnt/disc0_3/Filme on /mnt/filme type nfs (rw,v3,rsize=32768,wsize=32768,soft,udp,nolock,addr=192.168.2.33)

 

Was mir aufgefallen ist beim Geschwindigkeitstest blinkt die FD/Col LED...was ja für einen Kollisionserror steht. Aufnahme werde ich gleich mal testen...

[merkwuerden]

Bessere Werte bekomme ich auch nicht zustande (8677 Schreiben und 7876 Lesen). Dürfte das maximal Mögliche mit der WL-HDD sein, mehr gibt die wohl nicht her.

Worschter hatte damals beim Lesen noch etwas mehr (8258), aber den können wir leider nicht mehr fragen, wie er das angestellt hat.

Jedenfalls darf es bei 8677 Schreiben keine Abbrüche mit ARD, ZDF und Konsorten geben, der Durchsatz ist da auf jeden Fall ausreichend.

 

blinkt die FD/Col LED...was ja für einen Kollisionserror steht

Ohne Kollisionsfehler wird es in einem HalfDuplex-Netz kaum abgehen. Hab ich hier ebenfalls, was aber keinen Einfluss auf die Streamerei hat.

Der Linksys-Switch zeigt Kollisionen halt an, andere Switche verschweigen die mangels Anzeigefunzel dazu. Vorhanden dürften die trotzdem sein.

[tuxianer]

naja ARD bricht trotzdem noch ab. Selbst bei SAT 1 habe ich ab und zu noch Abbrüche. Ich habs mal eine Weile laufen lassen. Letztendlich war ein 2,x GB, eine 600 und ein 1,x GB File vorhanden. Ich kann echt nicht nachvollziehen, woher das kommt. Selbst wenn ich die Anderen Geräte vom Switch abziehe, so das nur WLHDD und DBox dranhängen wird es nicht besser.

[merkwuerden]

Werde dann wohl doch mal meine WL-HDD wieder flott machen. Schaumer mal.

[tuxianer]

So also scheinbar hat es wirklich was mit dem Verzögerten Start des Übertragens zu tun. Ich habe heute Abend mal während der Lottoziehung die Direktaufnahme gestartet und hatte jetzt ein 11GB File. Puffer war 4MB. Was ich noch eingestellt habe ist EPG neustarten vor der Aufnahme. Vlt lags daran. Aber das EPG hatte ich eig schon herunter getrimmt. 1500 Events Langzeit 12h.