Re: Kernel fails on Dreamcast - results of bisection

Adrian McMenamin
2009-03-21T23:39:25Z

[-- Warning: decoded text below may be mangled, UTF-8 assumed --]
[-- Attachment #1: Type: text/plain; charset="windows-1254", Size: 12149 bytes --]

On Sat, 2009-03-21 at 22:37 +0000, Adrian McMenamin wrote:
> I bisected the kernel in your (Paul's) git as I knew that it was not
> working well on the Dreamcast
....

> This is the result of the bisection. I cannot see what bit of this patch
> is causing the difficulties but I'm pretty sure that this is where it
> is. The actual problem is a race condition, as it does not happen on
> every boot...
>
> adrian@bossclass:~/lethal-repo$ git bisect good
> 955c0778723501cc16fec40501cd54b7e72d3e74 is first bad commit
> commit 955c0778723501cc16fec40501cd54b7e72d3e74
> Author: Magnus Damm <damm@igel.co.jp>
> Date: Thu Jan 22 09:55:31 2009 +0000
>
> sh: rework clocksource and sched_clock
>
> Rework and simplify the sched_clock and clocksource code. Instead
> of registering the clocksource in a shared file we move it into the
> tmu driver. Also, add code to handle sched_clock in the case of no
> clocksource.
>
> Signed-off-by: Magnus Damm <damm@igel.co.jp>
> Signed-off-by: Paul Mundt <lethal@linux-sh.org>
>
>

To test this further I reverted this commit and it does indeed seem to
fix the problem - the next line on the console in systems that boot (as
opposed to fail) is indeed one about the System Clock.

(I am now getting a lot of messages about failures of the udev script -
possibly because I have just turned a lot of debugging stuff on - in any
case the system does boot and works with that commit reverted).

I don't pretend to know why Magnus's patch is breaking the Dreamcast,
but I am pretty sure it is. Could it be reverted before being pushed
upstream while we wait for one that works?

Thanks

[ 0.000000] Linux version 2.6.29-rc7-00246-gc76acf0 (adrian@bossclass) (gcc version 4.2.2) #38 PREEMPT Sat Mar 21 23:20:59 GMT 2009
[ 0.000000] Boot params:
[ 0.000000] ... MOUNT_ROOT_RDONLY - 91f0c75f
[ 0.000000] ... RAMDISK_FLAGS - e1ff3018
[ 0.000000] ... ORIG_ROOT_DEV - 89093610
[ 0.000000] ... LOADER_TYPE - 3612e108
[ 0.000000] ... INITRD_START - 46088904
[ 0.000000] ... INITRD_SIZE - 6002306c
[ 0.000000] console [sercon0] enabled
[ 0.000000] Booting machvec: Sega Dreamcast
[ 0.000000] Node 0: start_pfn = 0xc000, low = 0xd000
[ 0.000000] Zone PFN ranges:
[ 0.000000] Normal 0x0000c000 -> 0x0000d000
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[1] active PFN ranges
[ 0.000000] 0: 0x0000c000 -> 0x0000d000
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 4064
[ 0.000000] Kernel command line: root=/dev/nfs rw nfsroot\x192.168.61.50:/home/adrian/debdist ip=dhcp earlyprintk=serial video=pvr2fb:640x480-32@60 console=ttySC1 panic=7
[ 0.000000] PID hash table entries: 64 (order: 6, 256 bytes)
[ 0.000000] Using tmu for system timer
[ 0.000000] Using 12.469 MHz high precision timer.
[ 0.009855] Console: colour dummy device 80x25
[ 0.014826] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.023379] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.027997] ... MAX_LOCK_DEPTH: 48
[ 0.032564] ... MAX_LOCKDEP_KEYS: 8191
[ 0.037341] ... CLASSHASH_SIZE: 4096
[ 0.042077] ... MAX_LOCKDEP_ENTRIES: 8192
[ 0.046883] ... MAX_LOCKDEP_CHAINS: 16384
[ 0.051787] ... CHAINHASH_SIZE: 8192
[ 0.056592] memory used by lock dependency info: 2335 kB
[ 0.062561] per task-struct memory footprint: 1152 bytes
[ 0.068609] Dentry cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.076255] Inode-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.086394] Memory: 9404k/16384k available (2276k kernel code, 773k data, 104k init)
[ 0.094955] PVR\x040205c1 CVR\0000000 PRR\0000000
[ 0.100260] I-cache : n_ways=1 n_sets%6 way_incr92
[ 0.106134] I-cache : entry_mask=0x00001fe0 alias_mask=0x00001000 n_aliases=2
[ 0.114022] D-cache : n_ways=1 n_setsQ2 way_incr\x16384
[ 0.119884] D-cache : entry_mask=0x00003fe0 alias_mask=0x00003000 n_aliases=4
[ 0.128712] SLUB: Genslabs\x10, HWalign2, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.137452] Calibrating delay loop (skipped)... 199.50 BogoMIPS PRESET (lpj99012)
[ 0.147811] Mount-cache hash table entries: 512
[ 0.162103] CPU: SH7750
[ 0.209214] net_namespace: 360 bytes
[ 0.216183] NET: Registered protocol family 16
[ 0.359931] bio: create slab <bio-0> at 0
[ 0.387586] Autoconfig PCI channel 0x8c2f6cf8
[ 0.392453] Scanning bus 00, I/O 0x01001600:0x01003600, Mem 0x01840000:0x01848000
[ 0.401502] 00:00.0 Class 0200: 11db:1234 (rev 10)
[ 0.406897] I/O at 0x01001600 [size=0x100]
[ 0.412330] Mem at 0x01840000 [size=0x100]
[ 0.422454] PCI: Fixing up device 0000:00:00.0
[ 0.494982] maple: bus core now registered
[ 0.501115] NET: Registered protocol family 2
[ 0.507560] IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.518585] TCP established hash table entries: 512 (order: 0, 4096 bytes)
[ 0.526564] TCP bind hash table entries: 512 (order: 2, 16384 bytes)
[ 0.534245] TCP: Hash tables configured (established 512 bind 512)
[ 0.541198] TCP reno registered
[ 0.547115] NET: Registered protocol family 1
[ 0.555960] maple : detected Dreamcast Controller: function 0x1: at (0, 0)
[ 0.563648] maple : no driver found
[ 0.573208] maple : detected Dreamcast Controller: function 0x1: at (1, 0)
[ 0.580963] maple : no driver found
[ 0.588013] maple : detected Keyboard: function 0x40: at (2, 0)
[ 0.594596] maple : no driver found
[ 0.602284] maple : detected Dreamcast Mouse: function 0x200: at (3, 0)
[ 0.609964] maple : no driver found
[ 0.620835] sq: Registering store queue API.
[ 0.635694] cpufreq: SuperH CPU frequency driver.
[ 0.641741] cpufreq: clock framework rate rounding not supported on this CPU.
[ 0.669573] HugeTLB registered 64 KB page size, pre-allocated 0 pages
[ 1.074440] msgmni has been set to 18
[ 1.094301] alg: No test for stdrng (krng)
[ 1.099987] io scheduler noop registered
[ 1.104430] io scheduler anticipatory registered (default)
[ 3.639032] SuperH SCI(F) driver initialized
[ 3.645264] sh-sci: ttySC0 at MMIO 0xffe00000 (irq = 23) is a sci
[ 3.657133] sh-sci: ttySC1 at MMIO 0xffe80000 (irq = 40) is a scif
ï¿½[ 3.664554] console handover: boot [sercon0] -> real [ttySC1]
[ 3.679176] 8139too Fast Ethernet driver 0.9.28
[ 3.692938] eth0: RealTek RTL8139 at 0xa1001700, 00:d0:f1:03:12:b9, IRQ 83
[ 3.704324] TCP cubic registered
[ 3.707730] NET: Registered protocol family 17
[ 3.731847] RPC: Registered udp transport module.
[ 3.736916] RPC: Registered tcp transport module.
[ 4.267055] eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
[ 5.276317] Sending DHCP requests ., OK
[ 5.288813] IP-Config: Got DHCP answer from 192.168.61.50, my address is 192.168.61.55
[ 5.303600] IP-Config: Complete:
[ 5.306947] device=eth0, addr\x192.168.61.55, mask%5.255.255.224, gw\x192.168.61.50,
[ 5.315652] host\x192.168.61.55, domain=, nis-domain=(none),
[ 5.322063] bootserver\x192.168.61.50, rootserver\x192.168.61.50, rootpath[ 5.331334] Looking up port of RPC 100003/2 on 192.168.61.50
[ 5.347340] Looking up port of RPC 100005/1 on 192.168.61.50
[ 5.383447] VFS: Mounted root (nfs filesystem) on device 0:13.
[ 5.391477] Freeing unused kernel memory: 104k freed
INIT: ï¿½version 2.86 bootingï¿½
ï¿½Starting the hotplug events dispatcher: udevd.
Synthesizing the initial hotplug events...udevd-event[862]: rename_netif: error changing netif name eth0 to eth1: Device or resource busy
done.
Waiting for /dev to be fully populated...udevd-event[1559]: udev_node_mknod: mknod(/dev/ttyz2, 020660, 3, 162) failed: No space left on device
udevd-event[1560]: udev_db_add_device: unable to create db link '/dev/.udev/db/devices@virtual@tty@ttyz3': No space left on device
udevd-event[1562]: udev_db_add_device: unable to create db link '/dev/.udev/db/devices@virtual@tty@ttyz4': No space left on device
udevd-event[1563]: udev_node_mknod: mknod(/dev/ttyz5, 020660, 3, 165) failed: No space left on device
udevd-event[1564]: udev_db_add_device: unable to create db link '/dev/.udev/db/devices@virtual@tty@ttyz6': No space left on device
udevd-event[1565]: udev_node_mknod: mknod(/dev/ttyz7, 020660, 3, 167) failed: No space left on device
udevd-event[1566]: udev_node_mknod: mknod(/dev/ttyz8, 020660, 3, 168) failed: No space left on device
udevd-event[1567]: udev_node_mknod: mknod(/dev/ttyz9, 020660, 3, 169) failed: No space left on device
udevd-event[1568]: udev_db_add_device: unable to create db link '/dev/.udev/db/devices@virtual@tty@ttyza': No space left on device
udevd-event[1569]: udev_node_mknod: mknod(/dev/ttyzb, 020660, 3, 171) failed: No space left on device
udevd-event[1570]: udev_node_mknod: mknod(/dev/ttyzc, 020660, 3, 172) failed: No space left on device
udevd-event[1571]: udev_node_mknod: mknod(/dev/ttyzd, 020660, 3, 173) failed: No space left on device
udevd-event[1572]: udev_node_mknod: mknod(/dev/ttyze, 020660, 3, 174) failed: No space left on device
done.
udevd-event[1573]: udev_node_mknod: mknod(/dev/ttyzf, 020660, 3, 175) failed: No space left on device
udevd-event[1574]: udev_node_mknod: mknod(/dev/vcs, 020660, 7, 0) failed: No space left on device
udevd-event[1575]: udev_node_mknod: mknod(/dev/vcsa, 020660, 7, 128) failed: No space left on device
Setting the system clock..
Cannot access the Hardware Clock via any known method.
Use the --debug option to see the details of our search for an access method.
Loading kernel modules...FATAL: Could not load /lib/modules/2.6.29-rc7-00246-gc76acf0/modules.dep: No such file or directory
Loading device-mapper support.
Setting kernel variables...done.
Mounting local filesystems...done.
Activating swapfile swap...done.
Setting up networking....
Setting up ALSA...done (none loaded).
INIT: ï¿½Entering runlevel: 2ï¿½
ï¿½Starting system log daemon: syslogdmknod: `/dev/xconsole': No space left on device
chown: cannot access `/dev/xconsole': No such file or directory
syslogd: /dev/xconsole: No such file or directory
syslogd: cannot create /dev/log: No space left on device
.
Starting kernel log daemon: klogd.
Starting mouse interface server: gpm/etc/rc2.d/S20gpm: line 65: /dev/input/mice: No such file or directory
/etc/rc2.d/S20gpm: line 65: /dev/input/mice: No such file or directory
/etc/rc2.d/S20gpm: line 65: /dev/input/mice: No such file or directory
udevd-event[1817]: udev_node_mknod: mknod(/dev/vcs1, 020660, 7, 1) failed: No space left on device
udevd-event[1818]: udev_node_mknod: mknod(/dev/vcsa1, 020660, 7, 129) failed: No space left on device
.
Starting NBD client process: FATAL: Could not load /lib/modules/2.6.29-rc7-00246-gc76acf0/modules.dep: No such file or directory
Connecting...Activating...
nbd-client.
* Not starting internet superserver: no services enabled.
Starting OpenBSD Secure Shell server: sshdmodprobe: FATAL: Could not load /lib/modules/2.6.29-rc7-00246-gc76acf0/modules.dep: No such file or directory

.
modprobe: FATAL: Could not load /lib/modules/2.6.29-rc7-00246-gc76acf0/modules.dep: No such file or directory

Starting NTP server: ntpdmodprobe: FATAL: Could not load /lib/modules/2.6.29-rc7-00246-gc76acf0/modules.dep: No such file or directory

.
[ 91.359819] warning: `ntpd' uses 32-bit capabilities (legacy support in use)
Starting periodic command scheduler: crond.
ï¿½ï¿½udevd-event[1905]: udev_node_mknod: mknod(/dev/vcs1, 020660, 7, 1) failed: No space left on device
udevd-event[1906]: udev_node_mknod: mknod(/dev/vcsa1, 020660, 7, 129) failed: No space left on device
udevd-event[1907]: udev_node_mknod: mknod(/dev/vcs2, 020660, 7, 2) failed: No space left on device
udevd-event[1908]: udev_node_mknod: mknod(/dev/vcsa2, 020660, 7, 130) failed: No space left on device

Debian GNU/Linux 4.0 dreamy ttySC1

dreamy login:

--
To unsubscribe from this list: send the line "unsubscribe linux-sh" in
the body of a message to majordomo@vger.kernel.org
More majordomo info at http://vger.kernel.org/majordomo-info.html