Immer wieder "Kanal nicht verfügbar"

Boxenweitwurf
-=HSKc=-Robby
Einsteiger
Einsteiger
Beiträge: 143
Registriert: Sonntag 25. Februar 2007, 20:33

Immer wieder "Kanal nicht verfügbar"

Beitrag von -=HSKc=-Robby »

Bei meiner D-Box (Sagem 2x), die in den Sommermonaten in der Gartenhütte ihren Dienst tun soll, kommt es immer wieder mal zu einem "Kanal nicht verfügbar" beim zappen, der sich nur durch einen Neustart beheben läßt.

Wenn dies auftritt, hat man immer folgendes im log:

Code: Alles auswählen

[zapit] tuned frequency does not match request. difference: 171
[dmx.cpp:read:184] read(fd, buf, n): Connection timed out
[zapit] pat parsing failed
[zapit] tuned frequency does not match request. difference: 429
[dmx.cpp:read:184] read(fd, buf, n): Connection timed out
[zapit] pat parsing failed
[zapit] tuned frequency does not match request. difference: 171
[dmx.cpp:read:184] read(fd, buf, n): Connection timed out
[zapit] pat parsing failed
zap failed!
[zapit] tuned frequency does not match request. difference: 429
[dmx.cpp:read:184] read(fd, buf, n): Connection timed out
[zapit] pat parsing failed
zap failed!
Dieses "tuned frequency does not match request" ist mir bisher noch nich untergekommen, leider hab ich mit der board-suche auch noch nichts dazu gefunden. :gruebel: Hat jemand eine Idee, was das Problem sein könnte?
seife
Developer
Beiträge: 4189
Registriert: Sonntag 2. November 2003, 12:36

Re: Immer wieder "Kanal nicht verfügbar"

Beitrag von seife »

Da ist der ENX gecrashed, vermute ich mal.
-=HSKc=-Robby
Einsteiger
Einsteiger
Beiträge: 143
Registriert: Sonntag 25. Februar 2007, 20:33

Re: Immer wieder "Kanal nicht verfügbar"

Beitrag von -=HSKc=-Robby »

Kann man das irgendwie genauer austesten?
Stingray
Erleuchteter
Erleuchteter
Beiträge: 453
Registriert: Mittwoch 27. Juni 2007, 15:25

Re: Immer wieder "Kanal nicht verfügbar"

Beitrag von Stingray »

Naja einfach mal mit Kältespray kühlen und schauen ob der Fehler dann immernoch auftritt.
-=HSKc=-Robby
Einsteiger
Einsteiger
Beiträge: 143
Registriert: Sonntag 25. Februar 2007, 20:33

Re: Immer wieder "Kanal nicht verfügbar"

Beitrag von -=HSKc=-Robby »

Leider passiert es zwar immer wieder mal, aber halt nicht bei jedem umschalten... Und nach einem Neustart funktioniert es dann auch wieder eine Weile.

Stingray hat geschrieben:Naja einfach mal mit Kältespray kühlen und schauen ob der Fehler dann immernoch auftritt.
Wenn ich das richtig verstehe, müsste es also schlimmer werden, je wärmer es ist bzw. je länger die Box läuft, oder? (Kältespray müsste ich erstmal kaufen :oops: )

Kann das ganze eventuell auch am Tuner liegen? Kann ich den irgendwie durchmessen? Finde leider nur angaben über Spannungen bei der Nokia-Box.
Wenn es am enx liegt, macht der vielleicht auch noch andere Fehlermeldungen im Log?
-=HSKc=-Robby
Einsteiger
Einsteiger
Beiträge: 143
Registriert: Sonntag 25. Februar 2007, 20:33

Re: Immer wieder "Kanal nicht verfügbar"

Beitrag von -=HSKc=-Robby »

Hab jetzt erstmal nen kompletten Boot-Log erstellt:

Code: Alles auswählen

debug: DDF: Calibrating delay loop... debug: DDF: 66.76 BogoMIPS
debug: BMon V1.0  mID 03
debug: feID 00    enxID 03
debug: fpID 52     dsID 01-6d.09.89.07.00.00-77
debug: HWrev 01  FPrev 0.23
debug: B/Ex/Fl(MB) 32/00/08
WATCHDOG reset enabled
dbox2:root> debug: 
BOOTP/TFTP bootstrap loader (v0.3)
debug: 
debug: Transmitting BOOTP request via broadcast
debug: Given up BOOTP/TFTP boot
boot net failed

