[Parted-maintainers] Bug#530909: Wrong message about device being busy

Martin Michlmayr tbm at cyrius.com
Mon Oct 12 20:33:59 UTC 2009


I filed this bug against parted-udeb and didn't realize this meant it
wouldn't show up on debian-boot.  This is definitely something for
debian-boot (not sure if it's a parted, partman or another problem),
so here again:

With the daily image I somtimes get:

  │ Error informing the kernel about modifications to partition /dev/sda2   │
  │ -- Device or resource busy.  This means Linux won't know about any      │
  │ changes you made to /dev/sda2 until you reboot -- so you shouldn't      │
  │ mount it or use it in any way before rebooting.                         │
  │                                                                         │
  │ ERROR!!!                                                                │
  │                                                                         │
  │                                Ignore                                   │
  │                                Cancel                                   │
  │                                                                         │
  │     <Go Back>                                                           │


I reported this issue in May and it's still there.  Other people have
seen it too.  It doesn't matter whether you install to SATA, USB or MMC.
This error doesn't show up all the time, but it shows up quite regularly.
This is definitely a regression from lenny.

What's not great about this error:

 - It's probably bogus.  At least I cannot see any errors in the logs.

 - The "ERROR!!!" string that doesn't really say anything at all.

 - The choice "Ignore" and "Cancel".  I guess "Ignore" is "go ahead"
   and "Cancel" is "go back", but there's a "go back" already.

 - When I press "Go Back", it actually goes ahead to format the
   partition (which worked fine).  In fact, all of the choices do
   the same (go ahead).

I didn't capture logs when this happened, but Nils Humenko just sent
me some (attached).
-- 
Martin Michlmayr
http://www.cyrius.com/
-------------- next part --------------
Oct 12 17:16:26 syslogd started: BusyBox v1.14.2
Oct 12 17:16:26 kernel: klogd started: BusyBox v1.14.2 (Debian 1:1.14.2-2)
Oct 12 17:16:26 kernel: [    0.000000] Initializing cgroup subsys cpuset
Oct 12 17:16:26 kernel: [    0.000000] Initializing cgroup subsys cpu
Oct 12 17:16:26 kernel: [    0.000000] Linux version 2.6.30-2-kirkwood (Debian 2.6.30-8) (waldi at debian.org) (gcc version 4.3.4 (Debian 4.3.4-3) ) #1 Sun Sep 27 22:57:55 UTC 2009
Oct 12 17:16:26 kernel: [    0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=00053977
Oct 12 17:16:26 kernel: [    0.000000] CPU: VIVT data cache, VIVT instruction cache
Oct 12 17:16:26 kernel: [    0.000000] Machine: Marvell SheevaPlug Reference Board
Oct 12 17:16:26 kernel: [    0.000000] Ignoring unrecognised tag 0x41000403
Oct 12 17:16:26 kernel: [    0.000000] Memory policy: ECC disabled, Data cache writeback
Oct 12 17:16:26 kernel: [    0.000000] On node 0 totalpages: 131072
Oct 12 17:16:26 kernel: [    0.000000] free_area_init_node: node 0, pgdat c0386954, node_mem_map c03f0000
Oct 12 17:16:26 kernel: [    0.000000]   Normal zone: 1024 pages used for memmap
Oct 12 17:16:26 kernel: [    0.000000]   Normal zone: 0 pages reserved
Oct 12 17:16:26 kernel: [    0.000000]   Normal zone: 130048 pages, LIFO batch:31
Oct 12 17:16:26 kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 130048
Oct 12 17:16:26 kernel: [    0.000000] Kernel command line: console=ttyS0,115200n8 base-installer/initramfs-tools/driver-policy=most
Oct 12 17:16:26 kernel: [    0.000000] NR_IRQS:114
Oct 12 17:16:26 kernel: [    0.000000] PID hash table entries: 2048 (order: 11, 8192 bytes)
Oct 12 17:16:26 kernel: [    0.000000] Console: colour dummy device 80x30
Oct 12 17:16:26 kernel: [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
Oct 12 17:16:26 kernel: [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
Oct 12 17:16:26 kernel: [    0.010000] Memory: 256MB 256MB = 512MB total
Oct 12 17:16:26 kernel: [    0.010000] Memory: 512896KB available (3232K code, 548K data, 120K init, 0K highmem)
Oct 12 17:16:26 kernel: [    0.010000] Calibrating delay loop... 1192.75 BogoMIPS (lpj=5963776)
Oct 12 17:16:26 kernel: [    0.250000] Security Framework initialized
Oct 12 17:16:26 kernel: [    0.250000] SELinux:  Disabled at boot.
Oct 12 17:16:26 kernel: [    0.250000] Mount-cache hash table entries: 512
Oct 12 17:16:26 kernel: [    0.250000] Initializing cgroup subsys ns
Oct 12 17:16:26 kernel: [    0.250000] Initializing cgroup subsys cpuacct
Oct 12 17:16:26 kernel: [    0.250000] Initializing cgroup subsys devices
Oct 12 17:16:26 kernel: [    0.250000] Initializing cgroup subsys freezer
Oct 12 17:16:26 kernel: [    0.250000] Initializing cgroup subsys net_cls
Oct 12 17:16:26 kernel: [    0.250000] CPU: Testing write buffer coherency: ok
Oct 12 17:16:26 kernel: [    0.250000] net_namespace: 1076 bytes
Oct 12 17:16:26 kernel: [    0.250000] regulator: core version 0.5
Oct 12 17:16:26 kernel: [    0.250000] NET: Registered protocol family 16
Oct 12 17:16:26 kernel: [    0.250000] Kirkwood: MV88F6281-A0, TCLK=200000000.
Oct 12 17:16:26 kernel: [    0.250000] Feroceon L2: Enabling L2
Oct 12 17:16:26 kernel: [    0.250000] Feroceon L2: Cache support initialised.
Oct 12 17:16:26 kernel: [    0.250000] initial MPP regs: 01111111 11113322 00001111 00100000 00000000 00000000 00000000
Oct 12 17:16:26 kernel: [    0.250000]   final MPP regs: 01111111 11113322 00001111 00000000 00000000 00000000 00000000
Oct 12 17:16:26 kernel: [    0.260000] bio: create slab <bio-0> at 0
Oct 12 17:16:26 kernel: [    0.260000] NET: Registered protocol family 2
Oct 12 17:16:26 kernel: [    0.260000] IP route cache hash table entries: 16384 (order: 4, 65536 bytes)
Oct 12 17:16:26 kernel: [    0.260000] TCP established hash table entries: 65536 (order: 7, 524288 bytes)
Oct 12 17:16:26 kernel: [    0.260000] TCP bind hash table entries: 65536 (order: 6, 262144 bytes)
Oct 12 17:16:26 kernel: [    0.260000] TCP: Hash tables configured (established 65536 bind 65536)
Oct 12 17:16:26 kernel: [    0.260000] TCP reno registered
Oct 12 17:16:26 kernel: [    0.260000] NET: Registered protocol family 1
Oct 12 17:16:26 kernel: [    0.260000] Trying to unpack rootfs image as initramfs...
Oct 12 17:16:26 kernel: [    0.270000] Switched to high resolution mode on CPU 0
Oct 12 17:16:26 kernel: [    0.450000] Freeing initrd memory: 2628K
Oct 12 17:16:26 kernel: [    0.450000] NetWinder Floating Point Emulator V0.97 (double precision)
Oct 12 17:16:26 kernel: [    0.450000] audit: initializing netlink socket (disabled)
Oct 12 17:16:26 kernel: [    0.450000] type=2000 audit(0.450:1): initialized
Oct 12 17:16:26 kernel: [    0.450000] VFS: Disk quotas dquot_6.5.2
Oct 12 17:16:26 kernel: [    0.450000] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Oct 12 17:16:26 kernel: [    0.450000] JFFS2 version 2.2. (NAND) (SUMMARY)  ?? 2001-2006 Red Hat, Inc.
Oct 12 17:16:26 kernel: [    0.450000] msgmni has been set to 1007
Oct 12 17:16:26 kernel: [    0.450000] alg: No test for stdrng (krng)
Oct 12 17:16:26 kernel: [    0.450000] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
Oct 12 17:16:26 kernel: [    0.450000] io scheduler noop registered
Oct 12 17:16:26 kernel: [    0.450000] io scheduler anticipatory registered
Oct 12 17:16:26 kernel: [    0.450000] io scheduler deadline registered
Oct 12 17:16:26 kernel: [    0.450000] io scheduler cfq registered (default)
Oct 12 17:16:26 kernel: [    0.460000] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
Oct 12 17:16:26 kernel: [    0.460000] serial8250.0: ttyS0 at MMIO 0xf1012000 (irq = 33) is a 16550A
Oct 12 17:16:26 kernel: [    0.460000] console [ttyS0] enabled
Oct 12 17:16:26 kernel: [    0.780000] brd: module loaded
Oct 12 17:16:26 kernel: [    0.790000] NAND device: Manufacturer ID: 0xad, Chip ID: 0xdc (Hynix NAND 512MiB 3,3V 8-bit)
Oct 12 17:16:26 kernel: [    0.800000] Scanning device for bad blocks
Oct 12 17:16:26 kernel: [    0.950000] Creating 3 MTD partitions on "orion_nand":
Oct 12 17:16:26 kernel: [    0.950000] 0x000000000000-0x000000100000 : "u-boot"
Oct 12 17:16:26 kernel: [    0.960000] uncorrectable error : <5>0x000000100000-0x000000500000 : "uImage"
Oct 12 17:16:26 kernel: [    0.970000] ftl_cs: FTL header not found.
Oct 12 17:16:26 kernel: [    0.970000] 0x000000500000-0x000020000000 : "root"
Oct 12 17:16:26 kernel: [    0.980000] ftl_cs: FTL header not found.
Oct 12 17:16:26 kernel: [    0.980000] mice: PS/2 mouse device common for all mice
Oct 12 17:16:26 kernel: [    0.990000] rtc-mv rtc-mv: rtc core: registered rtc-mv as rtc0
Oct 12 17:16:26 kernel: [    0.990000] i2c /dev entries driver
Oct 12 17:16:26 kernel: [    1.000000] cpuidle: using governor ladder
Oct 12 17:16:26 kernel: [    1.000000] cpuidle: using governor menu
Oct 12 17:16:26 kernel: [    1.010000] Registered led device: plug:green:health
Oct 12 17:16:26 kernel: [    1.010000] mv_xor_shared mv_xor_shared.0: Marvell shared XOR driver
Oct 12 17:16:26 kernel: [    1.020000] mv_xor_shared mv_xor_shared.1: Marvell shared XOR driver
Oct 12 17:16:26 kernel: [    1.070000] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
Oct 12 17:16:26 kernel: [    1.110000] mv_xor mv_xor.1: Marvell XOR: ( xor fill cpy )
Oct 12 17:16:26 kernel: [    1.150000] mv_xor mv_xor.2: Marvell XOR: ( xor cpy )
Oct 12 17:16:26 kernel: [    1.190000] mv_xor mv_xor.3: Marvell XOR: ( xor fill cpy )
Oct 12 17:16:26 kernel: [    1.190000] TCP cubic registered
Oct 12 17:16:26 kernel: [    1.200000] NET: Registered protocol family 17
Oct 12 17:16:26 kernel: [    1.200000] Gating clock of unused units
Oct 12 17:16:26 kernel: [    1.200000] before: 0x00df03dd
Oct 12 17:16:26 kernel: [    1.200000]  after: 0x00c501d9
Oct 12 17:16:26 kernel: [    1.200000] registered taskstats version 1
Oct 12 17:16:26 kernel: [    1.200000] rtc-mv rtc-mv: setting system clock to 2009-10-12 17:16:25 UTC (1255367785)
Oct 12 17:16:26 kernel: [    1.210000] Freeing init memory: 120K
Oct 12 17:16:26 kernel: [    1.330000] MV-643xx 10/100/1000 ethernet driver version 1.4
Oct 12 17:16:26 kernel: [    1.330000] mv643xx_eth smi: probed
Oct 12 17:16:26 kernel: [    1.390000] net eth0: port 0 with MAC address 00:50:43:43:2d:29
Oct 12 17:16:26 kernel: [    1.450000] mmc0: mvsdio driver initialized, lacking card detect (fall back to polling)
Oct 12 17:16:26 kernel: [    1.600000] mmc0: host does not support reading read-only switch. assuming write-enable.
Oct 12 17:16:26 kernel: [    1.610000] mmc0: new high speed SDHC card at address bc76
Oct 12 17:16:26 kernel: [    1.710000] mmcblk0: mmc0:bc76 SU04G 3.69 GiB 
Oct 12 17:16:26 kernel: [    1.710000]  mmcblk0: p1 p2
Oct 12 17:16:28 init: starting pid 447, tty '': '/sbin/reopen-console /sbin/debian-installer'
Oct 12 17:16:28 init: starting pid 453, tty '/dev/tty4': '/usr/bin/tail -f /var/log/syslog'
Oct 12 17:16:28 debconf: Setting debconf/language to en
Oct 12 17:16:28 main-menu[459]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Oct 12 17:16:28 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:16:28 main-menu[459]: INFO: Menu item 'localechooser' selected
Oct 12 17:16:28 debconf: Setting debconf/language to 
Oct 12 17:16:31 localechooser: info: Language = 'en'
Oct 12 17:16:31 localechooser: info: line=en;0;US;en_US.UTF-8;;console-setup
Oct 12 17:16:31 localechooser: info: Set debian-installer/language = 'en'
Oct 12 17:16:31 localechooser: info: Set debian-installer/locale = 'en_US.UTF-8'
Oct 12 17:16:31 localechooser: info: Set debian-installer/fallbacklocale = 'en_US.UTF-8'
Oct 12 17:16:31 localechooser: info: Default country = 'US'
Oct 12 17:16:31 localechooser: info: Set debian-installer/consoledisplay = 'console-setup'
Oct 12 17:16:31 debconf: Setting debconf/language to en
Oct 12 17:16:34 localechooser: info: Set debian-installer/country = 'GB'
Oct 12 17:16:34 localechooser: info: Set debian-installer/locale = 'en_GB.UTF-8'
Oct 12 17:16:34 localechooser: info: Selected locale (debian-installer/locale) = 'en_GB.UTF-8'
Oct 12 17:16:34 main-menu[459]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Oct 12 17:16:34 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:16:34 main-menu[459]: INFO: Menu item 'kbd-chooser' selected
Oct 12 17:16:34 main-menu[459]: INFO: Falling back to the package description for console-keymaps-at
Oct 12 17:16:34 main-menu[459]: INFO: Falling back to the package description for console-keymaps-at
Oct 12 17:16:34 kbd-chooser[556]: DEBUG: Mounting usbdevfs to look for kbd
Oct 12 17:16:34 kbd-chooser[556]: DEBUG: Adding generic entry for AT keymaps
Oct 12 17:16:34 kbd-chooser[556]: INFO: keyboard type at: present: false 
Oct 12 17:16:34 kbd-chooser[556]: INFO: keyboard type at: present: unknown  
Oct 12 17:16:34 kbd-chooser[556]: INFO: Setting debian-installer/serial-console to true
Oct 12 17:16:34 kbd-chooser[556]: INFO: Setting debian-installer/uml-console to false
Oct 12 17:16:34 kbd-chooser[556]: INFO: kbd-chooser: arch no-keyboard selected
Oct 12 17:16:34 kbd-chooser[556]: INFO: kbd-chooser: not setting keymap
Oct 12 17:16:34 main-menu[459]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Oct 12 17:16:34 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:16:34 main-menu[459]: INFO: Menu item 'ethdetect' selected
Oct 12 17:16:34 net/hw-detect.hotplug: Detected hotpluggable network interface eth0
Oct 12 17:16:35 net/hw-detect.hotplug: Detected hotpluggable network interface lo
Oct 12 17:16:35 apt-install: Queueing package udev for later installation
Oct 12 17:16:37 check-missing-firmware: no missing firmware in /tmp/missing-firmware
Oct 12 17:16:38 check-missing-firmware: no missing firmware in /tmp/missing-firmware
Oct 12 17:16:38 main-menu[459]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Oct 12 17:16:38 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:16:38 main-menu[459]: INFO: Menu item 'netcfg' selected
Oct 12 17:16:38 dhclient: Internet Systems Consortium DHCP Client V3.1.3
Oct 12 17:16:38 dhclient: Copyright 2004-2009 Internet Systems Consortium.
Oct 12 17:16:38 dhclient: All rights reserved.
Oct 12 17:16:38 dhclient: For info, please visit https://www.isc.org/software/dhcp/
Oct 12 17:16:38 dhclient: 
Oct 12 17:16:39 dhclient: Listening on LPF/eth0/00:50:43:43:2d:29
Oct 12 17:16:39 dhclient: Sending on   LPF/eth0/00:50:43:43:2d:29
Oct 12 17:16:39 dhclient: Sending on   Socket/fallback
Oct 12 17:16:40 kernel: [   16.550000] eth0: link up, 1000 Mb/s, full duplex, flow control disabled
Oct 12 17:16:42 dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 3
Oct 12 17:16:45 dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 6
Oct 12 17:16:51 dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 15
Oct 12 17:16:51 dhclient: DHCPOFFER from 192.168.2.1
Oct 12 17:16:51 dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67
Oct 12 17:16:51 dhclient: DHCPACK from 192.168.2.1
Oct 12 17:16:51 dhclient: bound to 192.168.2.60 -- renewal in 1523 seconds.
Oct 12 17:17:01 netcfg[1017]: INFO: Detected eth0 as a hotpluggable device
Oct 12 17:17:01 main-menu[459]: (process:1016): Internet Systems Consortium DHCP Client V3.1.3
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): Copyright 2004-2009 Internet Systems Consortium.
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): All rights reserved.
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): For info, please visit https://www.isc.org/software/dhcp/
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): Listening on LPF/eth0/00:50:43:43:2d:29
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): Sending on   LPF/eth0/00:50:43:43:2d:29
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): Sending on   Socket/fallback
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 3
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 6
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 15
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): DHCPOFFER from 192.168.2.1
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): DHCPREQUEST on eth0 to 255.255.255.255 port 67
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): DHCPACK from 192.168.2.1
Oct 12 17:17:01 main-menu[459]: (process:1016): 
Oct 12 17:17:01 main-menu[459]: (process:1016): bound to 192.168.2.60 -- renewal in 1523 seconds.
Oct 12 17:17:01 main-menu[459]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Oct 12 17:17:01 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:17:01 main-menu[459]: INFO: Falling back to the package description for auto-install
Oct 12 17:17:01 main-menu[459]: INFO: Falling back to the package description for ai-choosers
Oct 12 17:17:01 main-menu[459]: INFO: Menu item 'choose-mirror' selected
Oct 12 17:17:01 anna-install: Queueing udeb apt-mirror-setup for later installation
Oct 12 17:17:05 choose-mirror[1071]: DEBUG: command: wget -q http://ftp.de.debian.org/debian//dists/squeeze/Release -O - | grep ^Suite: | cut -d' ' -f 2
Oct 12 17:17:06 choose-mirror[1071]: DEBUG: command: wget -q http://ftp.de.debian.org/debian//dists/testing/Release -O - | grep ^Codename: | cut -d' ' -f 2
Oct 12 17:17:07 choose-mirror[1071]: INFO: codename set to: squeeze
Oct 12 17:17:07 choose-mirror[1071]: DEBUG: command: wget -q http://ftp.de.debian.org/debian//dists/testing/main/binary-armel/Release -O - | grep Architecture
Oct 12 17:17:07 anna-install: Queueing udeb squeeze-support for later installation
Oct 12 17:17:07 main-menu[459]: DEBUG: resolver (libc6-udeb): package doesn't exist (ignored)
Oct 12 17:17:07 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:17:07 main-menu[459]: INFO: Falling back to the package description for auto-install
Oct 12 17:17:07 main-menu[459]: INFO: Falling back to the package description for ai-choosers
Oct 12 17:17:07 main-menu[459]: INFO: Menu item 'download-installer' selected
Oct 12 17:17:08 net-retriever: gpgv: Signature made Mon Oct 12 15:27:24 2009 UTC using RSA key ID 55BE302B
Oct 12 17:17:08 net-retriever: gpgv: 
Oct 12 17:17:08 net-retriever: Good signature from "Debian Archive Automatic Signing Key (5.0/lenny) <ftpmaster at debian.org>"
Oct 12 17:17:08 net-retriever: 
Oct 12 17:17:09 anna[1113]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:17:09 anna[1113]: DEBUG: resolver (xfs-modules): package doesn't exist (ignored)
Oct 12 17:17:09 anna[1113]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:17:09 anna[1113]: DEBUG: resolver (xfs-modules): package doesn't exist (ignored)
Oct 12 17:17:09 anna[1113]: DEBUG: retrieving apt-mirror-setup 1:0.42
Oct 12 17:17:10 anna[1113]: DEBUG: retrieving apt-setup-udeb 1:0.42
Oct 12 17:17:11 anna[1113]: DEBUG: retrieving base-installer 1.102
Oct 12 17:17:12 anna[1113]: DEBUG: retrieving bootstrap-base 1.102
Oct 12 17:17:13 anna[1113]: DEBUG: retrieving cdrom-core-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:14 anna[1113]: DEBUG: retrieving clock-setup 0.100
Oct 12 17:17:15 anna[1113]: DEBUG: retrieving debootstrap-udeb 1.0.20
Oct 12 17:17:15 anna[1113]: DEBUG: retrieving di-utils-mapdevfs 1.71
Oct 12 17:17:15 anna[1113]: DEBUG: retrieving disk-detect 1.73
Oct 12 17:17:16 anna[1113]: DEBUG: retrieving e2fsprogs-udeb 1.41.9-1
Oct 12 17:17:17 anna[1113]: DEBUG: retrieving ext2-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:18 anna[1113]: DEBUG: retrieving ext3-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:19 anna[1113]: DEBUG: retrieving ext4-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:20 anna[1113]: DEBUG: retrieving fat-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:20 anna[1113]: DEBUG: retrieving finish-install 2.23
Oct 12 17:17:21 anna[1113]: DEBUG: retrieving flash-kernel-installer 2.23
Oct 12 17:17:22 anna[1113]: DEBUG: retrieving isofs-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:22 anna[1113]: DEBUG: retrieving jfs-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:23 anna[1113]: DEBUG: retrieving jfsutils-udeb 1.1.12-2
Oct 12 17:17:24 anna[1113]: DEBUG: retrieving kickseed-common 0.53
Oct 12 17:17:25 anna[1113]: DEBUG: retrieving libbsd0-udeb 0.1.6-1
Oct 12 17:17:25 anna[1113]: DEBUG: retrieving libc6-udeb 2.9-27
Oct 12 17:17:30 anna[1113]: DEBUG: retrieving libdevmapper1.02.1-udeb 2:1.02.38-2
Oct 12 17:17:31 anna[1113]: DEBUG: retrieving libparted1.8-udeb 1.8.8.git.2009.07.19-5
Oct 12 17:17:32 anna[1113]: DEBUG: retrieving libpcre3-udeb 7.8-3
Oct 12 17:17:33 anna[1113]: DEBUG: retrieving loop-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:33 anna[1113]: DEBUG: retrieving md-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:34 anna[1113]: DEBUG: retrieving mdadm-udeb 3.0-3.1
Oct 12 17:17:35 anna[1113]: DEBUG: retrieving mkreiserfs-udeb 1:3.6.21-1
Oct 12 17:17:36 anna[1113]: DEBUG: retrieving nic-usb-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:38 anna[1113]: DEBUG: retrieving nls-core-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:38 anna[1113]: DEBUG: retrieving nobootloader 1.25
Oct 12 17:17:39 anna[1113]: DEBUG: retrieving partman-auto 89
Oct 12 17:17:40 anna[1113]: DEBUG: retrieving partman-auto-raid 15
Oct 12 17:17:41 anna[1113]: DEBUG: retrieving partman-base 135
Oct 12 17:17:42 anna[1113]: DEBUG: retrieving partman-basicfilesystems 63
Oct 12 17:17:44 anna[1113]: DEBUG: retrieving partman-basicmethods 43
Oct 12 17:17:44 anna[1113]: DEBUG: retrieving partman-ext3 58
Oct 12 17:17:45 anna[1113]: DEBUG: retrieving partman-jfs 30
Oct 12 17:17:46 anna[1113]: DEBUG: retrieving partman-md 48
Oct 12 17:17:47 anna[1113]: DEBUG: retrieving partman-partitioning 72
Oct 12 17:17:49 anna[1113]: DEBUG: retrieving partman-reiserfs 45
Oct 12 17:17:50 anna[1113]: DEBUG: retrieving partman-target 64
Oct 12 17:17:51 anna[1113]: DEBUG: retrieving partman-utils 135
Oct 12 17:17:51 anna[1113]: DEBUG: retrieving partman-xfs 44
Oct 12 17:17:52 anna[1113]: DEBUG: retrieving pkgsel 0.25
Oct 12 17:17:53 anna[1113]: DEBUG: retrieving rdate-udeb 1:1.2-4
Oct 12 17:17:53 anna[1113]: DEBUG: retrieving reiserfs-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:54 anna[1113]: DEBUG: retrieving sata-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:55 anna[1113]: DEBUG: retrieving scsi-core-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:56 anna[1113]: DEBUG: retrieving tzsetup-udeb 1:0.26
Oct 12 17:17:57 anna[1113]: DEBUG: retrieving usb-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:57 anna[1113]: DEBUG: retrieving usb-storage-modules-2.6.30-2-kirkwood-di 1.39
Oct 12 17:17:58 anna[1113]: DEBUG: retrieving user-setup-udeb 1.28
Oct 12 17:17:59 anna[1113]: DEBUG: retrieving xfsprogs-udeb 3.0.4
Oct 12 17:18:02 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:18:02 main-menu[459]: INFO: Falling back to the package description for auto-install
Oct 12 17:18:02 main-menu[459]: INFO: Falling back to the package description for ai-choosers
Oct 12 17:18:02 main-menu[459]: INFO: Menu item 'clock-setup' selected
Oct 12 17:18:07 clock-setup: Mon Oct 12 17:18:07 UTC 2009
Oct 12 17:18:07 clock-setup: rdate: adjust local clock by 1.684110 seconds
Oct 12 17:18:07 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:18:07 main-menu[459]: INFO: Falling back to the package description for auto-install
Oct 12 17:18:07 main-menu[459]: INFO: Falling back to the package description for ai-choosers
Oct 12 17:18:07 main-menu[459]: INFO: Menu item 'disk-detect' selected
Oct 12 17:18:07 net/hw-detect.hotplug: Detected hotpluggable network interface eth0
Oct 12 17:18:07 kernel: [  101.820000] usbcore: registered new interface driver usbfs
Oct 12 17:18:07 kernel: [  101.820000] usbcore: registered new interface driver hub
Oct 12 17:18:07 net/hw-detect.hotplug: Detected hotpluggable network interface lo
Oct 12 17:18:08 kernel: [  102.030000] usbcore: registered new device driver usb
Oct 12 17:18:08 kernel: [  102.040000] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Oct 12 17:18:08 kernel: [  102.040000] orion-ehci orion-ehci.0: Marvell Orion EHCI
Oct 12 17:18:08 kernel: [  102.040000] orion-ehci orion-ehci.0: new USB bus registered, assigned bus number 1
Oct 12 17:18:08 kernel: [  102.070000] orion-ehci orion-ehci.0: irq 19, io mem 0xf1050000
Oct 12 17:18:08 kernel: [  102.090000] orion-ehci orion-ehci.0: USB 2.0 started, EHCI 1.00
Oct 12 17:18:08 kernel: [  102.090000] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Oct 12 17:18:08 kernel: [  102.090000] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Oct 12 17:18:08 kernel: [  102.090000] usb usb1: Product: Marvell Orion EHCI
Oct 12 17:18:08 kernel: [  102.090000] usb usb1: Manufacturer: Linux 2.6.30-2-kirkwood ehci_hcd
Oct 12 17:18:08 kernel: [  102.090000] usb usb1: SerialNumber: orion-ehci.0
Oct 12 17:18:08 kernel: [  102.090000] usb usb1: configuration #1 chosen from 1 choice
Oct 12 17:18:08 kernel: [  102.090000] hub 1-0:1.0: USB hub found
Oct 12 17:18:08 kernel: [  102.090000] hub 1-0:1.0: 1 port detected
Oct 12 17:18:08 hw-detect: Detected module 'usb-storage' for 'USB storage'
Oct 12 17:18:08 kernel: [  102.350000] SCSI subsystem initialized
Oct 12 17:18:08 hw-detect: insmod /lib/modules/2.6.30-2-kirkwood/kernel/drivers/scsi/scsi_mod.ko 
Oct 12 17:18:08 hw-detect: insmod /lib/modules/2.6.30-2-kirkwood/kernel/drivers/usb/storage/usb-storage.ko 
Oct 12 17:18:08 kernel: [  102.380000] Initializing USB Mass Storage driver...
Oct 12 17:18:08 kernel: [  102.380000] usbcore: registered new interface driver usb-storage
Oct 12 17:18:08 kernel: [  102.380000] USB Mass Storage support registered.
Oct 12 17:18:08 kernel: [  102.420000] usb 1-1: new high speed USB device using orion-ehci and address 2
Oct 12 17:18:08 apt-install: Queueing package usbutils for later installation
Oct 12 17:18:08 kernel: [  102.570000] usb 1-1: New USB device found, idVendor=090c, idProduct=1000
Oct 12 17:18:08 kernel: [  102.570000] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Oct 12 17:18:08 kernel: [  102.570000] usb 1-1: Product: USB DISK
Oct 12 17:18:08 kernel: [  102.570000] usb 1-1: Manufacturer: SMI Corporation
Oct 12 17:18:08 kernel: [  102.570000] usb 1-1: SerialNumber: AA04012700008697
Oct 12 17:18:08 kernel: [  102.570000] usb 1-1: configuration #1 chosen from 1 choice
Oct 12 17:18:08 kernel: [  102.580000] scsi0 : SCSI emulation for USB Mass Storage devices
Oct 12 17:18:08 kernel: [  102.580000] usb-storage: device found at 2
Oct 12 17:18:08 kernel: [  102.580000] usb-storage: waiting for device to settle before scanning
Oct 12 17:18:10 check-missing-firmware: no missing firmware in /tmp/missing-firmware
Oct 12 17:18:11 check-missing-firmware: no missing firmware in /tmp/missing-firmware
Oct 12 17:18:11 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:18:11 main-menu[459]: INFO: Falling back to the package description for auto-install
Oct 12 17:18:11 main-menu[459]: INFO: Falling back to the package description for ai-choosers
Oct 12 17:18:11 main-menu[459]: INFO: Menu item 'partman-base' selected
Oct 12 17:18:11 anna-install: Installing partman-auto-lvm
Oct 12 17:18:11 anna[3204]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:18:11 anna[3204]: DEBUG: retrieving lvm2-udeb 2.02.53-2
Oct 12 17:18:13 anna[3204]: DEBUG: retrieving partman-auto-lvm 33
Oct 12 17:18:13 kernel: [  107.580000] usb-storage: device scan complete
Oct 12 17:18:13 kernel: [  107.580000] scsi 0:0:0:0: Direct-Access     SMI      USB DISK         1100 PQ: 0 ANSI: 0 CCS
Oct 12 17:18:13 kernel: [  107.600000] Driver 'sd' needs updating - please use bus_type methods
Oct 12 17:18:13 kernel: [  107.600000] sd 0:0:0:0: [sda] 7864320 512-byte hardware sectors: (4.02 GB/3.75 GiB)
Oct 12 17:18:13 kernel: [  107.600000] sd 0:0:0:0: [sda] Write Protect is off
Oct 12 17:18:13 kernel: [  107.600000] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Oct 12 17:18:13 kernel: [  107.600000] sd 0:0:0:0: [sda] Assuming drive cache: write through
Oct 12 17:18:13 kernel: [  107.610000] sd 0:0:0:0: [sda] Assuming drive cache: write through
Oct 12 17:18:13 kernel: [  107.610000]  sda: sda1 sda2 sda3 < sda5 >
Oct 12 17:18:13 kernel: [  107.610000] sd 0:0:0:0: [sda] Attached SCSI removable disk
Oct 12 17:18:14 anna[3204]: DEBUG: retrieving partman-lvm 68
Oct 12 17:18:16 anna-install: Installing partman-auto-crypto
Oct 12 17:18:16 anna[3319]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:18:16 anna[3319]: DEBUG: retrieving partman-auto-crypto 12
Oct 12 17:18:17 anna[3319]: DEBUG: retrieving partman-crypto 39
Oct 12 17:18:19 kernel: [  113.650000] JFS: nTxBlock = 4030, nTxLock = 32243
Oct 12 17:18:19 kernel: [  113.950000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
Oct 12 17:18:19 md-devices: mdadm: No arrays found in config file or automatically
Oct 12 17:18:19 kernel: [  114.010000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
Oct 12 17:18:20 kernel: [  114.140000] device-mapper: uevent: version 1.0.3
Oct 12 17:18:20 kernel: [  114.140000] device-mapper: ioctl: 4.14.0-ioctl (2008-04-23) initialised: dm-devel at redhat.com
Oct 12 17:18:20 partman:   PV /dev/mmcblk0p2   VG sheeva   lvm2 [3.45 GB / 0    free]
Oct 12 17:18:20 partman:   Total: 1 [3.45 GB] / in use: 1 [3.45 GB] / in no VG: 0 [0   ]
Oct 12 17:18:20 partman:   Reading all physical volumes.  This may take a while...
Oct 12 17:18:20 partman:   Found volume group "sheeva" using metadata type lvm2
Oct 12 17:18:20 partman-lvm:   2 logical volume(s) in volume group "sheeva" now active
Oct 12 17:18:54 partman-lvm:   Logical volume "root" successfully removed
Oct 12 17:18:55 partman-lvm:   Logical volume "swap_1" successfully removed
Oct 12 17:18:55 partman-lvm:   0 logical volume(s) in volume group "sheeva" now active
Oct 12 17:18:55 partman-lvm:   Volume group "sheeva" successfully removed
Oct 12 17:18:55 partman-lvm:   Labels on physical volume "/dev/mmcblk0p2" successfully wiped
Oct 12 17:19:32 partman-lvm:   Physical volume "/dev/sda2" successfully created
Oct 12 17:19:33 partman-lvm:   Volume group "sheeva" successfully created
Oct 12 17:19:33 partman-lvm:   Logical volume "root" created
Oct 12 17:19:34 partman-lvm:   Logical volume "swap_1" created
Oct 12 17:20:06 kernel: [  220.850000] Adding 212984k swap on /dev/mapper/sheeva-swap_1.  Priority:-1 extents:1 across:212984k 
Oct 12 17:20:37 partman: mke2fs 1.41.9 (22-Aug-2009)
Oct 12 17:21:23 kernel: [  297.480000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
Oct 12 17:21:23 partman-md: mdadm: No arrays found in config file or automatically
Oct 12 17:21:23 kernel: [  297.540000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
Oct 12 17:23:58 kernel: [  452.710000] Adding 212984k swap on /dev/mapper/sheeva-swap_1.  Priority:-1 extents:1 across:212984k 
Oct 12 17:23:59 kernel: [  453.280000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
Oct 12 17:23:59 partman-md: mdadm: No arrays found in config file or automatically
Oct 12 17:23:59 kernel: [  453.340000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
Oct 12 17:23:59 partman-md: Selected spare count: 0
Oct 12 17:23:59 partman-md: RAID devices count: 2
Oct 12 17:23:59 partman-md: Spare devices count: 0
Oct 12 17:23:59 partman-md: mdadm: /dev/mmcblk0p1 appears to contain an ext2fs file system
Oct 12 17:23:59 partman-md:     size=248976K  mtime=Thu Jan  1 00:00:00 1970
Oct 12 17:23:59 partman-md: mdadm: largest drive (/dev/mmcblk0p2) exceeds size (248896K) by more than 1%
Oct 12 17:23:59 kernel: [  453.870000] md: bind<mmcblk0p1>
Oct 12 17:23:59 kernel: [  453.870000] md: bind<mmcblk0p2>
Oct 12 17:23:59 kernel: [  453.870000] md: md0: raid array is not clean -- starting background reconstruction
Oct 12 17:23:59 kernel: [  453.890000] md: raid1 personality registered for level 1
Oct 12 17:23:59 partman-md: mdadm: array /dev/md0 started.
Oct 12 17:23:59 kernel: [  453.890000] md0: WARNING: mmcblk0p2 appears to be on the same physical disk as mmcblk0p1.
Oct 12 17:23:59 kernel: [  453.890000] True protection against single-disk failure might be compromised.
Oct 12 17:23:59 kernel: [  453.890000] raid1: raid set md0 active with 2 out of 2 mirrors
Oct 12 17:23:59 kernel: [  453.900000] md: resync of RAID array md0
Oct 12 17:23:59 kernel: [  453.900000] md: minimum _guaranteed_  speed: 1000 KB/sec/disk.
Oct 12 17:23:59 kernel: [  453.900000] md: using maximum available idle IO bandwidth (but not more than 200000 KB/sec) for resync.
Oct 12 17:23:59 kernel: [  453.900000] md: using 128k window, over a total of 248896 blocks.
Oct 12 17:24:04 main-menu[459]: (process:3191): [0] ../../libparted/filesys.c:147 (ped_file_system_type_get): File system alias linux-swap(new) is deprecated
Oct 12 17:24:04 main-menu[459]: (process:3191): [0] ../../libparted/filesys.c:147 (ped_file_system_type_get): File system alias linux-swap(new) is deprecated
Oct 12 17:24:04 main-menu[459]: (process:3191): [0] ../../libparted/filesys.c:147 (ped_file_system_type_get): File system alias linux-swap(new) is deprecated
Oct 12 17:24:04 main-menu[459]: (process:3191): [0] ../../libparted/filesys.c:147 (ped_file_system_type_get): File system alias linux-swap(new) is deprecated
Oct 12 17:24:04 main-menu[459]: INFO: Menu item 'partman-base' succeeded but requested to be left unconfigured.
Oct 12 17:24:05 main-menu[459]: DEBUG: resolver (libgcc1): package doesn't exist (ignored)
Oct 12 17:24:09 main-menu[459]: INFO: Falling back to the package description for auto-install
Oct 12 17:24:09 main-menu[459]: INFO: Falling back to the package description for ai-choosers
Oct 12 17:24:09 main-menu[459]: INFO: Menu item 'di-utils-shell' selected
Oct 12 17:25:16 kernel: [  530.940000] md: md0: resync done.
Oct 12 17:25:17 kernel: [  531.490000] RAID1 conf printout:
Oct 12 17:25:17 kernel: [  531.490000]  --- wd:2 rd:2
Oct 12 17:25:17 kernel: [  531.490000]  disk 0, wo:0, o:1, dev:mmcblk0p1
Oct 12 17:25:17 kernel: [  531.490000]  disk 1, wo:0, o:1, dev:mmcblk0p2

-------------- next part --------------
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 2.6.30-2-kirkwood (Debian 2.6.30-8) (waldi at debian.org) (gcc version 4.3.4 (Debian 4.3.4-3) ) #1 Sun Sep 27 22:57:55 UTC 2009
[    0.000000] CPU: Feroceon 88FR131 [56251311] revision 1 (ARMv5TE), cr=00053977
[    0.000000] CPU: VIVT data cache, VIVT instruction cache
[    0.000000] Machine: Marvell SheevaPlug Reference Board
[    0.000000] Ignoring unrecognised tag 0x41000403
[    0.000000] Memory policy: ECC disabled, Data cache writeback
[    0.000000] On node 0 totalpages: 131072
[    0.000000] free_area_init_node: node 0, pgdat c0386954, node_mem_map c03f0000
[    0.000000]   Normal zone: 1024 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 130048 pages, LIFO batch:31
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 130048
[    0.000000] Kernel command line: console=ttyS0,115200n8 base-installer/initramfs-tools/driver-policy=most
[    0.000000] NR_IRQS:114
[    0.000000] PID hash table entries: 2048 (order: 11, 8192 bytes)
[    0.000000] Console: colour dummy device 80x30
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.010000] Memory: 256MB 256MB = 512MB total
[    0.010000] Memory: 512896KB available (3232K code, 548K data, 120K init, 0K highmem)
[    0.010000] Calibrating delay loop... 1192.75 BogoMIPS (lpj=5963776)
[    0.250000] Security Framework initialized
[    0.250000] SELinux:  Disabled at boot.
[    0.250000] Mount-cache hash table entries: 512
[    0.250000] Initializing cgroup subsys ns
[    0.250000] Initializing cgroup subsys cpuacct
[    0.250000] Initializing cgroup subsys devices
[    0.250000] Initializing cgroup subsys freezer
[    0.250000] Initializing cgroup subsys net_cls
[    0.250000] CPU: Testing write buffer coherency: ok
[    0.250000] net_namespace: 1076 bytes
[    0.250000] regulator: core version 0.5
[    0.250000] NET: Registered protocol family 16
[    0.250000] Kirkwood: MV88F6281-A0, TCLK=200000000.
[    0.250000] Feroceon L2: Enabling L2
[    0.250000] Feroceon L2: Cache support initialised.
[    0.250000] initial MPP regs: 01111111 11113322 00001111 00100000 00000000 00000000 00000000
[    0.250000]   final MPP regs: 01111111 11113322 00001111 00000000 00000000 00000000 00000000
[    0.260000] bio: create slab <bio-0> at 0
[    0.260000] NET: Registered protocol family 2
[    0.260000] IP route cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.260000] TCP established hash table entries: 65536 (order: 7, 524288 bytes)
[    0.260000] TCP bind hash table entries: 65536 (order: 6, 262144 bytes)
[    0.260000] TCP: Hash tables configured (established 65536 bind 65536)
[    0.260000] TCP reno registered
[    0.260000] NET: Registered protocol family 1
[    0.260000] Trying to unpack rootfs image as initramfs...
[    0.270000] Switched to high resolution mode on CPU 0
[    0.450000] Freeing initrd memory: 2628K
[    0.450000] NetWinder Floating Point Emulator V0.97 (double precision)
[    0.450000] audit: initializing netlink socket (disabled)
[    0.450000] type=2000 audit(0.450:1): initialized
[    0.450000] VFS: Disk quotas dquot_6.5.2
[    0.450000] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.450000] JFFS2 version 2.2. (NAND) (SUMMARY)  ?? 2001-2006 Red Hat, Inc.
[    0.450000] msgmni has been set to 1007
[    0.450000] alg: No test for stdrng (krng)
[    0.450000] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    0.450000] io scheduler noop registered
[    0.450000] io scheduler anticipatory registered
[    0.450000] io scheduler deadline registered
[    0.450000] io scheduler cfq registered (default)
[    0.460000] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled
[    0.460000] serial8250.0: ttyS0 at MMIO 0xf1012000 (irq = 33) is a 16550A
[    0.460000] console [ttyS0] enabled
[    0.780000] brd: module loaded
[    0.790000] NAND device: Manufacturer ID: 0xad, Chip ID: 0xdc (Hynix NAND 512MiB 3,3V 8-bit)
[    0.800000] Scanning device for bad blocks
[    0.950000] Creating 3 MTD partitions on "orion_nand":
[    0.950000] 0x000000000000-0x000000100000 : "u-boot"
[    0.960000] uncorrectable error : <5>0x000000100000-0x000000500000 : "uImage"
[    0.970000] ftl_cs: FTL header not found.
[    0.970000] 0x000000500000-0x000020000000 : "root"
[    0.980000] ftl_cs: FTL header not found.
[    0.980000] mice: PS/2 mouse device common for all mice
[    0.990000] rtc-mv rtc-mv: rtc core: registered rtc-mv as rtc0
[    0.990000] i2c /dev entries driver
[    1.000000] cpuidle: using governor ladder
[    1.000000] cpuidle: using governor menu
[    1.010000] Registered led device: plug:green:health
[    1.010000] mv_xor_shared mv_xor_shared.0: Marvell shared XOR driver
[    1.020000] mv_xor_shared mv_xor_shared.1: Marvell shared XOR driver
[    1.070000] mv_xor mv_xor.0: Marvell XOR: ( xor cpy )
[    1.110000] mv_xor mv_xor.1: Marvell XOR: ( xor fill cpy )
[    1.150000] mv_xor mv_xor.2: Marvell XOR: ( xor cpy )
[    1.190000] mv_xor mv_xor.3: Marvell XOR: ( xor fill cpy )
[    1.190000] TCP cubic registered
[    1.200000] NET: Registered protocol family 17
[    1.200000] Gating clock of unused units
[    1.200000] before: 0x00df03dd
[    1.200000]  after: 0x00c501d9
[    1.200000] registered taskstats version 1
[    1.200000] rtc-mv rtc-mv: setting system clock to 2009-10-12 17:16:25 UTC (1255367785)
[    1.210000] Freeing init memory: 120K
[    1.330000] MV-643xx 10/100/1000 ethernet driver version 1.4
[    1.330000] mv643xx_eth smi: probed
[    1.390000] net eth0: port 0 with MAC address 00:50:43:43:2d:29
[    1.450000] mmc0: mvsdio driver initialized, lacking card detect (fall back to polling)
[    1.600000] mmc0: host does not support reading read-only switch. assuming write-enable.
[    1.610000] mmc0: new high speed SDHC card at address bc76
[    1.710000] mmcblk0: mmc0:bc76 SU04G 3.69 GiB 
[    1.710000]  mmcblk0: p1 p2
[   16.550000] eth0: link up, 1000 Mb/s, full duplex, flow control disabled
[  101.820000] usbcore: registered new interface driver usbfs
[  101.820000] usbcore: registered new interface driver hub
[  102.030000] usbcore: registered new device driver usb
[  102.040000] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[  102.040000] orion-ehci orion-ehci.0: Marvell Orion EHCI
[  102.040000] orion-ehci orion-ehci.0: new USB bus registered, assigned bus number 1
[  102.070000] orion-ehci orion-ehci.0: irq 19, io mem 0xf1050000
[  102.090000] orion-ehci orion-ehci.0: USB 2.0 started, EHCI 1.00
[  102.090000] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[  102.090000] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[  102.090000] usb usb1: Product: Marvell Orion EHCI
[  102.090000] usb usb1: Manufacturer: Linux 2.6.30-2-kirkwood ehci_hcd
[  102.090000] usb usb1: SerialNumber: orion-ehci.0
[  102.090000] usb usb1: configuration #1 chosen from 1 choice
[  102.090000] hub 1-0:1.0: USB hub found
[  102.090000] hub 1-0:1.0: 1 port detected
[  102.350000] SCSI subsystem initialized
[  102.380000] Initializing USB Mass Storage driver...
[  102.380000] usbcore: registered new interface driver usb-storage
[  102.380000] USB Mass Storage support registered.
[  102.420000] usb 1-1: new high speed USB device using orion-ehci and address 2
[  102.570000] usb 1-1: New USB device found, idVendor=090c, idProduct=1000
[  102.570000] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[  102.570000] usb 1-1: Product: USB DISK
[  102.570000] usb 1-1: Manufacturer: SMI Corporation
[  102.570000] usb 1-1: SerialNumber: AA04012700008697
[  102.570000] usb 1-1: configuration #1 chosen from 1 choice
[  102.580000] scsi0 : SCSI emulation for USB Mass Storage devices
[  102.580000] usb-storage: device found at 2
[  102.580000] usb-storage: waiting for device to settle before scanning
[  107.580000] usb-storage: device scan complete
[  107.580000] scsi 0:0:0:0: Direct-Access     SMI      USB DISK         1100 PQ: 0 ANSI: 0 CCS
[  107.600000] Driver 'sd' needs updating - please use bus_type methods
[  107.600000] sd 0:0:0:0: [sda] 7864320 512-byte hardware sectors: (4.02 GB/3.75 GiB)
[  107.600000] sd 0:0:0:0: [sda] Write Protect is off
[  107.600000] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
[  107.600000] sd 0:0:0:0: [sda] Assuming drive cache: write through
[  107.610000] sd 0:0:0:0: [sda] Assuming drive cache: write through
[  107.610000]  sda: sda1 sda2 sda3 < sda5 >
[  107.610000] sd 0:0:0:0: [sda] Attached SCSI removable disk
[  113.650000] JFS: nTxBlock = 4030, nTxLock = 32243
[  113.950000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
[  114.010000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
[  114.140000] device-mapper: uevent: version 1.0.3
[  114.140000] device-mapper: ioctl: 4.14.0-ioctl (2008-04-23) initialised: dm-devel at redhat.com
[  220.850000] Adding 212984k swap on /dev/mapper/sheeva-swap_1.  Priority:-1 extents:1 across:212984k 
[  297.480000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
[  297.540000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
[  452.710000] Adding 212984k swap on /dev/mapper/sheeva-swap_1.  Priority:-1 extents:1 across:212984k 
[  453.280000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
[  453.340000] uncorrectable error : <3>uncorrectable error : <3>end_request: I/O error, dev mtdblock0, sector 0
[  453.870000] md: bind<mmcblk0p1>
[  453.870000] md: bind<mmcblk0p2>
[  453.870000] md: md0: raid array is not clean -- starting background reconstruction
[  453.890000] md: raid1 personality registered for level 1
[  453.890000] md0: WARNING: mmcblk0p2 appears to be on the same physical disk as mmcblk0p1.
[  453.890000] True protection against single-disk failure might be compromised.
[  453.890000] raid1: raid set md0 active with 2 out of 2 mirrors
[  453.900000] md: resync of RAID array md0
[  453.900000] md: minimum _guaranteed_  speed: 1000 KB/sec/disk.
[  453.900000] md: using maximum available idle IO bandwidth (but not more than 200000 KB/sec) for resync.
[  453.900000] md: using 128k window, over a total of 248896 blocks.
[  530.940000] md: md0: resync done.
[  531.490000] RAID1 conf printout:
[  531.490000]  --- wd:2 rd:2
[  531.490000]  disk 0, wo:0, o:1, dev:mmcblk0p1
[  531.490000]  disk 1, wo:0, o:1, dev:mmcblk0p2
-------------- next part --------------
parted_server: Opening infifo
/lib/partman/choose_partition/30lvm/choices: IN: PARTITION_INFO =dev=sda 254983680-4022161919
parted_server: Read command: PARTITION_INFO
parted_server: command_partition_info()
parted_server: Opening outfifo
parted_server: command_partition_info: info for partition with id 254983680-4022161919
parted_server: partition_with_id(254983680-4022161919)
parted_server: OUT: OK


parted_server: command_partition_info: partition found
parted_server: OUT: 2   254983680-4022161919    3767178240      primary unknown /dev/sda2


parted_server: Closing infifo and outfifo
parted_server: main_loop: iteration 351
parted_server: Opening infifo
/lib/partman/choose_partition/30lvm/choices: IN: VALID_FLAGS =dev=sda 254983680-4022161919
parted_server: Read command: VALID_FLAGS
parted_server: command_valid_flags()
parted_server: Opening outfifo
parted_server: partition_with_id(254983680-4022161919)
parted_server: OUT: OK


parted_server: Partition found (254983680-4022161919)
parted_server: OUT: boot


parted_server: OUT: hidden


parted_server: OUT: raid


parted_server: OUT: lvm


parted_server: OUT: lba


parted_server: OUT: palo


parted_server: OUT: prep


parted_server: OUT: 


parted_server: Closing infifo and outfifo
parted_server: main_loop: iteration 352
parted_server: Opening infifo
/lib/partman/choose_partition/35crypto/choices: *******************************************************
/lib/partman/choose_partition/35crypto/choices: IN: PARTITIONS =dev=mapper=sheeva-root
parted_server: Read command: PARTITIONS
parted_server: command_partitions()
parted_server: Opening outfifo
parted_server: OUT: OK


parted_server: OUT: 1   0-3342860287    3342860288      primary ext3    /dev/mapper/sheeva-root 


parted_server: Partitions printed
parted_server: OUT: 


parted_server: Closing infifo and outfifo
/lib/partman/choose_partition/35crypto/choices: paragraph: 1    0-3342860287    3342860288      primary ext3    /dev/mapper/sheeva-root
parted_server: main_loop: iteration 353
parted_server: Opening infifo
/lib/partman/choose_partition/35crypto/choices: IN: PARTITIONS =dev=mapper=sheeva-swap_1
parted_server: Read command: PARTITIONS
parted_server: command_partitions()
parted_server: Opening outfifo
parted_server: OUT: OK


parted_server: OUT: 1   0-218103807     218103808       primary linux-swap      /dev/mapper/sheeva-swap_1


parted_server: Partitions printed
parted_server: OUT: 


parted_server: Closing infifo and outfifo
/lib/partman/choose_partition/35crypto/choices: paragraph: 1    0-218103807     218103808       primary linux-swap      /dev/mapper/sheeva-swap_1
parted_server: main_loop: iteration 354
parted_server: Opening infifo
/lib/partman/choose_partition/35crypto/choices: IN: PARTITIONS =dev=mmcblk0
parted_server: Read command: PARTITIONS
parted_server: command_partitions()
parted_server: Opening outfifo
parted_server: OUT: OK


parted_server: OUT: 1   32256-254983679 254951424       primary ext2    /dev/mmcblk0p1  


parted_server: OUT: 2   254983680-3964584959    3709601280      primary unknown /dev/mmcblk0p2  


parted_server: Partitions printed
parted_server: OUT: 


parted_server: Closing infifo and outfifo
/lib/partman/choose_partition/35crypto/choices: paragraph: 1    32256-254983679 254951424       primary ext2    /dev/mmcblk0p1
/lib/partman/choose_partition/35crypto/choices: paragraph: 2    254983680-3964584959    3709601280      primary unknown /dev/mmcblk0p2
parted_server: main_loop: iteration 355
parted_server: Opening infifo
/lib/partman/choose_partition/35crypto/choices: IN: PARTITIONS =dev=sda
parted_server: Read command: PARTITIONS
parted_server: command_partitions()
parted_server: Opening outfifo
parted_server: OUT: OK


parted_server: OUT: 1   32256-254983679 254951424       primary ext2    /dev/sda1


parted_server: OUT: 2   254983680-4022161919    3767178240      primary unknown /dev/sda2


parted_server: Partitions printed
parted_server: OUT: 


parted_server: Closing infifo and outfifo
/lib/partman/choose_partition/35crypto/choices: paragraph: 1    32256-254983679 254951424       primary ext2    /dev/sda1
/lib/partman/choose_partition/35crypto/choices: paragraph: 2    254983680-4022161919    3767178240      primary unknown /dev/sda2
parted_server: main_loop: iteration 356
parted_server: Opening infifo
/lib/partman/choose_partition/60partition_tree/choices: *******************************************************
/lib/partman/choose_partition/60partition_tree/choices: IN: PARTITIONS =dev=mapper=sheeva-root
parted_server: Read command: PARTITIONS
parted_server: command_partitions()
parted_server: Opening outfifo
parted_server: OUT: OK


parted_server: OUT: 1   0-3342860287    3342860288      primary ext3    /dev/mapper/sheeva-root 


parted_server: Partitions printed
parted_server: OUT: 


parted_server: Closing infifo and outfifo
/lib/partman/choose_partition/60partition_tree/choices: paragraph: 1    0-3342860287    3342860288      primary ext3    /dev/mapper/sheeva-root
parted_server: main_loop: iteration 357
parted_server: Opening infifo
/lib/partman/choose_partition/60partition_tree/choices: IN: PARTITIONS =dev=mapper=sheeva-swap_1
parted_server: Read command: PARTITIONS
parted_server: command_partitions()
parted_server: Opening outfifo
parted_server: OUT: OK


parted_server: OUT: 1   0-218103807     218103808       primary linux-swap      /dev/mapper/sheeva-swap_1


parted_server: Partitions printed
parted_server: OUT: 


parted_server: Closing infifo and outfifo
/lib/partman/choose_partition/60partition_tree/choices: paragraph: 1    0-218103807     218103808       primary linux-swap      /dev/mapper/sheeva-swap_1
parted_server: main_loop: iteration 358
parted_server: Opening infifo
/lib/partman/choose_partition/60partition_tree/choices: IN: PARTITIONS =dev=mmcblk0
parted_server: Read command: PARTITIONS
parted_server: command_partitions()
parted_server: Opening outfifo
parted_server: OUT: OK


parted_server: OUT: 1   32256-254983679 254951424       primary ext2    /dev/mmcblk0p1  


parted_server: OUT: 2   254983680-3964584959    3709601280      primary unknown /dev/mmcblk0p2  


parted_server: Partitions printed
parted_server: OUT: 


parted_server: Closing infifo and outfifo
/lib/partman/choose_partition/60partition_tree/choices: paragraph: 1    32256-254983679 254951424       primary ext2    /dev/mmcblk0p1
/lib/partman/choose_partition/60partition_tree/choices: paragraph: 2    254983680-3964584959    3709601280      primary unknown /dev/mmcblk0p2
parted_server: main_loop: iteration 359
parted_server: Opening infifo
/lib/partman/choose_partition/60partition_tree/choices: IN: PARTITIONS =dev=sda
parted_server: Read command: PARTITIONS
parted_server: command_partitions()
parted_server: Opening outfifo
parted_server: OUT: OK


parted_server: OUT: 1   32256-254983679 254951424       primary ext2    /dev/sda1


parted_server: OUT: 2   254983680-4022161919    3767178240      primary unknown /dev/sda2


parted_server: Partitions printed
parted_server: OUT: 


parted_server: Closing infifo and outfifo
/lib/partman/choose_partition/60partition_tree/choices: paragraph: 1    32256-254983679 254951424       primary ext2    /dev/sda1
/lib/partman/choose_partition/60partition_tree/choices: paragraph: 2    254983680-4022161919    3767178240      primary unknown /dev/sda2
parted_server: main_loop: iteration 360
parted_server: Opening infifo
/bin/partman: IN: QUIT
parted_server: Read command: QUIT
parted_server: Quitting



More information about the Parted-maintainers mailing list