Page 1 of 1

tftp boot freezes system on play recordings

Posted: Mon Feb 16, 2009 10:45 pm
by fuecy
I have some problems with booting from tftp
After booting from tftp, withing 5 min playing a recording the system totally freezes. No keyboard, remote or telnet response.

First i want to tell that my problem is different from this tread becourse my system boots.

So my configuration.
I have one server running gentoo with mythtv 0.21_p19046
I have one client with mythbuntu (boot from hd)
I have one desktop running gentoo with mythclient (boot from hd)
and i have one VIA EPIA M1000 with 1Gb mem running minimyth where this is all about.

The start situation is my minimyth box is running minimyth from ram disk version 64 booting from cf. That is working good. Be course i am tiered of the updating process with the cf card i want to try booting from tftp.
Now comes the trouble. The boot process looks ok. I use the same configuration, same version of minimyth, same hardware only booting from tftp and after a few minites my recordings freeze (all other clients work fine). Be course I am not able to login to watch the log files or when logging to the server there are no log lines that give me a indicator that there is something different from booting from the cf card. (be course i have no clue at all this also can be a bug in the tftp server for example)

Some configurations
As tftp server i use tftp-hpa 0.48 started as

Code: Select all

/usr/sbin/in.tftpd -l -R 4096:32767 -c -v -s /var/tftp/
My file structure for the tftp looks like

Code: Select all

.:
minimyth

./minimyth:
conf  conf-rw  current  minimyth-0.21.0-61  minimyth-0.21.0-64  pxelinux.0  pxelinux.cfg

./minimyth/conf:
tv-beneden

./minimyth/conf/tv-beneden:
joystickmenurc  lircd.conf  lircrc  minimyth.conf  minimyth.pm  minimyth.script

./minimyth/conf-rw:
tv-beneden+codecs.sfs  tv-beneden+themecache.sfs

./minimyth/minimyth-0.21.0-61:
kernel  rootfs  themes

./minimyth/minimyth-0.21.0-61/themes:
G.A.N.T.sfs   Iulius-OSD.sfs  Minimalist-wide.sfs  MythCenter.sfs  Retro.sfs           Titivillus.sfs    blootubelite-wide.sfs  blueosd.sfs     isthmus.sfs
Gray-OSD.sfs  Iulius.sfs      MythCenter-wide.sfs  Retro-OSD.sfs   Titivillus-OSD.sfs  blootube-osd.sfs  blue.sfs               defaultosd.sfs

./minimyth/minimyth-0.21.0-64:
kernel  minimyth.md5  rootfs  themes  version

./minimyth/minimyth-0.21.0-64/themes:
G.A.N.T.sfs   Iulius-OSD.sfs  Minimalist-wide.sfs  MythCenter.sfs  Retro.sfs           Titivillus.sfs    blootubelite-wide.sfs  blueosd.sfs     isthmus.sfs
Gray-OSD.sfs  Iulius.sfs      MythCenter-wide.sfs  Retro-OSD.sfs   Titivillus-OSD.sfs  blootube-osd.sfs  blue.sfs               defaultosd.sfs

./minimyth/pxelinux.cfg:
01-00-40-63-ec-da-d6  default.org
The pxelinux.cfg
Where tv-beneden points to the minimyth client and xerees is my server and current is a softlink to the minimyth folder

Code: Select all

DEFAULT minimyth-ram

LABEL minimyth-ram
        KERNEL current/kernel
        APPEND ro root=/dev/ram0 ramdisk_size=96000 initrd=current/rootfs MM_MINIMYTH_BOOT_URL=tftp://xerees/minimyth/ MM_DHCP_HOST_NAME=tv-beneden MM_INIT_TYPE=sh
When booting the first time my tftp log file contains all the request and the write actions also explain the write permissions are working

Code: Select all

