Attachment 292854 Details for Bug 429939 – anaconda log

anaconda log

anaconda.log (text/plain), 18.99 KB, created by Bill Peck on 2008-01-24 20:48:06 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Bill Peck

Created: 2008-01-24 20:48:06 UTC

Size: 18.99 KB

patch

obsolete

>17:06:50 INFO    : kernel command line: initrd=pxelinux.cfg/0A0C0439.data/initrd.img ks=http://rhts.lab.boston.redhat.com/kickstarts/hosts/dell-pe800-01.rhts.boston.redhat.com/ks.cfg console=ttyS0,115200 BOOT_IMAGE=pxelinux.cfg/0A0C0439.data/vmlinuz 
>
>17:06:50 INFO    : anaconda version 11.4.0.24 on i386 starting
>17:06:50 INFO    : text mode forced due to serial/virtpconsole
>17:06:50 INFO    : 3344456 kB are available
>17:06:50 DEBUG   : probing buses
>17:06:50 DEBUG   : waiting for hardware to initialize
>17:06:52 INFO    : getting kickstart file
>17:06:52 INFO    : only have one network device: eth0
>17:06:52 INFO    : sending dhcp request through device eth0
>17:06:52 DEBUG   : waiting for link eth0...
>17:06:52 DEBUG   :    0 seconds.
>17:06:52 DEBUG   : sleep (nicdelay) for 0 secs first
>17:06:52 DEBUG   : continuing...
>17:06:52 DEBUG   : sending anaconda-Linux 2.6.24-0.164.rc8.git4.fc9 i686 as dhcp vendor-class
>requesting dhcp timeout 45
>17:06:52 INFO    : Listening on LPF/eth0/00:0f:1f:f8:56:a6
>17:06:52 INFO    : Sending on   LPF/eth0/00:0f:1f:f8:56:a6
>17:06:52 INFO    : Sending on   Socket/fallback
>17:06:54 INFO    : DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
>17:06:59 INFO    : DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 14
>17:07:13 INFO    : DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 7
>17:07:13 INFO    : DHCPOFFER from 10.12.4.15
>17:07:13 INFO    : DHCPREQUEST on eth0 to 255.255.255.255 port 67
>17:07:13 INFO    : DHCPACK from 10.12.4.15
>17:07:13 INFO    : bound to 10.12.4.57 -- renewal in 10715 seconds.
>17:07:13 INFO    : doing kickstart... setting it up
>17:07:13 DEBUG   : waiting for link eth0...
>17:07:13 DEBUG   :    0 seconds.
>17:07:13 DEBUG   : sleep (nicdelay) for 0 secs first
>17:07:13 DEBUG   : continuing...
>17:07:13 INFO    : reverse name lookup worked
>17:07:13 DEBUG   : getHostandPath host: |rhts.lab.boston.redhat.com|
>17:07:13 DEBUG   : getHostandPath file(1): |/kickstarts/hosts/dell-pe800-01.rhts.boston.redhat.com/ks.cfg|
>17:07:13 INFO    : file location: http://rhts.lab.boston.redhat.com/kickstarts/hosts/dell-pe800-01.rhts.boston.redhat.com/ks.cfg
>17:07:13 INFO    : transferring http://rhts.lab.boston.redhat.com/kickstarts/hosts/dell-pe800-01.rhts.boston.redhat.com/ks.cfg to a fd
>17:07:13 INFO    : setting up kickstart
>17:07:13 INFO    : kickstartFromNfs
>17:07:13 INFO    : results of nfs, host is jeter.boston.redhat.com, dir is /vol/fedora/rawhide/nightly/rawhide-20080123/i386/os, opts are '(null)'
>17:07:13 INFO    : kickstart forcing text mode
>17:07:13 INFO    : kickstart forcing cmdline mode
>17:07:13 DEBUG   : going to set language to en_US.UTF-8
>17:07:13 INFO    : setting language to en_US.UTF-8
>17:07:13 WARNING : requested keymap us is not valid, asking
>17:07:13 INFO    : need to set up networking
>17:07:13 INFO    : going to pick interface
>17:07:13 INFO    : going to do getNetConfig
>17:07:13 INFO    : eth0 is not a wireless adapter
>17:07:13 DEBUG   : waiting for link eth0...
>17:07:13 DEBUG   :    0 seconds.
>17:07:13 DEBUG   : sleep (nicdelay) for 0 secs first
>17:07:13 DEBUG   : continuing...
>17:07:13 DEBUG   : sending anaconda-Linux 2.6.24-0.164.rc8.git4.fc9 i686 as dhcp vendor-class
>requesting dhcp timeout 45
>17:07:13 INFO    : DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
>17:07:18 INFO    : DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 12
>17:07:30 INFO    : DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 17
>17:07:30 INFO    : DHCPOFFER from 10.12.4.15
>17:07:30 INFO    : DHCPREQUEST on eth0 to 255.255.255.255 port 67
>17:07:30 INFO    : DHCPACK from 10.12.4.15
>17:07:30 INFO    : bound to 10.12.4.57 -- renewal in 9958 seconds.
>17:07:30 DEBUG   : device = eth0
>17:07:30 DEBUG   : broadcast = 10.12.7.255
>17:07:30 DEBUG   : netmask = 255.255.252.0
>17:07:30 DEBUG   : network = 10.12.4.0
>17:07:30 DEBUG   : gateway = 10.12.7.254
>17:07:30 DEBUG   : dns[0] = 10.12.4.15
>17:07:30 DEBUG   : dns[1] = 172.16.76.10
>17:07:30 DEBUG   : waiting for link eth0...
>17:07:30 DEBUG   :    0 seconds.
>17:07:30 DEBUG   : sleep (nicdelay) for 0 secs first
>17:07:30 DEBUG   : continuing...
>17:07:30 INFO    : reverse name lookup worked
>17:07:30 INFO    : starting to STEP_URL
>17:07:30 INFO    : going to do nfsGetSetup
>17:07:30 INFO    : host is jeter.boston.redhat.com, dir is /vol/fedora/rawhide/nightly/rawhide-20080123/i386/os, opts are '(null)'
>17:07:30 INFO    : mounting nfs path jeter.boston.redhat.com:/vol/fedora/rawhide/nightly/rawhide-20080123/i386/os
>17:07:30 INFO    : can access /mnt/source/images/stage2.img
>17:07:30 INFO    : trying to mount CD device /dev/sr0 on /mnt/stage2
>17:07:30 INFO    : mntloop /dev/loop0 on /mnt/runtime as /mnt/source/images/stage2.img fd is 21
>17:07:30 DEBUG   : after mountStage2, rc is 0
>17:07:30 INFO    : got url nfs://mnt/source
>17:07:30 WARNING : Failed to create /etc/selinux/config: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/customizable_types: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/dbus_contexts: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/default_contexts: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/default_type: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/failsafe_context: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/files/media: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/initrc_context: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/removable_context: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/securetty_types: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/userhelper_context: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/users/guest_u: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/users/root: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/users/staff_u: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/users/unconfined_u: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/users/user_u: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/contexts/users/xguest_u: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/modules/semanage.read.LOCK: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/modules/semanage.trans.LOCK: Read-only file system
>17:07:30 WARNING : Failed to create /etc/selinux/targeted/setrans.conf: Read-only file system
>17:07:30 WARNING : Failed to symlink /var/lib/xkb to /tmp: File exists
>17:07:30 INFO    : Loading SELinux policy
>17:07:31 ERROR   : failed to load policy, disabling SELinux
>17:07:31 INFO    : getting ready to spawn shell now
>17:07:31 INFO    : not spawning a shell
>17:07:31 INFO    : NFS install method detected, will use RHupdates/
>17:07:31 INFO    : Running anaconda script /usr/bin/anaconda
>17:07:39 INFO    : using only installclass _Fedora
>17:07:39 INFO    : Starting message bus...
>17:07:44 INFO    : Starting HAL daemon...
>17:07:49 WARNING : /usr/lib/python2.5/site-packages/pykickstart/parser.py:651: DeprecationWarning: Script does not end with %end.  This syntax has been deprecated.  It may be removed from future releases, which will result in a fatal error from kickstart.  Please modify your kickstart file to use this updated syntax.
>  warnings.warn(_("%s does not end with %%end.  This syntax has been deprecated.  It may be removed from future releases, which will result in a fatal error from kickstart.  Please modify your kickstart file to use this updated syntax.") % _("Script"), DeprecationWarning)
>
>17:07:50 INFO    : anaconda called with cmdline = ['/usr/bin/anaconda', '-m', 'nfs://mnt/source', '--serial', '-T', '-C', '--noselinux', '--kickstart', '/tmp/ks.cfg', '--lang', 'en_US.UTF-8']
>17:07:50 INFO    : Display mode = c
>17:07:50 INFO    : Method = file:///mnt/source
>17:07:53 DEBUG   : starting mpaths
>17:07:53 DEBUG   : self.driveList(): [u'sda', u'sdb']
>17:07:53 DEBUG   : DiskSet.skippedDisks: []
>17:07:53 DEBUG   : DiskSet.skippedDisks: []
>17:07:53 DEBUG   : starting all mpaths on drives [u'sda', u'sdb']
>17:07:53 DEBUG   : scanning for multipath on drives [u'sda', u'sdb']
>17:07:53 DEBUG   : loading bdevid modules from: '/tmp/updates/bdevid/:/mnt/source/RHupdates/bdevid/:/lib/bdevid/:/usr/lib/bdevid/'
>17:07:53 DEBUG   : mpaths: []
>17:07:53 DEBUG   : done starting mpaths.  Drivelist: [u'sda', u'sdb']
>17:07:53 DEBUG   : starting dmraids
>17:07:53 DEBUG   : self.driveList(): [u'sda', u'sdb']
>17:07:53 DEBUG   : DiskSet.skippedDisks: []
>17:07:53 DEBUG   : DiskSet.skippedDisks: []
>17:07:53 DEBUG   : starting all dmraids on drives [u'sda', u'sdb']
>17:07:53 DEBUG   : scanning for dmraid on drives [u'sda', u'sdb']
>17:07:53 ERROR   : error scanning dmraid, disabling: discover_disks() takes at most 1 argument (2 given)
>17:07:53 ERROR   : error scanning dmraid, disabling: discover_disks() takes at most 1 argument (2 given)
>17:07:53 DEBUG   : done starting dmraids.  Drivelist: [u'sda', u'sdb']
>17:07:53 INFO    : Running kickstart %%pre script(s)
>17:07:54 INFO    : All kickstart %%pre script(s) have been run
>17:07:54 INFO    : Detected 3280M of memory
>17:07:54 INFO    : Swap attempt of 1000M to 2000M
>17:07:54 WARNING : step installtype does not exist
>17:07:54 WARNING : step confirminstall does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 WARNING : step complete does not exist
>17:07:54 INFO    : moving (1) to step networkdevicecheck
>17:07:54 INFO    : moving (1) to step partitionobjinit
>17:07:54 INFO    : no initiator set
>17:07:54 INFO    : no /tmp/fcpconfig; not configuring zfcp
>17:07:54 INFO    : Reinitializing label for drive sda
>17:07:54 DEBUG   : adding drive sda to disk list
>17:07:54 INFO    : Reinitializing label for drive sdb
>17:07:54 DEBUG   : adding drive sdb to disk list
>17:07:55 INFO    : moving (1) to step autopartitionexecute
>17:07:55 INFO    : Detected 3280M of memory
>17:07:55 INFO    : Swap attempt of 1000M to 2000M
>17:07:55 DEBUG   : adding drive sda to disk list
>17:07:55 DEBUG   : adding drive sdb to disk list
>17:07:55 DEBUG   : used space is 2024
>17:07:55 DEBUG   : actual space is 476608
>17:07:55 DEBUG   : used space is 3008
>17:07:55 DEBUG   : actual space is 476608
>17:07:55 DEBUG   : used space is 476608
>17:07:55 DEBUG   : actual space is 476608
>17:07:55 DEBUG   : used space is 476608
>17:07:55 DEBUG   : actual space is 476608
>17:07:55 INFO    : moving (1) to step partitiondone
>17:07:55 INFO    : moving (1) to step enablefilesystems
>17:07:55 INFO    : disk.commit() for /dev/sda
>17:07:55 INFO    : disk.commit() for /dev/sdb
>17:07:55 DEBUG   : adding drive sda to disk list
>17:07:55 DEBUG   : adding drive sdb to disk list
>17:07:56 INFO    : formatting swap as swap
>17:07:56 INFO    : formatting / as ext3
>17:07:56 INFO    : Format command:  ['mke2fs', '/dev/VolGroup00/LogVol00', '-j']
>
>17:11:09 INFO    : formatting /boot as ext3
>17:11:09 INFO    : Format command:  ['mke2fs', '/dev/sda1', '-j']
>
>17:11:09 DEBUG   : adding drive sda to disk list
>17:11:09 CRITICAL: parted exception: Error: File system has an invalid signature for a FAT file system.
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: File system has an invalid signature for a FAT file system.
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:09 CRITICAL: parted exception: Error: Unrecognised linux swap signature 'rQìY0Wu}|'.
>17:11:10 DEBUG   : adding drive sdb to disk list
>17:11:10 CRITICAL: parted exception: Error: File system has an invalid signature for a FAT file system.
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: File system has an invalid signature for a FAT file system.
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: amiga_find_part : Didn't find rdb block, should never happen
>
>17:11:10 CRITICAL: parted exception: Error: Unrecognised linux swap signature '          '.
>17:11:10 INFO    : lv VolGroup00/LogVol00, attr is -wi-a-
>17:11:10 INFO    : lv is VolGroup00/LogVol00, size of 474624
>17:11:10 INFO    : lv is VolGroup00/LogVol01, size of 1984
>17:11:10 INFO    : trying to mount /dev/VolGroup00/LogVol00 on /
>17:11:10 DEBUG   : isys.py:mount()- going to mount /dev/VolGroup00/LogVol00 on /mnt/sysimage
>17:11:10 INFO    : trying to mount /dev/sda1 on /boot
>17:11:10 DEBUG   : isys.py:mount()- going to mount /dev/sda1 on /mnt/sysimage/boot
>17:11:10 INFO    : trying to mount sys on /sys
>17:11:10 DEBUG   : isys.py:mount()- going to mount sys on /mnt/sysimage/sys
>17:11:10 INFO    : trying to mount proc on /proc
>17:11:10 DEBUG   : isys.py:mount()- going to mount proc on /mnt/sysimage/proc
>17:11:10 INFO    : moving (1) to step migratefilesystems
>17:11:10 INFO    : moving (1) to step bootloadersetup
>17:11:10 INFO    : moving (1) to step reposetup
>17:11:10 INFO    : added repository Additional Fedora Software with URL http://mirrors.fedoraproject.org/mirrorlist?repo=rawhide&arch=i386
>17:11:15 WARNING : /usr/lib/python2.5/site-packages/yum/repos.py:63: RuntimeWarning: YumProgress.progressbar called when popped
>  self.callback.progressbar(num, len(repos), repo.id)
>
>17:11:17 INFO    : no groups missing
>17:11:17 INFO    : moving (1) to step basepkgsel
>17:11:21 INFO    : moving (1) to step postselection
>17:11:21 DEBUG   : no kernel-smp package
>17:11:21 INFO    : selected kernel package for kernel
>17:11:21 DEBUG   : selecting kernel-devel
>17:11:42 INFO    : moving (1) to step install
>17:11:42 INFO    : moving (1) to step setuptime
>17:11:42 ERROR   : unable to set timezone
>17:11:45 INFO    : moving (1) to step preinstallconfig
>17:11:45 WARNING : no dev package, going to bind mount /dev
>17:11:45 DEBUG   : isys.py:mount()- going to mount /dev on /mnt/sysimage/dev
>17:11:45 INFO    : self.hostname = dell-pe800-01.rhts.boston.redhat.com
>17:11:45 INFO    : moving (1) to step installpackages
>17:11:45 INFO    : Preparing to install packages
>17:15:13 INFO    : moving (1) to step postinstallconfig
>17:15:13 INFO    : moving (1) to step writeconfig
>17:15:13 INFO    : Writing main configuration
>17:15:14 INFO    : self.hostname = dell-pe800-01.rhts.boston.redhat.com
>17:15:14 INFO    : moving (1) to step firstboot
>17:15:14 INFO    : moving (1) to step instbootloader
>17:15:16 INFO    : vg VolGroup00, size is 476672, pesize is 32768
>17:15:16 INFO    : vg VolGroup00, size is 476672, pesize is 32768
>17:15:16 INFO    : vg VolGroup00, size is 476672, pesize is 32768
>17:15:16 INFO    : vg VolGroup00, size is 476672, pesize is 32768
>17:15:17 INFO    : moving (1) to step writeksconfig
>17:15:17 INFO    : Writing autokickstart file
>17:15:17 INFO    : moving (1) to step writeregkey
>17:15:17 INFO    : moving (1) to step setfilecon
>17:15:17 INFO    : moving (1) to step copylogs
>17:15:17 INFO    : Copying anaconda logs
>17:15:17 INFO    : moving (1) to step methodcomplete
>17:15:17 INFO    : moving (1) to step postscripts
>17:15:17 INFO    : Running kickstart %%post script(s)
>17:15:17 ERROR   : Error code 127 encountered running the kickstart script at line 242

Actions: View

Attachments on bug 429939: 292854