Flash-FS bootstrap loader (v1.5)

Found Flash-FS superblock version 3.1
Found file /root/platform/sagem-dbox2/kernel/os in Flash-FS
debug: Got Block #0008
debug: Got Block #0012
debug: Got Block #0016
debug: Got Block #0020
debug: Got Block #0024
debug: Got Block #0028
debug: Got Block #0032
debug: Got Block #0036
debug: Got Block #0040
debug: Got Block #0044


will verify ELF image, start= 0x800000, size= 172392
verify sig: 262
Branching to 0x40000


U-Boot 1.3.4 (Tuxbox) (Oct  3 2008 - 20:41:44)

CPU:   PPC823ZTnnB2 at 66 MHz: 2 kB I-Cache 1 kB D-Cache
Board: DBOX2, Sagem, BMon V1.0
       Watchdog enabled
I2C:   ready
DRAM:  32 MB
FLASH:  8 MB
Scanning JFFS2 FS:   .  | / .  - \ |  done.

env: found "console"
env: found "baudrate"
env: found "dbox_duplex"
env: found "lcd_inverse"
env: found "lcd_contrast"
FB:    ready
LCD:   ready
In:    serial
Out:   serial
Err:   serial
Net:   SCC ETHERNET

Options:
  1: Console on null
  2: Console on ttyS0
  3: Console on framebuffer
Select option (1-3), other keys to stop autoboot:  1  0 
............................................................... done
Un-Protected 63 sectors
### FS (squashfs) loading 'vmlinuz' to 0x100000
### FS load complete: 687795 bytes loaded to 0x100000
## Booting kernel from Legacy Image at 00100000 ...
   Image Name:   dbox2
   Image Type:   PowerPC Linux Kernel Image (gzip compressed)
   Data Size:    687731 Bytes = 671.6 kB
   Load Address: 00000000
   Entry Point:  00000000
   Verifying Checksum ... OK
   Uncompressing Kernel Image ... OK
Linux version 2.4.37.1-dbox2 (vom Jtg-Team)  (gcc version 3.4.6) #1 Fr 1. Mai 16:32:23 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 = 247500000/60
m8xx_wdt: active wdt found (SWTC: 0xFFFF, SWP: 0x1)
m8xx_wdt: keep-alive trigger installed (PITC: 0x2000)
Console: colour dummy device 80x25
Calibrating delay loop... 65.74 BogoMIPS
Memory: 30792k available (1168k kernel code, 348k 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)
CPM UART driver version 0.04
ttyS0 at 0x0280 is on SMC1 using BRGttyS1 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:30:af:f6
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
Creating 6 MTD partitions on "D-Box 2 flash memory":
0x00000000-0x00020000 : "BR bootloader"
0x00020000-0x00040000 : "FLFS (U-Boot)"
0x00040000-0x006a0000 : "root (SquashFS_LZMA)"
0x006a0000-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

init started: BusyBox v1.14.0 (2009-05-01 16:30:18 CEST)