Feb 15 15:05:34 xerees in.tftpd[31550]: RRQ from 192.168.8.30 filename /minimyth/pxelinux.0
Feb 15 15:05:34 xerees in.tftpd[31550]: tftp: client does not accept options
Feb 15 15:05:34 xerees in.tftpd[31551]: RRQ from 192.168.8.30 filename /minimyth/pxelinux.0
Feb 15 15:05:34 xerees in.tftpd[31568]: RRQ from 192.168.8.30 filename /minimyth/pxelinux.cfg/ffffffff-ffff-ffff-ffff-ffffffffffff
Feb 15 15:05:34 xerees in.tftpd[31570]: RRQ from 192.168.8.30 filename /minimyth/pxelinux.cfg/01-00-40-63-ec-da-d6
Feb 15 15:05:34 xerees in.tftpd[31572]: RRQ from 192.168.8.30 filename /minimyth/current/kernel
Feb 15 15:05:34 xerees in.tftpd[31624]: RRQ from 192.168.8.30 filename /minimyth/current/rootfs
Feb 15 15:06:02 xerees in.tftpd[7097]: RRQ from 192.168.8.30 filename minimyth/conf/tv-beneden/minimyth.conf
Feb 15 15:06:05 xerees in.tftpd[7200]: RRQ from 192.168.8.30 filename minimyth/conf/tv-beneden/lircrc
Feb 15 15:06:07 xerees in.tftpd[7291]: RRQ from 192.168.8.30 filename minimyth/conf/tv-beneden/lircd.conf
Feb 15 15:06:09 xerees in.tftpd[7398]: RRQ from 192.168.8.30 filename minimyth/conf-rw/tv-beneden+codecs.sfs
Feb 15 15:06:09 xerees in.tftpd[7515]: RRQ from 192.168.8.30 filename minimyth/current/extras.sfs
Feb 15 15:06:09 xerees in.tftpd[7537]: RRQ from 192.168.8.30 filename minimyth/conf/tv-beneden/extras.sfs
Feb 15 15:06:09 xerees in.tftpd[7553]: RRQ from 192.168.8.30 filename minimyth/conf/default/extras.sfs
Feb 15 16:06:15 xerees mountd[5667]: authenticated mount request from 192.168.8.30:601 for /mnt/data1/fotos (/mnt/data1)
Feb 15 16:06:15 xerees mountd[5667]: authenticated mount request from 192.168.8.30:616 for /mnt/data1/ROMS (/mnt/data1)
Feb 15 16:06:15 xerees mountd[5667]: authenticated mount request from 192.168.8.30:630 for /mnt/data1/mp3 (/mnt/data1)
Feb 15 16:06:15 xerees mountd[5667]: authenticated mount request from 192.168.8.30:644 for /mnt/data2/films (/mnt/data2)
Feb 15 15:06:17 xerees in.tftpd[10220]: RRQ from 192.168.8.30 filename minimyth/current/themes/G.A.N.T.sfs
Feb 15 15:06:18 xerees in.tftpd[10581]: RRQ from 192.168.8.30 filename minimyth/current/themes/blueosd.sfs
Feb 15 15:06:19 xerees in.tftpd[10584]: RRQ from 192.168.8.30 filename minimyth/conf-rw/tv-beneden+themecache.sfs
Feb 15 15:19:46 xerees in.tftpd[31205]: WRQ from 192.168.8.30 filename minimyth/conf-rw/tv-beneden+themecache.sfs
Feb 15 15:20:50 xerees in.tftpd[31226]: WRQ from 192.168.8.30 filename minimyth/conf-rw/tv-beneden+codecs.sfs
The minimyth log file

Code: Select all

