system time running too fast

Christopher Hall hsw at openmoko.com
Wed Nov 19 03:08:04 CET 2008


Hello everyone,

 I am in the process of trying to boot a system on S3C6410;
basically the existing GTA02 root file system with a kernel
from andy-tracking.

I can get to /bin/sh and I am trying to see where the startup
sequence fails so I added the lines
  ps ax
  /bin/sh
to the end of /etc/init.d/rc

when "ps ax" runs, just before the process
listing is displayed, it outputs:
  Unknown HZ value! (71) Assume 100.

repeating the "ps ax"  at the shell prompt the error
id repeated but the value 71 slowly increments to 94
then the error disappears.

By repeating the "ps ax" quickly I estimated around 8 sec for
each increment.

The initial 71 value was consistent over several reboots.

Also using date/uptime/top to watch the clock; the system time
appears to be running 2x too fast.  A short test was
type "date", wait 60 seconds then type date "date" again
system time had advanced 2 minutes.

Perhaps both problems are related.  Any advice on where to look?
And what information I should collect to help debug this.

-- 
Best regards.
Christopher Hall                      hswATopenmoko.com
-------------- next part --------------
[ 0.000000] Linux version 2.6.28-andy_b6ccced6e53b0560-gb6ccced-dirty (hsw at linux-desktop) (gcc version 4.1.2) #16 Fri Nov 14 19:34:29 CST 2008
[ 0.000000] CPU: ARMv6-compatible processor [410fb766] revision 6 (ARMv7), cr=00c5387f
[ 0.000000] CPU: VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
[ 0.000000] Machine: OM-GTA03
[ 0.000000] Memory policy: ECC disabled, Data cache writeback
[ 0.000000] On node 0 totalpages: 32768
[ 0.000000] free_area_init_node: node 0, pgdat c02f74b0, node_mem_map c0383000
[ 0.000000] Normal zone: 256 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 32512 pages, LIFO batch:7
[ 0.000000] Movable zone: 0 pages used for memmap
[ 0.000000] CPU S3C6410 (id 0x36410101)
[ 0.000000] s3c6410_init_clocks: initialising clocks
[ 0.000000] S3C24XX Clocks, (c) 2004 Simtec Electronics
[ 0.000000] s3c6400_setup_clocks: registering clocks
[ 0.000000] s3c6400_setup_clocks: clkdiv0 = 01043310
[ 0.000000] s3c6400_setup_clocks: xtal is 12000000
[ 0.000000] S3C64XX: PLL settings, A=532000000, M=532000000, E=24000000
[ 0.000000] S3C64XX: HCLK2=266000000, HCLK=133000000, PCLK=66500000
[ 0.000000] mout_apll: source is fout_apll (1), rate is 532000000
[ 0.000000] mout_epll: source is fout_epll (1), rate is 24000000
[ 0.000000] mout_mpll: source is mpll (1), rate is 532000000
[ 0.000000] mmc_bus: source is mout_epll (0), rate is 24000000
[ 0.000000] mmc_bus: source is mout_epll (0), rate is 24000000
[ 0.000000] mmc_bus: source is mout_epll (0), rate is 24000000
[ 0.000000] usb-host-bus: source is mout_epll (0), rate is 24000000
[ 0.000000] s3c64xx_clk_doutmpll_get_rate: parent is 532000000
[ 0.000000] uclk1: source is dout_mpll (1), rate is 66500000
[ 0.000000] spi-bus: source is mout_epll (0), rate is 24000000
[ 0.000000] spi-bus: source is mout_epll (0), rate is 24000000
[ 0.000000] audio-bus: source is mout_epll (0), rate is 24000000
[ 0.000000] audio-bus: source is mout_epll (0), rate is 24000000
[ 0.000000] irda-bus: source is mout_epll (0), rate is 24000000
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32512
[ 0.000000] Kernel command line: console=ttySAC3,115200 loglevel=8 init=/bin/sh root=/dev/mmcblk0p2 rootfstype=ext3
[ 0.000000] s3c64xx_init_irq: initialising interrupts
[ 0.000000] PID hash table entries: 512 (order: 9, 2048 bytes)
[ 0.000000] timer tcon=00000000, tcnt 1b0f0, tcfg 00000201,00000000, usec 00001719
[42949372.960000] Console: colour dummy device 80x30
[42949372.960000] console [ttySAC3] enabled
[42949372.970000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[42949372.980000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[42949373.020000] Memory: 128MB = 128MB total
[42949373.020000] Memory: 126192KB available (2772K code, 686K data, 100K init)
[42949373.030000] SLUB: Genslabs=12, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[42949373.040000] Calibrating delay loop... 265.42 BogoMIPS (lpj=1327104)
[42949373.290000] Mount-cache hash table entries: 512
[42949373.290000] CPU: Testing write buffer coherency: ok
[42949373.310000] net_namespace: 316 bytes
[42949373.310000] regulator: core version 0.5
[42949373.320000] NET: Registered protocol family 16
[42949373.360000] S3C6410: Initialising architecture
[42949373.500000] cfg80211: Using static regulatory domain info
[42949373.500000] cfg80211: Regulatory domain: US
[42949373.510000] \0x09(start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[42949373.520000] \0x09(2402000 KHz - 2472000 KHz @ 40000 KHz), (600 mBi, 2700 mBm)
[42949373.530000] \0x09(5170000 KHz - 5190000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
[42949373.540000] \0x09(5190000 KHz - 5210000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
[42949373.550000] \0x09(5210000 KHz - 5230000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
[42949373.560000] \0x09(5230000 KHz - 5330000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
[42949373.570000] \0x09(5735000 KHz - 5835000 KHz @ 40000 KHz), (600 mBi, 3000 mBm)
[42949373.580000] cfg80211: Calling CRDA for country: US
[42949373.610000] ashmem: initialized
[42949373.690000] JFFS2 version 2.2. (NAND) (SUMMARY) \0xc2\0xa9 2001-2006 Red Hat, Inc.
[42949373.700000] alg: No test for stdrng (krng)
[42949373.700000] io scheduler noop registered
[42949373.710000] io scheduler anticipatory registered
[42949373.720000] io scheduler deadline registered
[42949373.730000] io scheduler cfq registered (default)
[42949374.970000] Serial: 8250/16550 driver4 ports, IRQ sharing disabled
[42949374.990000] ##### init_resume_dependency_list(head=c02f0958)
[42949374.990000] s3c6400-uart.0: s3c2410_serial0 at MMIO 0x7f005000 (irq = 16) is a S3C6400/10
[42949375.000000] ##### init_resume_dependency_list(head=c02f0a24)
[42949375.010000] s3c6400-uart.1: s3c2410_serial1 at MMIO 0x7f005400 (irq = 20) is a S3C6400/10
[42949375.020000] ##### init_resume_dependency_list(head=c02f0af0)
[42949375.030000] s3c6400-uart.2: s3c2410_serial2 at MMIO 0x7f005800 (irq = 24) is a S3C6400/10
[42949375.040000] ##### init_resume_dependency_list(head=c02f0bbc)
[42949375.050000] s3c6400-uart.3: s3c2410_serial3 at MMIO 0x7f005c00 (irq = 28) is a S3C6400/10
[42949375.110000] brd: module loaded
[42949375.140000] loop: module loaded
[42949375.140000] libertas_sdio: Libertas SDIO driver
[42949375.140000] libertas_sdio: Copyright Pierre Ossman
[42949375.160000] mice: PS/2 mouse device common for all mice
[42949375.170000] input: Neo1973 Buttons as /class/input/input0
[42949375.240000] i2c /dev entries driver
[42949375.250000] s3c2440-i2c s3c2440-i2c.0: slave address 0x10
[42949375.250000] s3c2440-i2c s3c2440-i2c.0: bus frequency set to 377 KHz
[42949375.270000] s3c2440-i2c s3c2440-i2c.0: i2c-0: S3C I2C adapter
[42949375.280000] input: GTA02 PMU events as /class/input/input1
[42949375.540000] pcf50633 0-0073: IRQ 105 cannot be enabled as wake-upsource in this hardware revision! vs 105
[42949375.550000] pcf50633-rtc pcf50633-rtc: rtc core: registered pcf50633 as rtc0
[42949375.570000] pcf50633 0-0073: probe completed
[42949375.660000] cpuidle: using governor ladder
[42949375.660000] sdhci: Secure Digital Host Controller Interface driver
[42949375.670000] sdhci: Copyright(c) Pierre Ossman
[42949375.680000] s3c-sdhci s3c-sdhci.0: clock source 0: hsmmc (133000000 Hz)
[42949375.690000] s3c-sdhci s3c-sdhci.0: clock source 1: hsmmc (133000000 Hz)
[42949375.700000] s3c-sdhci s3c-sdhci.0: clock source 2: mmc_bus (24000000 Hz)
[42949375.710000] sdhci: ============== REGISTER DUMP ==============
[42949375.720000] sdhci: Sys addr: 0x00000000 | Version: 0x00000401
[42949375.730000] sdhci: Blk size: 0x00000000 | Blk cnt: 0x00000000
[42949375.740000] sdhci: Argument: 0x00000000 | Trn mode: 0x00000000
[42949375.750000] sdhci: Present: 0x01ff0000 | Host ctl: 0x00000000
[42949375.760000] sdhci: Power: 0x00000000 | Blk gap: 0x00000000
[42949375.770000] sdhci: Wake-up: 0x00000000 | Clock: 0x00000000
[42949375.780000] sdhci: Timeout: 0x00000000 | Int stat: 0x00000000
[42949375.790000] sdhci: Int enab: 0x02ff00fb | Sig enab: 0x02ff00fb
[42949375.800000] sdhci: AC12 err: 0x00000000 | Slot int: 0x00000000
[42949375.810000] sdhci: Caps: 0x05e80080 | Max curr: 0x00000000
[42949375.820000] sdhci: ===========================================
[42949375.830000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949375.840000] mmc0: SDHCI controller on samsung-hsmmc [s3c-sdhci.0] using DMA
[42949375.860000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949375.870000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949375.880000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949375.890000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949375.910000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949375.910000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949375.920000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949375.930000] logger: created 64K log 'log_main'
[42949375.940000] logger: created 256K log 'log_events'
[42949375.950000] logger: created 64K log 'log_radio'
[42949375.970000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949375.980000] regulator: auto: 3300 mV normal
[42949375.990000] regulator: down1: 900 <--> 1200 mV normal
[42949376.000000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=80808080
[42949376.010000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=00008080
[42949376.020000] regulator: down2: 1800 mV normal
[42949376.030000] s3c6410_setup_sdhci0_cfg_card: CTRL 2=c0004100, 3=00008080
[42949376.040000] mmc0: new high speed SDHC card at address 0002
[42949376.050000] mmcblk0: mmc0:0002 SD 3.77 GiB
[42949376.060000] mmcblk0: p1 p2 p3
[42949376.070000] regulator: ldo1: 1300 mV normal
[42949376.100000] regulator: ldo2: 3300 mV normal
[42949376.120000] regulator: ldo3: 3300 mV normal
[42949376.140000] regulator: ldo4: 3000 mV normal
[42949376.160000] regulator: ldo5: 3000 mV normal
[42949376.160000] regulator: ldo6: 3000 mV normal
[42949376.160000] regulator: hcldo: 2000 <--> 3300 mV normal
[42949376.180000] regulator: memldo: 1800 mV normal
[42949376.190000] Advanced Linux Sound Architecture Driver Version 1.0.18rc3.
[42949376.200000] ASoC version 0.13.2
[42949376.200000] ALSA device list:
[42949376.210000] No soundcards found.
[42949376.220000] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
[42949376.260000] pcf50633-rtc pcf50633-rtc: setting system clock to 2000-02-01 00:00:16 UTC (949363216)
[42949378.150000] kjournald starting. Commit interval 5 seconds
[42949378.150000] EXT3 FS on mmcblk0p2, internal journal
[42949378.160000] EXT3-fs: recovery complete.
[42949378.170000] EXT3-fs: mounted filesystem with ordered data mode.
[42949378.180000] VFS: Mounted root (ext3 filesystem).
[42949378.190000] Freeing init memory: 100K
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 189 bytes
Desc: This is a digitally signed message part.
Url : http://lists.openmoko.org/pipermail/openmoko-kernel/attachments/20081119/0b2ca403/attachment.pgp 


More information about the openmoko-kernel mailing list