starting pid 8, tty '': '/etc/init.d/rcS'
Starting the automounter
insmod: /lib/modules/2.4.37.1-dbox2//kernel/fs/cifs/cifs.o: module not found
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-8xx]: adapter: 0
$Id: cam.c,v 1.30 2004/01/10 16:36:34 alexw Exp $
$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).
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.99 2006/01/08 21:36:22 carjay Exp $
avia_av: AVIA500 sptsfix is off
avia_av_core: Starting avia_av_wdt thread.
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... eNX
avia_gt_enx: $Id: avia_gt_enx.c,v 1.21 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_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_av_core: Starting avia_gt_wdt thread.
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
jtginfo $Id: jtginfo.c,v 2.2 2009/02/07 21:51:28 jtgriker Exp $
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 $
ves1x93: Detected ves1993
DVB: registering frontend 0:0 (VES1993)...
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 $
Thu Jan  1 12:00:00 CET 1970
$Id: sectionsd.cpp,v 1.292 2009/05/05 18:57:39 rhabarber1848 Exp $
[sectionsd] Caching max 6000 events
[sectionsd] Caching 14 days
[sectionsd] Caching 6 hours Extended Text
[sectionsd] Events are old 60min after their end time
12:00:02.883 Add Channel Blacklist for channel 0x042100010000, mask 0xffffffff0000
12:00:02.933 Add Channel Blacklist for channel 0x040200010000, mask 0xffffffff0000
12:00:03.019 Add Channel Blacklist for channel 0x045400010000, mask 0xffffffff0000
/var/tuxbox/config/mybouquets.xml: No such file or directory
[timerd] woke up from timer? false!
rdate: cannot connect to remote host (192.53.103.108): Connection refused
[camd] ca system id: 1702
$Id: zapit.cpp,v 1.428 2009/04/28 06:43:03 rhabarber1848 Exp $
[zapit] PMT update enabled
[frontend] uncommitted_switch_mode 0 auto_fec 0
[getservices] dup transponder id 9 onid 85
[getservices] dup transponder id 444 onid 1
[getservices] /var/tuxbox/config/zapit/myservices.xml  found.
[getservices] dup transponder id 421 onid 1
[getservices] dup transponder id 3F8 onid 1
[getservices] dup transponder id 402 onid 1
[getservices] dup transponder id 3 onid 85
[getservices] dup transponder id 11 onid 85
[getservices] dup transponder id 42C onid 1
[getservices] dup transponder id 2 onid 85
[getservices] dup transponder id 9 onid 85
[getservices] dup transponder id 436 onid 1
[getservices] dup transponder id 4 onid 85
[getservices] dup transponder id 43A onid 1
[getservices] dup transponder id 1 onid 85
[getservices] dup transponder id 7 onid 85
[getservices] dup transponder id 443 onid 1
[getservices] dup transponder id 444 onid 1
[getservices] dup transponder id 446 onid 1
[getservices] dup transponder id 44E onid 1
[getservices] dup transponder id 5 onid 85
[getservices] dup transponder id 21 onid 85
[getservices] dup transponder id 454 onid 1
[getservices] dup transponder id 459 onid 1
[getservices]: removing 'Top Girl Sex TV NEU' (service_id=0x315d): FAILED!
[getservices]: removing 'Gratis Hot TV NEU' (service_id=0x315e): FAILED!
[getservices] dup transponder id 45B onid 1
[getservices] dup transponder id 45D onid 1
/tmp/currentservices.xml: No such file or directory
[controld] Boxtype detected: (3, Sagem D-BOX2)
[controld]: ROUTEVIDEO v1 = 0 a1 = 0 v2 = 0 a2 = 0 fblk=1
[CVideo::setVideoFormat] output format: 4:3(LB)
[yhttpd] Webserver nhttpd/3.1.8 (yhttpd_core/1.2.0)
[LCDFONT] initializing core...
[LCDFONT] adding font /share/fonts/micron.ttf...OK (Micron/Regular)
[LCDFONT] adding font /share/fonts/micron_bold.ttf...OK (Micron/Bold)
[LCDFONT] adding font /share/fonts/pakenham.ttf...OK (Pakenham/Regular)
[LCDFONT] Intializing font cache...
[yhttpd] Webserver starting...
neutrino: /lib/libpng12.so.0: no version information available (required by neutrino)
starting /bin/neutrino
[neutrino] frameBuffer Instance created
812k video mem
avia_gt_gv: set_input_mode (mode=2)
avia_gt_gv: set_input_size (width=720, height=576)
[neutrino] enable flash
[neutrino] Software update enabled
[LCDFONT] initializing core...
[LCDFONT] adding font /share/fonts/12.pcf.gz...OK (Fix12/Regular)
[LCDFONT] adding font /share/fonts/14B.pcf.gz...OK (Fix14/Bold)
[LCDFONT] adding font /share/fonts/15B.pcf.gz...OK (Fix15/Bold)
[LCDFONT] Intializing font cache...
[LCDFONT] FTC_Face_Requester (Fix14/Bold)
/dev/input/event1: No such file or directory
[neutrino] menue setup
imageinfo: JTG-Image [IDE] (2.3.2)
loading locales: scandir: No such file or directory
[neutrino] can not use input repeat on fd_rc[0]: 22 (Invalid argument) 
[neutrino] setRepeat: delay=950 period=0 use kernel-repeat: no
[neutrino] received 17 sats
[neutrino] registering as event client
[LCDFONT] FTC_Face_Requester (Fix15/Bold)
[timeThread] - 09.05.2009 16:08:44, tim: Sat May  9 16:08:44 2009
[timeThread] Time set via DVB, going to sleep for 10800 seconds.
[zapit] tuned frequency does not match request. difference: 644
PES, queue 0 normal.
12:00:26.052 dmxSDT: going to sleep...
12:00:26.150 dmxCN: going to sleep...
12:00:26.151 eit_set_update_filter, servicekey = 0x44d00016dca, current version 29
[neutrino] no file /var/tuxbox/config/init.end was found
[neutrino] initialized everything
[controld] VIDEO_EVENT_SIZE_CHANGED 720x576 (4:3 -> 16:9)
[LCDFONT] FTC_Face_Requester (Fix12/Regular)
16:09:28.172 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:31.673 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:33.829 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:36.481 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:40.483 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:43.466 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:45.919 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:48.459 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:53.882 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:55.992 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:09:59.837 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:10:05.578 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:10:16.686 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[4] filter 0x60 mask 0xf1
16:10:46.630 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[5] filter 0x61 mask 0xf1
16:11:00.726 [sectionsd] DMX::readNbytes received POLLERR, pid 0x12, filter[5] filter 0x61 mask 0xf1
16:14:47.454 dmxCN: waking up again - requested from .change()
[zapit] tuned frequency does not match request. difference: 644
PES, queue 0 normal.
16:14:49.755 dmxCN: going to sleep...
16:14:49.756 eit_set_update_filter, servicekey = 0x43700016d66, current version 24
16:17:59.790 EIT Update Filter: Activate cnThread
16:17:59.827 dmxCN: waking up again - requested from .change()
16:18:35.146 dmxCN: going to sleep...
16:18:35.147 eit_set_update_filter, servicekey = 0x43700016d66, current version 25
16:47:59.210 EIT Update Filter: Activate cnThread
16:47:59.248 dmxCN: waking up again - requested from .change()
16:48:35.280 dmxCN: going to sleep...
16:48:35.281 eit_set_update_filter, servicekey = 0x43700016d66, current version 26
16:58:19.690 EIT Update Filter: Activate cnThread
16:58:19.727 dmxCN: waking up again - requested from .change()
16:58:55.022 dmxCN: going to sleep...
16:58:55.023 eit_set_update_filter, servicekey = 0x43700016d66, current version 27
16:59:51.960 EIT Update Filter: Activate cnThread
16:59:51.998 dmxCN: waking up again - requested from .change()
16:59:53.543 dmxCN: going to sleep...
16:59:53.544 eit_set_update_filter, servicekey = 0x43700016d66, current version 28
17:04:44.153 EIT Update Filter: Activate cnThread
17:04:44.192 dmxCN: waking up again - requested from .change()
17:04:46.044 dmxCN: going to sleep...
17:04:46.045 eit_set_update_filter, servicekey = 0x43700016d66, current version 29
Sieht doch eigentlich normal aus, oder übersehe ich was?


