Attachment 907220 Details for Bug 1107679 – File: anaconda.log

File: anaconda.log

anaconda.log (text/plain), 16.31 KB, created by Radek Vykydal on 2014-06-10 12:43:18 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Radek Vykydal

Created: 2014-06-10 12:43:18 UTC

Size: 16.31 KB

patch

obsolete

>12:34:22,472 INFO anaconda: /sbin/anaconda 21.39-1
>12:34:22,984 INFO anaconda: 1572864 kB (1536 MB) are available
>12:34:22,984 INFO anaconda: check_memory(): total:1536, needed:512, graphical:512
>12:34:23,016 INFO anaconda: anaconda called with cmdline = ['/sbin/anaconda']
>12:34:23,018 INFO anaconda: Default encoding = utf-8 
>12:34:23,092 INFO anaconda: Parsing kickstart: /run/install/ks.cfg
>12:34:24,511 INFO anaconda: Display mode = g
>12:34:24,514 INFO anaconda: 1572864 kB (1536 MB) are available
>12:34:24,515 INFO anaconda: check_memory(): total:1536, needed:512, graphical:512
>12:34:27,204 DEBUG anaconda: X server has signalled a successful start.
>12:34:27,207 WARN anaconda: logo image is missing, using a substitute
>12:34:27,218 INFO anaconda: Starting window manager, pid 1291.
>12:34:27,308 INFO anaconda: Started spice-vdagent.
>12:34:27,313 INFO anaconda: Running Thread: AnaXklWrapperInitThread (139997909477120)
>12:34:28,502 WARN anaconda: module import of __init__ failed: <type 'exceptions.AttributeError'>
>12:34:28,506 WARN anaconda: module import of __init__ failed: <type 'exceptions.AttributeError'>
>12:34:28,508 INFO anaconda: using only installclass _Fedora
>12:34:28,511 INFO anaconda: bootloader EXTLINUX on X86 platform
>12:34:28,563 INFO anaconda: bootloader EXTLINUX on X86 platform
>12:34:28,650 DEBUG anaconda: network: devices found ['eth0']
>12:34:29,057 DEBUG anaconda: updating hostname office12.anaconda.englab.brq.redhat.com
>12:34:29,130 INFO anaconda: Running Thread: AnaStorageThread (139997764736768)
>12:34:29,132 INFO anaconda: Running Thread: AnaTimeInitThread (139997756344064)
>12:34:29,137 INFO anaconda: Running Thread: AnaWaitForConnectingNMThread (139997747951360)
>12:34:29,138 INFO anaconda: Running Thread: AnaPayloadThread (139997739558656)
>12:34:29,270 INFO anaconda: got 2 NTP servers from DHCP
>12:34:29,283 INFO anaconda: Thread Done: AnaWaitForConnectingNMThread (139997747951360)
>12:34:29,323 INFO anaconda: Running Thread: AnaGeolocationRefreshThread (139997747951360)
>12:34:29,328 INFO anaconda: Starting geolocation lookup
>12:34:29,329 INFO anaconda: Geolocation provider: Fedora GeoIP
>12:34:30,085 INFO anaconda: Thread Done: AnaXklWrapperInitThread (139997909477120)
>12:34:30,006 INFO anaconda: Thread Done: AnaTimeInitThread (139997756344064)
>12:34:31,234 INFO anaconda: Geolocation lookup finished in 1.9 seconds
>12:34:31,255 INFO anaconda: got results from geolocation
>12:34:31,351 INFO anaconda: Thread Done: AnaGeolocationRefreshThread (139997747951360)
>12:34:31,846 DEBUG anaconda: network standalone spoke (init): completed: ['eth0']
>12:34:31,924 DEBUG anaconda: Entered hub: SummaryHub
>12:34:33,215 INFO anaconda: Running Thread: AnaNTPserver1 (139997747951360)
>12:34:33,227 INFO anaconda: Running Thread: AnaNTPserver2 (139997756344064)
>12:34:33,231 INFO anaconda: Running Thread: AnaDateTimeThread (139997909477120)
>12:34:33,431 INFO anaconda: Thread Done: AnaDateTimeThread (139997909477120)
>12:34:33,492 INFO anaconda: Thread Done: AnaNTPserver2 (139997756344064)
>12:34:33,495 INFO anaconda: Thread Done: AnaNTPserver1 (139997747951360)
>12:34:33,530 INFO anaconda: Running Thread: AnaAddLayoutsInitThread (139997747951360)
>12:34:33,535 DEBUG anaconda: Starting applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f53c2fdea50>> on <dictionary-keyiterator object at 0x7f53c2911158>
>12:34:33,549 INFO anaconda: Running Thread: AnaGtkBatchPre1 (139997756344064)
>12:34:33,625 INFO anaconda: Thread Done: AnaGtkBatchPre1 (139997756344064)
>12:34:33,781 INFO anaconda: Running Thread: AnaKeyboardThread (139997756344064)
>12:34:34,604 INFO anaconda: Running Thread: AnaStorageWatcher (139997909477120)
>12:34:34,678 INFO anaconda: Running Thread: AnaCustomStorageInit (139997675759360)
>12:34:35,435 DEBUG anaconda: network: GUI, device configuration added: connection b4345e1f-8a3e-4d1a-9e46-1138ea7ddd60 device eth0
>12:34:35,499 DEBUG anaconda: network: GUI, connection b4345e1f-8a3e-4d1a-9e46-1138ea7ddd60 found
>12:34:35,505 DEBUG anaconda: network: GUI, connection 6ad4c7e5-2c34-4ef0-aa95-a9233e493052 found
>12:34:35,888 DEBUG anaconda: updating hostname office12.anaconda.englab.brq.redhat.com
>12:34:36,246 INFO anaconda: setting installation environment hostname to office12.anaconda.englab.brq.redhat.com
>12:34:41,640 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.datetime_spoke.DatetimeSpoke object at 0x7f53c28df9d0>
>12:34:41,902 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7f53c290afd0>
>12:34:41,908 INFO anaconda: setting <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7f53c290afd0> status to: Getting list of layouts...
>12:34:41,910 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790> status to: Probing storage...
>12:34:44,051 INFO anaconda: Thread Done: AnaStorageThread (139997764736768)
>12:34:44,055 INFO anaconda: Thread Done: AnaPayloadThread (139997739558656)
>12:34:44,404 INFO anaconda: Thread Done: AnaCustomStorageInit (139997675759360)
>12:34:44,408 INFO anaconda: Thread Done: AnaStorageWatcher (139997909477120)
>12:34:44,527 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790>
>12:34:44,530 INFO anaconda: Running Thread: AnaExecuteStorageThread (139997909477120)
>12:34:44,537 DEBUG anaconda: new disk order: []
>12:34:44,564 DEBUG anaconda: new disk order: []
>12:34:44,866 DEBUG anaconda: stage1 device cannot be of type lvmvg
>12:34:44,871 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:34:44,873 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:34:44,878 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>12:34:44,879 DEBUG anaconda: _is_valid_size(vda) returning True
>12:34:44,881 DEBUG anaconda: _is_valid_location(vda) returning True
>12:34:44,882 DEBUG anaconda: _is_valid_format(vda) returning True
>12:34:44,883 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>12:34:44,885 INFO anaconda: Thread Done: AnaExecuteStorageThread (139997909477120)
>12:34:44,915 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790>
>12:34:44,918 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790> status to: Saving storage configuration...
>12:34:44,924 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790>
>12:34:46,996 DEBUG anaconda: Finished applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f53c2fdea50>> on <dictionary-keyiterator object at 0x7f53c2911158>
>12:34:46,999 INFO anaconda: Thread Done: AnaAddLayoutsInitThread (139997747951360)
>12:34:47,003 INFO anaconda: Thread Done: AnaKeyboardThread (139997756344064)
>12:34:47,142 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7f53c290afd0>
>12:34:47,235 INFO anaconda: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7f53bfdd75d0> status to: Wired (eth0) connected
>12:36:23,717 DEBUG anaconda: Entered spoke: DatetimeSpoke
>12:36:23,723 WARN anaconda: /tmp/updates/pyanaconda/ui/gui/spokes/datetime_spoke.py:753: Warning: Source ID 1365 was not found when attempting to remove it
>  GLib.source_remove(self._update_datetime_timer_id)
>
>12:36:23,819 INFO anaconda: Running Thread: AnaSyncTime_clock.util.phx2.redhat.com (139997756344064)
>12:36:24,155 INFO anaconda: Running Thread: AnaNTPserver3 (139997747951360)
>12:36:24,158 INFO anaconda: Running Thread: AnaNTPserver4 (139997909477120)
>12:36:24,002 INFO anaconda: System time set to Tue Jun 10 12:36:24 2014
>12:36:24,004 INFO anaconda: Thread Done: AnaSyncTime_clock.util.phx2.redhat.com (139997756344064)
>12:36:24,124 INFO anaconda: Thread Done: AnaNTPserver3 (139997747951360)
>12:36:24,284 INFO anaconda: Thread Done: AnaNTPserver4 (139997909477120)
>14:36:26,435 WARN anaconda: /tmp/updates/pyanaconda/ui/gui/spokes/datetime_spoke.py:515: Warning: Source ID 1365 was not found when attempting to remove it
>  GLib.source_remove(self._update_datetime_timer_id)
>
>14:36:26,496 DEBUG anaconda: Left spoke: DatetimeSpoke
>14:36:27,631 DEBUG anaconda: Entered spoke: KeyboardSpoke
>12:36:28,000 INFO anaconda: System time set to Tue Jun 10 12:36:28 2014
>12:36:28,614 DEBUG anaconda: Left spoke: KeyboardSpoke
>12:36:29,514 DEBUG anaconda: Entered spoke: StorageSpoke
>12:36:30,926 DEBUG anaconda: disk free: 992.5 KiB  fs free: 419.72 MiB  sw needs: 476.84 MiB  auto swap: 0 B
>12:36:35,724 INFO anaconda: Running Thread: AnaExecuteStorageThread (139997909477120)
>12:36:35,724 DEBUG anaconda: Left spoke: StorageSpoke
>12:36:35,762 DEBUG anaconda: new disk order: []
>12:36:36,123 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790>
>12:36:36,126 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790> status to: Saving storage configuration...
>12:36:36,227 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>12:36:36,228 DEBUG anaconda: _is_valid_size(vda) returning True
>12:36:36,228 DEBUG anaconda: _is_valid_location(vda) returning True
>12:36:36,228 DEBUG anaconda: _is_valid_format(vda) returning True
>12:36:36,229 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>12:36:37,131 DEBUG anaconda: new disk order: []
>12:36:37,298 DEBUG anaconda: stage1 device cannot be of type lvmvg
>12:36:37,300 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:36:37,300 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:36:37,302 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>12:36:37,303 DEBUG anaconda: _is_valid_size(vda) returning True
>12:36:37,303 DEBUG anaconda: _is_valid_location(vda) returning True
>12:36:37,304 DEBUG anaconda: _is_valid_format(vda) returning True
>12:36:37,304 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>12:36:37,308 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>12:36:37,309 DEBUG anaconda: _is_valid_size(vda) returning True
>12:36:37,309 DEBUG anaconda: _is_valid_location(vda) returning True
>12:36:37,309 DEBUG anaconda: _is_valid_format(vda) returning True
>12:36:37,310 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>12:36:37,310 DEBUG anaconda: _is_valid_disklabel(vda1) returning True
>12:36:37,311 DEBUG anaconda: _is_valid_size(vda1) returning True
>12:36:37,311 DEBUG anaconda: _is_valid_location(vda1) returning True
>12:36:37,312 DEBUG anaconda: _is_valid_partition(vda1) returning True
>12:36:37,313 DEBUG anaconda: _is_valid_format(vda1) returning True
>12:36:37,313 DEBUG anaconda: is_valid_stage2_device(vda1) returning True
>12:36:37,315 DEBUG anaconda: new disk order: []
>12:36:37,484 DEBUG anaconda: stage1 device cannot be of type lvmvg
>12:36:37,486 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:36:37,486 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:36:37,488 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>12:36:37,489 DEBUG anaconda: _is_valid_size(vda) returning True
>12:36:37,489 DEBUG anaconda: _is_valid_location(vda) returning True
>12:36:37,490 DEBUG anaconda: _is_valid_format(vda) returning True
>12:36:37,490 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>12:36:37,492 INFO anaconda: Running Thread: AnaCheckStorageThread (139997747951360)
>12:36:37,493 INFO anaconda: Thread Done: AnaExecuteStorageThread (139997909477120)
>12:36:37,501 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>12:36:37,502 DEBUG anaconda: _is_valid_size(vda) returning True
>12:36:37,502 DEBUG anaconda: _is_valid_location(vda) returning True
>12:36:37,503 DEBUG anaconda: _is_valid_format(vda) returning True
>12:36:37,503 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>12:36:37,503 DEBUG anaconda: _is_valid_disklabel(vda1) returning True
>12:36:37,504 DEBUG anaconda: _is_valid_size(vda1) returning True
>12:36:37,505 DEBUG anaconda: _is_valid_location(vda1) returning True
>12:36:37,505 DEBUG anaconda: _is_valid_partition(vda1) returning True
>12:36:37,506 DEBUG anaconda: _is_valid_format(vda1) returning True
>12:36:37,506 DEBUG anaconda: is_valid_stage2_device(vda1) returning True
>12:36:37,507 INFO anaconda: Thread Done: AnaCheckStorageThread (139997747951360)
>12:36:37,546 INFO anaconda: fs space: 21.07 GiB  needed: 476.84 MiB
>12:36:37,585 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790>
>12:36:37,589 INFO anaconda: fs space: 21.07 GiB  needed: 476.84 MiB
>12:36:37,626 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790>
>12:36:37,627 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790> status to: Checking storage configuration...
>12:36:37,630 INFO anaconda: fs space: 21.07 GiB  needed: 476.84 MiB
>12:36:37,667 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f53bfdcd790>
>12:36:39,559 DEBUG anaconda: Left hub: SummaryHub
>12:36:39,560 DEBUG anaconda: Entered hub: ProgressHub
>12:36:39,660 INFO anaconda: Running Thread: AnaInstallThread (139997747951360)
>12:36:41,538 INFO anaconda: Setting up the installation environment
>12:36:42,683 DEBUG anaconda: Entered spoke: PasswordSpoke
>12:36:46,133 INFO anaconda: Creating disklabel on /dev/vda
>12:36:47,112 INFO anaconda: Creating ext4 on /dev/vda1
>12:36:48,500 INFO anaconda: Creating lvmpv on /dev/vda2
>12:36:50,162 INFO anaconda: Creating swap on /dev/mapper/fedora_office12-swap
>12:36:51,104 INFO anaconda: Creating ext4 on /dev/mapper/fedora_office12-root
>12:36:56,037 DEBUG anaconda: Left spoke: PasswordSpoke
>12:36:56,150 INFO anaconda: executing ostreesetup=<pykickstart.commands.ostreesetup.F21_OSTreeSetup object at 0x7f53ce5dbe90>
>12:39:01,295 INFO anaconda: ostree admin deploy starting
>12:40:12,119 INFO anaconda: ostree admin deploy complete
>12:40:12,206 INFO anaconda: Copying bootloader data: extlinux
>12:40:20,009 INFO anaconda: Installing bootloader
>12:40:20,012 INFO anaconda: bootloader stage1 target device is vda
>12:40:20,014 INFO anaconda: bootloader stage2 target device is vda1
>12:40:21,368 INFO anaconda: Performing post-installation setup tasks
>12:40:21,829 INFO anaconda: Moving /mnt/sysimage/boot/extlinux/extlinux.conf -> /mnt/sysimage/boot/loader/syslinux.cfg
>12:40:48,480 INFO anaconda: Thread Done: AnaInstallThread (139997747951360)
>12:40:48,549 INFO anaconda: Running Thread: AnaConfigurationThread (139997747951360)
>12:40:48,551 INFO anaconda: Configuring installed system
>12:40:51,172 WARN anaconda: Failed to save NTP configuration: Cannot open config file /mnt/sysimage/ostree/deploy/fedora-atomic/deploy/60506ba30bdd1a5070dda106492f4bacd2cf947026f615343e78437608947c71.0/etc/chrony.conf for reading (No such file or directory)
>12:40:53,072 INFO anaconda: Writing network configuration
>12:40:53,076 INFO anaconda: setting installation environment hostname to office12.anaconda.englab.brq.redhat.com
>12:40:53,376 INFO anaconda: Creating users
>12:40:53,379 INFO anaconda: created new libuser.conf at /tmp/libuser.Xd2q1F with instPath="/mnt/sysimage/ostree/deploy/fedora-atomic/deploy/60506ba30bdd1a5070dda106492f4bacd2cf947026f615343e78437608947c71.0"
>12:40:53,615 INFO anaconda: Configuring addons
>12:40:53,617 INFO anaconda: Generating initramfs
>12:40:53,619 INFO anaconda: Running post-installation scripts
>12:40:53,620 INFO anaconda: Running kickstart %%post script(s)
>12:40:54,117 ERR anaconda: Error code 1 running the kickstart script at line 33
>12:40:54,118 INFO anaconda: All kickstart %%post script(s) have been run
>12:40:54,121 DEBUG anaconda: running handleException
>12:40:54,124 DEBUG anaconda: Traceback (most recent call last):
>
>  File "/tmp/updates/pyanaconda/threads.py", line 227, in run
>    threading.Thread.run(self, *args, **kwargs)
>
>  File "/usr/lib64/python2.7/threading.py", line 766, in run
>    self.__target(*self.__args, **self.__kwargs)
>
>  File "/tmp/updates/pyanaconda/install.py", line 116, in doConfiguration
>    _writeKS(ksdata)
>
>  File "/tmp/updates/pyanaconda/install.py", line 47, in _writeKS
>    with open(path, "w") as f:
>
>IOError: [Errno 2] No such file or directory: '/mnt/sysimage/ostree/deploy/fedora-atomic/deploy/60506ba30bdd1a5070dda106492f4bacd2cf947026f615343e78437608947c71.0/root/anaconda-ks.cfg'
>
>12:40:54,127 DEBUG anaconda: Gtk running, queuing exception handler to the main loop
>12:40:54,129 INFO anaconda: Thread Done: AnaConfigurationThread (139997747951360)
>12:40:59,574 INFO anaconda: Running kickstart %%traceback script(s)
>12:40:59,575 INFO anaconda: All kickstart %%traceback script(s) have been run

Actions: View

Attachments on bug 1107679: 907219 | 907220 | 907221 | 907222 | 907223 | 907224 | 907225 | 907226 | 907227 | 907229 | 907230