[mythtv-users] Mythbuntu slow startup : 0.24-fixes problem?

Tue Feb 15 11:11:00 UTC 2011

Hi,

I recently updated to 0.24-fixes and around that time my startup times 
became much slower. Also when the box starts I often get messages about 
not being able to locate the b/e. I'm really not sure what is going 
wrong (if anything), I've extracted some bits from dmesg that seem to 
take some time. Sometimes I turn the box on and come back soon 
afterwards to find out it has shut itself down (I've been using 
mythwelcome very happily until recently). Could anyone have a look and 
offer any words of advice?

In particular:

1) Why the big gap between 2.18 secs and 22.59?
2) Why the big gap between 24.62 secs and 29.55?
3) Why might EXT4-fs (sda1) be re-mounted at 29 seconds?
4) When it says "saa7164_downloadimage() Image downloaded" is it 
downloading over the network?
5) Why does it do this twice?
6) Are the delays to do with tda10048 firmware to be expected?

Thanks,
John.

...

[    1.728047] e1000e 0000:00:19.0: irq 42 for MSI/MSI-X
[    1.901674] e1000e 0000:00:19.0: eth0: (PCI Express:2.5GB/s:Width x1) 
00:27:0e:03:fb:9d
[    1.901677] e1000e 0000:00:19.0: eth0: Intel(R) PRO/1000 Network 
Connection
[    1.901719] e1000e 0000:00:19.0: eth0: MAC: 9, PHY: 9, PBA No: ffffff-0ff
[    1.991213] usb 2-1.3: new full speed USB device using ehci_hcd and 
address 3
[    1.997235] EXT4-fs (sda1): mounted filesystem with ordered data 
mode. Opts: (null)
[    2.180674] usb 2-1.4: new high speed USB device using ehci_hcd and 
address 4
[   22.598957] udev[398]: starting version 163
[   22.621252] lp: driver loaded but no devices found
[   22.644433] agpgart-intel 0000:00:00.0: Intel HD Graphics Chipset
[   22.645942] agpgart-intel 0000:00:00.0: detected 32764K stolen memory
[   22.725304] Adding 11285624k swap on /dev/sda5.  Priority:-1 
extents:1 across:11285624k
[   22.728337] saa7164 driver loaded
[   22.728380] saa7164 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> 
IRQ 16
[   22.738950] CORE saa7164[0]: subsystem: 0070:8940, board: Hauppauge 
WinTV-HVR2200 [card=4,insmod option]

...

[   23.861117] sd 4:0:0:0: Attached scsi generic sg2 type 0
[   23.861970] sd 4:0:0:0: [sdb] 488386584 1024-byte logical blocks: 
(500 GB/465 GiB)
[   23.862716] sd 4:0:0:0: [sdb] Write Protect is off
[   23.862719] sd 4:0:0:0: [sdb] Mode Sense: 3c 00 00 00
[   23.862722] sd 4:0:0:0: [sdb] Assuming drive cache: write through
[   23.863839] sd 4:0:0:0: [sdb] 488386584 1024-byte logical blocks: 
(500 GB/465 GiB)
[   23.864586] sd 4:0:0:0: [sdb] Assuming drive cache: write through
[   23.864630]  sdb:
[   23.893780] type=1400 audit(1297715719.474:10): apparmor="STATUS" 
operation="profile_replace" name="/sbin/dhclient3" pid=1084 
comm="apparmor_parser"
[   23.894287] type=1400 audit(1297715719.474:11): apparmor="STATUS" 
operation="profile_replace" 
name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=1084 
comm="apparmor_parser"
[   24.298965]  sdb1
[   24.299775] sd 4:0:0:0: [sdb] 488386584 1024-byte logical blocks: 
(500 GB/465 GiB)
[   24.300525] sd 4:0:0:0: [sdb] Assuming drive cache: write through
[   24.300556] sd 4:0:0:0: [sdb] Attached SCSI disk
[   24.382196] lirc_dev: IR Remote Control driver registered, major 250
[   24.622491] e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow 
Control: RX
[   24.622495] e1000e 0000:00:19.0: eth0: 10/100 speed: disabling TSO
[   24.624413] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   29.551927] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro,commit=0
[   30.187019] saa7164_downloadimage() Image downloaded, booting...
[   30.296723] saa7164_downloadimage() Image booted successfully.
[   30.296744] starting firmware download(2)
[   32.630794] saa7164_downloadimage() Image downloaded, booting...
[   34.057156] saa7164_downloadimage() Image booted successfully.
[   34.057178] firmware download complete.
[   34.097540] tveeprom 5-0000: Hauppauge model 89619, rev D3F2, serial# 
7088724
[   34.097546] tveeprom 5-0000: MAC address is 00:0d:fe:6c:2a:54
[   34.097550] tveeprom 5-0000: tuner model is NXP 18271C2_716x (idx 
152, type 4)
[   34.097558] tveeprom 5-0000: TV standards PAL(B/G) NTSC(M) PAL(I) 
SECAM(L/L') PAL(D/D1/K) ATSC/DVB Digital (eeprom 0xfc)
[   34.097561] tveeprom 5-0000: audio processor is SAA7164 (idx 43)
[   34.097562] tveeprom 5-0000: decoder processor is SAA7164 (idx 40)
[   34.097564] tveeprom 5-0000: has radio
[   34.097565] saa7164[0]: Hauppauge eeprom: model=89619
[   34.152080] tda18271 6-0060: creating new instance
[   34.156306] TDA18271HD/C2 detected @ 6-0060
[   34.600232] DVB: registering new adapter (saa7164)
[   34.600236] DVB: registering adapter 0 frontend 0 (NXP TDA10048HN 
DVB-T)...
[   34.629520] tda18271 7-0060: creating new instance
[   34.633485] TDA18271HD/C2 detected @ 7-0060
[   35.024689] eth0: no IPv6 routers present
[   35.079362] tda18271: performing RF tracking filter calibration
[   38.084803] tda18271: RF tracking filter calibration complete
[   38.084907] DVB: registering new adapter (saa7164)
[   38.084910] DVB: registering adapter 1 frontend 0 (NXP TDA10048HN 
DVB-T)...
[   42.113252] tda10048_firmware_upload: waiting for firmware upload 
(dvb-fe-tda10048-1.0.fw)...
[   42.114833] tda10048_firmware_upload: firmware read 24878 bytes.
[   42.114835] tda10048_firmware_upload: firmware uploading
[   45.071862] tda10048_firmware_upload: firmware uploaded
[   45.462831] tda18271: performing RF tracking filter calibration
[   48.099171] tda18271: RF tracking filter calibration complete
[   48.347260] tda10048_firmware_upload: waiting for firmware upload 
(dvb-fe-tda10048-1.0.fw)...
[   48.348707] tda10048_firmware_upload: firmware read 24878 bytes.
[   48.348709] tda10048_firmware_upload: firmware uploading
[   51.305872] tda10048_firmware_upload: firmware uploaded