Hier mal wieder eine Fehlermeldung vor dem Absturz...

Code: Alles auswählen

19:07:39.613 dmxCN: going to sleep...
19:07:39.614 eit_set_update_filter, servicekey = 0x20085002b, current version 30
19:07:42.296 dmxCN: waking up again - requested from .change()
PES, queue 0 normal.
19:07:43.398 dmxCN: going to sleep...
19:07:43.399 eit_set_update_filter, servicekey = 0x20085000b, current version 26
avia_gt_wdt_thread: FIFO_PDCT = 127 ==> risc crashed .. restarting
avia_gt_wdt_thread: FIFO_PDCT = 127 ==> risc crashed .. restarting
19:32:15.156 dmxCN: waking up again - requested from .change()
[dmx.cpp:read:184] read(fd, buf, n): Connection timed out
[zapit] pat parsing failed
zap failed!
[dmx.cpp:read:184] read(fd, buf, n): Connection timed out
[zapit] pat parsing failed
zap failed!
Aber diesmal ohne

Code: Alles auswählen

[zapit] tuned frequency does not match request.
tik-tak
Erleuchteter
Erleuchteter
Beiträge: 543
Registriert: Donnerstag 14. Dezember 2006, 09:23

Re: Immer wieder "Kanal nicht verfügbar"

Beitrag von tik-tak »

Erstzen Sie AVIA-eNX einfach und dann weiter schauen :)