Feb 16 21:24:35 (none) syslog.info syslogd started: BusyBox v1.11.3
Feb 16 21:24:35 (none) daemon.info init: Entering runlevel: 5
Feb 16 21:24:35 (none) local0.info minimyth: starting splash screen
Feb 16 21:24:36 (none) local0.info minimyth: configuring shared libraries ...
Feb 16 21:24:38 (none) local0.info minimyth: starting loopback network interface ...
Feb 16 21:24:38 (none) auth.notice portmap[870]: Only binding to LOOPBACK address!
Feb 16 21:24:38 (none) local0.info minimyth: starting DHCP client ...
Feb 16 21:24:39 (none) local0.info udhcpc[950]: udhcpc (v1.11.3) started
Feb 16 21:24:39 (none) local0.info udhcpc[950]: Sending discover...
Feb 16 21:24:39 (none) local0.info udhcpc[950]: Sending select for 192.168.8.30...
Feb 16 21:24:39 (none) local0.info udhcpc[950]: Lease of 192.168.8.30 obtained, lease time 600
Feb 16 22:24:39 (none) local0.info minimyth: loading kernel modules (automatic) ...
Feb 16 22:24:41 (none) local0.info minimyth: warning: sh init scripts are deprecated.
Feb 16 22:24:41 (none) local0.info minimyth: use perl init scripts rather than sh init scripts.
Feb 16 22:24:42 (none) local0.info minimyth: downloading configuration ...
Feb 16 22:24:42 (none) local0.info minimyth: 'tftp://xerees/minimyth/conf/tv-beneden/minimyth.conf' downloaded.
Feb 16 22:24:42 (none) local0.info minimyth: processing configuration ...
Feb 16 22:24:42 (none) local0.info minimyth: checking for obsolete variables ...
Feb 16 22:24:46 (none) local0.info minimyth: stopping DHCP client ...
Feb 16 21:24:46 (none) local0.info udhcpc[1049]: Received SIGTERM
Feb 16 22:24:46 (none) local0.info minimyth: starting DHCP client ...
Feb 16 22:24:46 (none) local0.info udhcpc[2689]: udhcpc (v1.11.3) started
Feb 16 22:24:46 (none) local0.info udhcpc[2689]: Sending discover...
Feb 16 21:24:46 (none) local0.info udhcpc[2689]: Sending select for 192.168.8.30...
Feb 16 21:24:46 (none) local0.info udhcpc[2689]: Lease of 192.168.8.30 obtained, lease time 600
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables ...
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_ACPI'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_AUDIO'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_BACKEND'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_CODECS'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_CPU'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_CRON'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_DEBUG'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_DHCP'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_EXTERNAL'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_EXTRAS'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_FLASH'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_FONT'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_GAME'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_HARDWARE'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_LCDPROC'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_LIRC'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_MASTER'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_MEDIA'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_MINIMYTH'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_MYTHDB'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_PLUGIN'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_SECURITY'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_SSH_SERVER'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_THEME'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_VERSION'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_VIDEO'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_WIIMOTE'
Feb 16 22:24:47 (none) local0.info minimyth: processing configuration: checking variables 'MM_X'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables ...
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_ACPI'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_AUDIO'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_BACKEND'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_CODECS'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_CPU'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_CRON'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_DEBUG'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_DHCP'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_EXTERNAL'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_EXTRAS'
Feb 16 22:24:48 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_FLASH'
Feb 16 22:24:50 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_FONT'
Feb 16 22:24:50 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_GAME'
Feb 16 22:24:50 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_HARDWARE'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_LCDPROC'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_LIRC'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_MASTER'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_MEDIA'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_MINIMYTH'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_MYTHDB'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_PLUGIN'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_SECURITY'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_SSH_SERVER'
Feb 16 22:24:51 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_THEME'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_VERSION'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_VIDEO'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_WIIMOTE'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: defaulting variables 'MM_X'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables ...
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_ACPI'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_AUDIO'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_BACKEND'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_CODECS'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_CPU'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_CRON'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_DEBUG'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_DHCP'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_EXTERNAL'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_EXTRAS'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_FLASH'
Feb 16 22:24:52 (none) local0.info minimyth: processing configuration: writing variables 'MM_FONT'
Feb 16 22:24:53 (none) local0.info minimyth: processing configuration: writing variables 'MM_GAME'
Feb 16 22:24:53 (none) local0.info minimyth: processing configuration: writing variables 'MM_HARDWARE'
Feb 16 22:24:53 (none) local0.info minimyth: processing configuration: writing variables 'MM_LCDPROC'
Feb 16 22:24:53 (none) local0.info minimyth: processing configuration: writing variables 'MM_LIRC'
Feb 16 22:24:53 (none) local0.info minimyth: processing configuration: writing variables 'MM_MASTER'
Feb 16 22:24:53 (none) local0.info minimyth: processing configuration: writing variables 'MM_MEDIA'
Feb 16 22:24:54 (none) local0.info minimyth: processing configuration: writing variables 'MM_MINIMYTH'
Feb 16 22:24:54 (none) local0.info minimyth: processing configuration: writing variables 'MM_MYTHDB'
Feb 16 22:24:54 (none) local0.info minimyth: processing configuration: writing variables 'MM_PLUGIN'
Feb 16 22:24:54 (none) local0.info minimyth: processing configuration: writing variables 'MM_SECURITY'
Feb 16 22:24:55 (none) local0.info minimyth: processing configuration: writing variables 'MM_SSH_SERVER'
Feb 16 22:24:55 (none) local0.info minimyth: processing configuration: writing variables 'MM_THEME'
Feb 16 22:24:55 (none) local0.info minimyth: processing configuration: writing variables 'MM_VERSION'
Feb 16 22:24:55 (none) local0.info minimyth: processing configuration: writing variables 'MM_VIDEO'
Feb 16 22:24:55 (none) local0.info minimyth: processing configuration: writing variables 'MM_WIIMOTE'
Feb 16 22:24:55 (none) local0.info minimyth: processing configuration: writing variables 'MM_X'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files ...
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_ACPI'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_AUDIO'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_BACKEND'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_CODECS'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_CPU'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_CRON'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_DEBUG'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_DHCP'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_EXTERNAL'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_EXTRAS'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_FLASH'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_FONT'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_GAME'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_HARDWARE'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_LCDPROC'
Feb 16 22:24:56 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_LIRC'
Feb 16 22:24:57 (none) local0.info minimyth: 'tftp://xerees/minimyth/conf/tv-beneden/lircd.conf' downloaded.
Feb 16 22:24:57 (none) local0.info minimyth: 'tftp://xerees/minimyth/conf/tv-beneden/lircrc' downloaded.
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_MASTER'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_MEDIA'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_MINIMYTH'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_MYTHDB'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_PLUGIN'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_SECURITY'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_SSH_SERVER'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_THEME'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_VERSION'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_VIDEO'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_WIIMOTE'
Feb 16 22:24:57 (none) local0.info minimyth: processing configuration: fetching additional files 'MM_X'
Feb 16 22:24:58 (none) local0.info minimyth: loading kernel modules (manual) ...
Feb 16 22:24:58 (none) local0.info minimyth: starting system logging ...
Feb 16 22:24:58 (none) user.notice kernel: klogd started: BusyBox v1.11.3 (2008-11-29 00:28:47 PST)
Feb 16 22:24:58 (none) user.info kernel:  entries: 65536 (order: 7, 524288 bytes)
Feb 16 22:24:58 (none) user.info kernel: TCP: Hash tables configured (established 131072 bind 65536)
Feb 16 22:24:58 (none) user.info kernel: TCP reno registered
Feb 16 22:24:58 (none) user.info kernel: NET: Registered protocol family 1
Feb 16 22:24:58 (none) user.info kernel: checking if image is initramfs...it isn't (bad gzip magic numbers); looks like an initrd
Feb 16 22:24:58 (none) user.info kernel: Switched to NOHz mode on CPU #0
Feb 16 22:24:58 (none) user.info kernel: Freeing initrd memory: 71992k freed
Feb 16 22:24:58 (none) user.warn kernel: highmem bounce pool size: 64 pages
Feb 16 22:24:58 (none) user.info kernel: squashfs: version 3.4 (2008/08/26) Phillip Lougher
Feb 16 22:24:58 (none) user.info kernel: msgmni has been set to 1768
Feb 16 22:24:58 (none) user.info kernel: io scheduler noop registered (default)
Feb 16 22:24:58 (none) user.info kernel: PCI: VIA PCI bridge detected.Disabling DAC.
Feb 16 22:24:58 (none) user.debug kernel: pci 0000:01:00.0: Boot video device
Feb 16 22:24:58 (none) user.info kernel: vesafb: framebuffer at 0xd8000000, mapped to 0xf8880000, using 2400k, total 65536k
Feb 16 22:24:58 (none) user.info kernel: vesafb: mode is 640x480x32, linelength=2560, pages=50
Feb 16 22:24:58 (none) user.info kernel: vesafb: protected mode interface info at c000:8846
Feb 16 22:24:58 (none) user.info kernel: vesafb: pmi: set display start = c00c8897, set palette = c00c88f7
Feb 16 22:24:58 (none) user.info kernel: vesafb: pmi: ports = b4c3 b503 d403 d503 cc03 d703 d803 d903 ff03
Feb 16 22:24:58 (none) user.info kernel: vesafb: scrolling: redraw
Feb 16 22:24:58 (none) user.info kernel: vesafb: Truecolor: size=0:8:8:8, shift=0:16:8:0
Feb 16 22:24:58 (none) user.warn kernel: Console: switching to colour frame buffer device 80x30
Feb 16 22:24:58 (none) user.info kernel: fb0: VESA VGA frame buffer device
Feb 16 22:24:58 (none) user.info kernel: brd: module loaded
Feb 16 22:24:58 (none) user.info kernel: Intel(R) PRO/1000 Network Driver - version 7.3.20-k2-NAPI
Feb 16 22:24:58 (none) user.info kernel: Copyright (c) 1999-2006 Intel Corporation.
Feb 16 22:24:58 (none) user.info kernel: e1000e: Intel(R) PRO/1000 Network Driver - 0.3.3.3-k2
Feb 16 22:24:58 (none) user.info kernel: e1000e: Copyright (c) 1999-2008 Intel Corporation.
Feb 16 22:24:58 (none) user.info kernel: Atheros(R) L2 Ethernet Driver - version 2.2.3
Feb 16 22:24:58 (none) user.info kernel: Copyright (c) 2007 Atheros Corporation.
Feb 16 22:24:58 (none) user.info kernel: pcnet32.c:v1.35 21.Apr.2008 tsbogend@alpha.franken.de
Feb 16 22:24:58 (none) user.info kernel: e100: Intel(R) PRO/100 Network Driver, 3.5.23-k4-NAPI
Feb 16 22:24:58 (none) user.info kernel: e100: Copyright(c) 1999-2006 Intel Corporation
Feb 16 22:24:58 (none) user.warn kernel: via-rhine: Broken BIOS detected, avoid_D3 enabled.
Feb 16 22:24:58 (none) user.info kernel: via-rhine.c:v1.10-LK1.4.3 2007-03-06 Written by Donald Becker
Feb 16 22:24:58 (none) user.warn kernel: ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 11
Feb 16 22:24:58 (none) user.debug kernel: PCI: setting IRQ 11 as level-triggered
Feb 16 22:24:58 (none) user.info kernel: ACPI: PCI Interrupt 0000:00:12.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
Feb 16 22:24:58 (none) user.info kernel: eth0: VIA Rhine II at 0xde002000, 00:40:63:ec:da:d6, IRQ 11.
Feb 16 22:24:58 (none) user.info kernel: eth0: MII PHY found at address 1, status 0x786d advertising 05e1 Link cde1.
Feb 16 22:24:58 (none) user.info kernel: PNP: No PS/2 controller found. Probing ports directly.
Feb 16 22:24:58 (none) user.info kernel: serio: i8042 KBD port at 0x60,0x64 irq 1
Feb 16 22:24:58 (none) user.info kernel: mice: PS/2 mouse device common for all mice
Feb 16 22:24:58 (none) user.info kernel: TCP cubic registered
Feb 16 22:24:58 (none) user.info kernel: RPC: Registered udp transport module.
Feb 16 22:24:58 (none) user.info kernel: RPC: Registered tcp transport module.
Feb 16 22:24:58 (none) user.warn kernel: Using IPI No-Shortcut mode
Feb 16 22:24:58 (none) user.notice kernel: RAMDISK: squashfs filesystem found at block 0
Feb 16 22:24:58 (none) user.notice kernel: RAMDISK: Loading 71990KiB [1 disk] into ram disk... |^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\
Feb 16 22:24:58 (none) user.info kernel: ^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H\^H|^H/^H-^H
Feb 16 22:24:58 (none) user.warn kernel: VFS: Mounted root (squashfs filesystem) readonly.
Feb 16 22:24:58 (none) user.info kernel: Freeing unused kernel memory: 204k freed
Feb 16 22:24:58 (none) user.info kernel: Registering unionfs 2.5 (for 2.6.26.5)
Feb 16 22:24:58 (none) user.info kernel: usbcore: registered new interface driver usbfs
Feb 16 22:24:58 (none) user.info kernel: usbcore: registered new interface driver hub
Feb 16 22:24:58 (none) user.info kernel: usbcore: registered new device driver usb
Feb 16 22:24:58 (none) user.info kernel: eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1
Feb 16 22:24:58 (none) user.info kernel: NET: Registered protocol family 17
Feb 16 22:24:58 (none) user.info kernel: Real Time Clock Driver v1.12ac
Feb 16 22:24:58 (none) user.info kernel: io scheduler anticipatory registered
Feb 16 22:24:58 (none) user.info kernel: loop: module loaded
Feb 16 22:24:58 (none) user.info kernel: ppdev: user-space parallel port driver
Feb 16 22:24:58 (none) user.info kernel: Linux agpgart interface v0.103
Feb 16 22:24:58 (none) user.info kernel: agpgart: Detected VIA CLE266 chipset
Feb 16 22:24:58 (none) user.info kernel: ACPI: CPU0 (power states: C1[C1] C2[C2])
Feb 16 22:24:58 (none) user.info kernel: ACPI: ACPI0007:00 is registered as cooling_device0
Feb 16 22:24:58 (none) user.info kernel: ACPI: Processor [CPU0] (supports 2 throttling states)
Feb 16 22:24:58 (none) user.info kernel: agpgart: AGP aperture is 128M @ 0xd0000000
Feb 16 22:24:58 (none) user.info kernel: input: Power Button (FF) as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
Feb 16 22:24:58 (none) user.info kernel: ACPI: Power Button (FF) [PWRF]
Feb 16 22:24:58 (none) user.info kernel: input: Power Button (CM) as /devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input1
Feb 16 22:24:58 (none) user.info kernel: ACPI: Power Button (CM) [PWRB]
Feb 16 22:24:58 (none) user.info kernel: USB Universal Host Controller Interface driver v3.0, overcurrent ignored
Feb 16 22:24:58 (none) user.info kernel: ACPI: PCI Interrupt 0000:00:10.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
Feb 16 22:24:58 (none) user.info kernel: uhci_hcd 0000:00:10.0: UHCI Host Controller
Feb 16 22:24:58 (none) user.info kernel: uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 1
Feb 16 22:24:58 (none) user.info kernel: uhci_hcd 0000:00:10.0: irq 11, io base 0x0000d400
Feb 16 22:24:58 (none) user.info kernel: usb usb1: configuration #1 chosen from 1 choice
Feb 16 22:24:58 (none) user.info kernel: hub 1-0:1.0: USB hub found
Feb 16 22:24:58 (none) user.info kernel: hub 1-0:1.0: 2 ports detected
Feb 16 22:24:58 (none) user.warn kernel: ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 12
Feb 16 22:24:58 (none) user.debug kernel: PCI: setting IRQ 12 as level-triggered
Feb 16 22:24:58 (none) user.info kernel: ACPI: PCI Interrupt 0000:00:10.1[B] -> Link [LNKB] -> GSI 12 (level, low) -> IRQ 12
Feb 16 22:24:58 (none) user.info kernel: uhci_hcd 0000:00:10.1: UHCI Host Controller
Feb 16 22:24:58 (none) user.info kernel: uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus number 2
Feb 16 22:24:58 (none) user.info kernel: uhci_hcd 0000:00:10.1: irq 12, io base 0x0000d800
Feb 16 22:24:58 (none) user.info kernel: usb usb2: configuration #1 chosen from 1 choice
Feb 16 22:24:58 (none) user.info kernel: hub 2-0:1.0: USB hub found
Feb 16 22:24:58 (none) user.info kernel: hub 2-0:1.0: 2 ports detected
Feb 16 22:24:58 (none) user.warn kernel: ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
Feb 16 22:24:58 (none) user.debug kernel: PCI: setting IRQ 10 as level-triggered
Feb 16 22:24:58 (none) user.info kernel: ACPI: PCI Interrupt 0000:00:10.2[C] -> Link [LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 16 22:24:58 (none) user.info kernel: uhci_hcd 0000:00:10.2: UHCI Host Controller
Feb 16 22:24:58 (none) user.info kernel: uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus number 3
Feb 16 22:24:58 (none) user.info kernel: uhci_hcd 0000:00:10.2: irq 10, io base 0x0000dc00
Feb 16 22:24:58 (none) user.info kernel: usb usb3: configuration #1 chosen from 1 choice
Feb 16 22:24:58 (none) user.info kernel: hub 3-0:1.0: USB hub found
Feb 16 22:24:58 (none) user.info kernel: hub 3-0:1.0: 2 ports detected
Feb 16 22:24:58 (none) user.info kernel: ACPI: PCI Interrupt 0000:00:0d.0[A] -> Link [LNKB] -> GSI 12 (level, low) -> IRQ 12
Feb 16 22:24:58 (none) user.info kernel: usb 1-1: new low speed USB device using uhci_hcd and address 2
Feb 16 22:24:58 (none) user.info kernel: ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[12]  MMIO=[de000000-de0007ff]  Max Packet=[2048]  IR/IT contexts=[4/8]
Feb 16 22:24:58 (none) user.warn kernel: ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 5
Feb 16 22:24:58 (none) user.debug kernel: PCI: setting IRQ 5 as level-triggered
Feb 16 22:24:58 (none) user.info kernel: ACPI: PCI Interrupt 0000:00:10.3[D] -> Link [LNKD] -> GSI 5 (level, low) -> IRQ 5
Feb 16 22:24:58 (none) user.info kernel: ehci_hcd 0000:00:10.3: EHCI Host Controller
Feb 16 22:24:58 (none) user.info kernel: ehci_hcd 0000:00:10.3: new USB bus registered, assigned bus number 4
Feb 16 22:24:58 (none) user.info kernel: ehci_hcd 0000:00:10.3: irq 5, io mem 0xde001000
Feb 16 22:24:58 (none) user.info kernel: ehci_hcd 0000:00:10.3: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004, overcurrent ignored
Feb 16 22:24:58 (none) user.info kernel: usb usb4: configuration #1 chosen from 1 choice
Feb 16 22:24:58 (none) user.info kernel: hub 4-0:1.0: USB hub found
Feb 16 22:24:58 (none) user.info kernel: hub 4-0:1.0: 6 ports detected
Feb 16 22:24:58 (none) user.info kernel: ACPI: PCI Interrupt 0000:00:11.5[C] -> Link [LNKC] -> GSI 10 (level, low) -> IRQ 10
Feb 16 22:24:58 (none) user.debug kernel: PCI: Setting latency timer of device 0000:00:11.5 to 64
Feb 16 22:24:58 (none) user.info kernel: vt1211: Found VT1211 chip at 0x6000, revision 2
Feb 16 22:24:58 (none) user.err kernel: usb 1-1: device not accepting address 2, error -71
Feb 16 22:24:58 (none) user.err kernel: hub 1-0:1.0: unable to enumerate USB device on port 1
Feb 16 22:24:58 (none) user.debug kernel: ieee1394: Host added: ID:BUS[0-00:1023]  GUID[0040635000095a46]
Feb 16 22:24:58 (none) user.info kernel: usb 1-1: new low speed USB device using uhci_hcd and address 4
Feb 16 22:24:58 (none) user.warn kernel: Marking TSC unstable due to: possible TSC halt in C2.
Feb 16 22:24:58 (none) user.info kernel: usb 1-1: configuration #1 chosen from 1 choice
Feb 16 22:24:58 (none) user.info kernel: usb 1-2: new low speed USB device using uhci_hcd and address 5
Feb 16 22:24:58 (none) user.info kernel: usb 1-2: configuration #1 chosen from 1 choice
Feb 16 22:24:58 (none) user.info kernel: usbcore: registered new interface driver hiddev
Feb 16 22:24:58 (none) user.info kernel: input: BTC USB Multimedia Keyboard as /devices/pci0000:00/0000:00:10.0/usb1/1-1/1-1:1.0/input/input2
Feb 16 22:24:58 (none) user.info kernel: input: USB HID v1.10 Keyboard [BTC USB Multimedia Keyboard] on usb-0000:00:10.0-1
Feb 16 22:24:58 (none) user.info kernel: input: BTC USB Multimedia Keyboard as /devices/pci0000:00/0000:00:10.0/usb1/1-1/1-1:1.1/input/input3
Feb 16 22:24:58 (none) user.info kernel: input,hiddev96: USB HID v1.10 Device [BTC USB Multimedia Keyboard] on usb-0000:00:10.0-1
Feb 16 22:24:58 (none) user.info kernel: input: Logitech USB Receiver as /devices/pci0000:00/0000:00:10.0/usb1/1-2/1-2:1.0/input/input4
Feb 16 22:24:58 (none) user.info kernel: input: USB HID v1.10 Keyboard [Logitech USB Receiver] on usb-0000:00:10.0-2
Feb 16 22:24:58 (none) user.info kernel: input: Logitech USB Receiver as /devices/pci0000:00/0000:00:10.0/usb1/1-2/1-2:1.1/input/input5
Feb 16 22:24:58 (none) user.info kernel: input,hiddev97: USB HID v1.10 Device [Logitech USB Receiver] on usb-0000:00:10.0-2
Feb 16 22:24:58 (none) user.info kernel: usbcore: registered new interface driver usbhid
Feb 16 22:24:58 (none) user.info kernel: usbhid: v2.6:USB HID core driver
Feb 16 22:24:58 (none) user.info kernel: [drm] Initialized drm 1.1.0 20060810
Feb 16 22:24:58 (none) user.info kernel: ACPI: PCI Interrupt 0000:01:00.0[A] -> Link [LNKA] -> GSI 11 (level, low) -> IRQ 11
Feb 16 22:24:58 (none) user.info kernel: [drm] Initialized via 2.11.1 20070202 on minor 0
Feb 16 22:24:59 (none) local0.info minimyth: starting CPU frequency scaling ...
Feb 16 22:24:59 (none) user.info kernel: longhaul: VIA C3 'Nehemiah C' [C5P] CPU detected.  Powersaver supported.
Feb 16 22:24:59 (none) user.info kernel: longhaul: ACPI I/O at 0x400
Feb 16 22:24:59 (none) user.info kernel: longhaul: Using northbridge support.
Feb 16 22:24:59 (none) user.info kernel: longhaul: Using ACPI support.
Feb 16 22:24:59 (none) local0.info minimyth: starting virtual console ...
Feb 16 22:25:00 (none) local0.info minimyth: starting telnet server ...
Feb 16 22:25:00 (none) local0.info minimyth: configuring ssh server ...
Feb 16 22:25:00 (none) user.info kernel: unionfs: new lower inode mtime (bindex=0, name=ssh)
Feb 16 22:25:01 (none) local0.info minimyth: starting cron ...
Feb 16 22:25:01 (none) local0.info minimyth: restoring selected game configuration files ...
Feb 16 22:25:02 (none) local0.info minimyth: configuring MythTV master backend communication ...
Feb 16 22:25:02 (none) local0.info minimyth: installing binary codecs ...
Feb 16 22:25:03 (none) auth.info login[5147]: root login on 'pts/0'
Feb 16 22:25:04 (none) user.warn kernel: Clocksource tsc unstable (delta = -140166927 ns)
The minimyth configuration, those that are not commented (that are the same as booting from cf)

Code: Select all

MM_DHCP_HOST_NAME='tv-beneden'
MM_DHCP_DOMAIN_NAME='local'
MM_DHCP_NTP_SERVERS='192.168.8.10'
MM_DHCP_TCODE='Europe/Amsterdam'

MM_CPU_FREQUENCY_GOVERNOR='ondemand'

MM_AUDIO_TYPE='analog'

MM_X_OUTPUT_VGA='auto'
MM_X_MODE='800x600'

MM_MASTER_SERVER='192.168.8.10'
MM_MASTER_DBUSERNAME='***********'
MM_MASTER_DBPASSWORD='***********'

MM_THEME_NAME='G.A.N.T'
MM_THEMEOSD_NAME='blueosd'

MM_MEDIA_GALLERY_URL='nfs://192.168.8.10/mnt/data1/fotos'
MM_MEDIA_GAME_URL='nfs://192.168.8.10/mnt/data1/ROMS'
MM_MEDIA_MUSIC_URL='nfs://192.168.8.10/mnt/data1/mp3'
MM_MEDIA_VIDEO_URL='nfs://192.168.8.10/mnt/data2/films'

MM_LIRC_DRIVER='devinput'
MM_LIRC_DEVICE='/dev/input/event3'
MM_LIRC_FETCH_LIRCD_CONF='yes'
MM_LIRC_FETCH_LIRCRC='yes'

MM_MYTHDB_JUMPPOINTS_MythGallery='MythGallery~Alt+G'
MM_MYTHDB_JUMPPOINTS_Play_music='Play music~Alt+M'
MM_MYTHDB_JUMPPOINTS_MythStream='MythStream~Alt+S'
MM_MYTHDB_JUMPPOINTS_TV_Recording_Playback='TV Recording Playback~Alt+T'
MM_MYTHDB_JUMPPOINTS_MythVideo='MythVideo~Alt+V'
MM_MYTHDB_JUMPPOINTS_Main_Menu='Main Menu~Alt+H'
Ow and yes i could try boot from NFS or webdev but i want it to do with tftp in the first place.

Iam also remember a forum tread where pablo is telling tftp is unstable and thats he moved to webdev without any explain iam also wondering why. maybe related to this?

When writing i remember i did not do a full memory test. I will do in a few days.

If needed i can provide a lot more information about the systems and configurations, please ask. But for now this is all i think we need to solve this issue
Thanks for helping

Posted: Sun Feb 22, 2009 4:32 pm
by Pablo
Given that it works initially but fails after running from a short period of time, the most likely problem is out-of-memory of over-heating. Another possible cause might be a network issue.

How much memory do you have?

After you boot the frontend, what does

Code: Select all

cat /proc/meminfo
show?

Posted: Sun Feb 22, 2009 7:00 pm
by fuecy
I see no problem there. I have 1Gb mem
Running all day from CF without having memory or heat problems

It can be a network problem... as it now runs from TFTP
But i just bought a new 3com switch, becourse i had problems with my previous switch. After that i could not track any network issues.

Code: Select all

MemTotal:       971144 kB
MemFree:        697756 kB
Buffers:         27548 kB
Cached:          76584 kB
SwapCached:          0 kB
Active:          73500 kB
Inactive:        80492 kB
HighTotal:       65472 kB
HighFree:          192 kB
LowTotal:       905672 kB
LowFree:        697564 kB
SwapTotal:           0 kB
SwapFree:            0 kB
Dirty:               0 kB
Writeback:           0 kB
AnonPages:       49860 kB
Mapped:          20272 kB
Slab:             7220 kB
SReclaimable:     4060 kB
SUnreclaim:       3160 kB
PageTables:        700 kB
NFS_Unstable:        0 kB
Bounce:              0 kB
WritebackTmp:        0 kB
CommitLimit:    485572 kB
Committed_AS:   158056 kB
VmallocTotal:   114680 kB
VmallocUsed:      7952 kB
VmallocChunk:   106484 kB

Posted: Tue Feb 24, 2009 3:31 am
by Pablo
Both the CF and TFTP boot methods load the the compressed root file system and configuration files into memory. The only differences are the with the CF boot, the network cards PXE boot is never invoked and the files normally read from the TFTP server are read from the CF card.

You might make sure that the client is given the same parameters by DHCP when PXE makes the request (which it does once during boot) and when MiniMyth DHCP client makes the request (which it does twice during boot).

SOLVED. Ntp courses the problem

Posted: Fri Feb 27, 2009 6:43 pm
by fuecy
Solved.

My ntp server had some strange behavor. The clock get out of sync (half a sec) and mr tcp did not know what to do with the incomming packages from the video stream. So i installed a other ntp server, problem solved.
Now i go look into the ntp server.

Thanks for thinking with me.

Posted: Thu Mar 05, 2009 2:04 pm
by fuecy
The ntp server problem is resolved but i still have problems freezing the system when waching recordings by booting from tftp.
Its only when wachting recordings, live tv and music works fine.
And still no problems with booting from CF.

Are there any onthing things i can try. For so far iam not able to find what really courses the problem, becourse i cant have any interaction with the system after the it freezes.

Any advice, things i can try or request for more information of the configuration is welcome