Attachment 895902 Details for Bug 1098185 – File: anaconda.log

File: anaconda.log

anaconda.log (text/plain), 17.63 KB, created by Vratislav Podzimek on 2014-05-15 12:55:14 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Vratislav Podzimek

Created: 2014-05-15 12:55:14 UTC

Size: 17.63 KB

patch

obsolete

>12:47:18,896 INFO anaconda: /sbin/anaconda 21.36-1
>12:47:20,669 INFO anaconda: 4063232 kB (3968 MB) are available
>12:47:20,669 INFO anaconda: check_memory(): total:3968, needed:512, graphical:512
>12:47:20,720 INFO anaconda: anaconda called with cmdline = ['/sbin/anaconda']
>12:47:20,721 INFO anaconda: Default encoding = utf-8 
>12:47:20,812 INFO anaconda: Parsing kickstart: /usr/share/anaconda/interactive-defaults.ks
>12:47:21,270 INFO anaconda: Display mode = g
>12:47:21,281 INFO anaconda: 4063232 kB (3968 MB) are available
>12:47:21,301 INFO anaconda: check_memory(): total:3968, needed:512, graphical:512
>12:47:25,709 DEBUG anaconda: X server has signalled a successful start.
>12:47:25,710 WARN anaconda: logo image is missing, using a substitute
>12:47:25,713 INFO anaconda: Starting window manager, pid 1584.
>12:47:25,939 INFO anaconda: Started spice-vdagent.
>12:47:25,945 INFO anaconda: Running Thread: AnaXklWrapperInitThread (140689901664000)
>12:47:29,651 WARN anaconda: module import of __init__ failed: <type 'exceptions.AttributeError'>
>12:47:29,653 WARN anaconda: module import of __init__ failed: <type 'exceptions.AttributeError'>
>12:47:29,653 INFO anaconda: using only installclass _Fedora
>12:47:32,531 INFO anaconda: bootloader GRUB2 on X86 platform
>12:47:32,669 INFO anaconda: bootloader GRUB2 on X86 platform
>12:47:32,808 DEBUG anaconda: network: devices found ['ens3']
>12:47:33,537 DEBUG anaconda: updating hostname office46.anaconda.englab.brq.redhat.com
>12:47:33,600 INFO anaconda: Running Thread: AnaStorageThread (140689703429888)
>12:47:33,603 INFO anaconda: Running Thread: AnaTimeInitThread (140689695037184)
>12:47:33,605 INFO anaconda: Running Thread: AnaWaitForConnectingNMThread (140689686644480)
>12:47:33,607 INFO anaconda: Running Thread: AnaPayloadThread (140689678251776)
>12:47:33,832 INFO anaconda: got 2 NTP servers from DHCP
>12:47:33,881 INFO anaconda: Thread Done: AnaWaitForConnectingNMThread (140689686644480)
>12:47:33,899 INFO anaconda: Running Thread: AnaGeolocationRefreshThread (140689686644480)
>12:47:33,904 INFO anaconda: Starting geolocation lookup
>12:47:33,916 INFO anaconda: Geolocation provider: Fedora GeoIP
>12:47:40,384 INFO anaconda: Geolocation lookup finished in 6.5 seconds
>12:47:40,585 INFO anaconda: got results from geolocation
>12:47:40,693 INFO anaconda: Thread Done: AnaGeolocationRefreshThread (140689686644480)
>12:47:43,492 INFO anaconda: Thread Done: AnaXklWrapperInitThread (140689901664000)
>12:47:47,386 DEBUG anaconda: Entered spoke: WelcomeLanguageSpoke
>12:48:12,887 INFO anaconda: Thread Done: AnaStorageThread (140689703429888)
>12:48:14,008 INFO anaconda: Thread Done: AnaTimeInitThread (140689695037184)
>12:48:50,396 INFO anaconda: Thread Done: AnaPayloadThread (140689678251776)
>12:52:07,973 DEBUG anaconda: network standalone spoke (init): completed: ['ens3']
>12:52:08,331 DEBUG anaconda: Left spoke: WelcomeLanguageSpoke
>12:52:08,332 DEBUG anaconda: Entered hub: SummaryHub
>12:52:09,213 INFO anaconda: Running Thread: AnaNTPserver1 (140689678251776)
>12:52:09,218 INFO anaconda: Running Thread: AnaNTPserver2 (140689695037184)
>12:52:09,227 INFO anaconda: Running Thread: AnaDateTimeThread (140689703429888)
>12:52:09,305 INFO anaconda: fs space: 0 B  needed: 2861.02 MiB
>12:52:09,555 INFO anaconda: Thread Done: AnaDateTimeThread (140689703429888)
>12:52:09,567 INFO anaconda: Thread Done: AnaNTPserver1 (140689678251776)
>12:52:09,627 INFO anaconda: Running Thread: AnaAddLayoutsInitThread (140689678251776)
>12:52:09,630 DEBUG anaconda: Starting applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7ff4d9a17a90>> on <dictionary-keyiterator object at 0x7ff4db57edb8>
>12:52:09,631 INFO anaconda: Running Thread: AnaGtkBatchPre1 (140689703429888)
>12:52:09,637 INFO anaconda: Thread Done: AnaGtkBatchPre1 (140689703429888)
>12:52:09,719 INFO anaconda: Thread Done: AnaNTPserver2 (140689695037184)
>12:52:09,790 INFO anaconda: Running Thread: AnaKeyboardThread (140689695037184)
>12:52:09,801 INFO anaconda: fs space: 0 B  needed: 2861.02 MiB
>12:52:09,970 INFO anaconda: fs space: 0 B  needed: 2861.02 MiB
>12:52:10,036 INFO anaconda: Running Thread: AnaSourceWatcher (140689703429888)
>12:52:10,086 INFO anaconda: Running Thread: AnaSoftwareWatcher (140689901664000)
>12:52:10,117 DEBUG anaconda: Setting up repos: []
>12:52:10,179 INFO anaconda: Running Thread: AnaStorageWatcher (140689593132800)
>12:52:10,190 INFO anaconda: Thread Done: AnaStorageWatcher (140689593132800)
>12:52:10,239 INFO anaconda: Running Thread: AnaCustomStorageInit (140689593132800)
>12:52:11,175 DEBUG anaconda: network: GUI, device configuration added: connection 3931ee66-fa42-40c0-87ac-48db16034a93 device ens3
>12:52:11,456 DEBUG anaconda: network: GUI, connection 3931ee66-fa42-40c0-87ac-48db16034a93 found
>12:52:11,457 DEBUG anaconda: network: GUI, connection e1dc9436-191a-4c9f-bf44-957bb88bb314 found
>12:52:12,628 DEBUG anaconda: updating hostname office46.anaconda.englab.brq.redhat.com
>12:52:14,901 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.datetime_spoke.DatetimeSpoke object at 0x7ff4d9a17ad0>
>12:52:14,945 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7ff4d9a174d0>
>12:52:14,946 INFO anaconda: setting <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7ff4d9a174d0> status to: Getting list of layouts...
>12:52:14,947 INFO anaconda: setting <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7ff4d9d25ad0> status to: Probing storage...
>12:52:14,947 INFO anaconda: setting <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7ff4d9d25ad0> status to: Downloading package metadata...
>12:52:14,948 INFO anaconda: setting <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7ff4d9d8a790> status to: Downloading package metadata...
>12:52:14,949 INFO anaconda: setting <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7ff4d9d8a790> status to: Downloading group metadata...
>12:52:14,950 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7ff4d9e45090> status to: Probing storage...
>12:52:14,965 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7ff4d9e45090>
>08:52:16,760 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7ff4d9d8a790>
>08:52:16,776 INFO anaconda: Thread Done: AnaSoftwareWatcher (140689901664000)
>08:52:16,790 INFO anaconda: Running Thread: AnaCheckSoftwareThread (140689584740096)
>08:52:17,074 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7ff4d9d8a790>
>08:52:17,084 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7ff4d9d25ad0>
>08:52:17,085 INFO anaconda: setting <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7ff4d9d8a790> status to: Checking software dependencies...
>08:52:18,370 INFO anaconda: Thread Done: AnaCustomStorageInit (140689593132800)
>08:52:28,850 DEBUG anaconda: Finished applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7ff4d9a17a90>> on <dictionary-keyiterator object at 0x7ff4db57edb8>
>08:52:28,852 INFO anaconda: Thread Done: AnaAddLayoutsInitThread (140689678251776)
>08:52:28,873 INFO anaconda: Thread Done: AnaKeyboardThread (140689695037184)
>08:52:28,923 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7ff4d9a174d0>
>08:52:29,695 INFO anaconda: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7ff4db54e110> status to: Wired (ens3) connected
>08:52:37,548 DEBUG anaconda: Entered spoke: StorageSpoke
>08:52:41,524 DEBUG anaconda: disk free: 18.47 GiB  fs free: 0 B  sw needs: 2861.02 MiB  auto swap: 0 B
>08:52:41,902 INFO anaconda: Running Thread: AnaExecuteStorageThread (140689695037184)
>08:52:41,903 DEBUG anaconda: Left spoke: StorageSpoke
>08:52:42,024 DEBUG anaconda: new disk order: []
>08:52:42,072 DEBUG anaconda: new disk order: []
>08:52:43,818 DEBUG anaconda: Entered spoke: CustomPartitioningSpoke
>08:52:43,867 DEBUG anaconda: stage1 device cannot be of type mdarray
>08:52:43,869 DEBUG anaconda: stage1 device cannot be of type mdarray
>08:52:43,871 DEBUG anaconda: stage1 device cannot be of type mdarray
>08:52:43,875 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>08:52:43,876 DEBUG anaconda: _is_valid_size(vda) returning True
>08:52:43,877 DEBUG anaconda: _is_valid_location(vda) returning True
>08:52:43,877 DEBUG anaconda: _is_valid_format(vda) returning True
>08:52:43,878 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>08:52:43,878 INFO anaconda: Thread Done: AnaExecuteStorageThread (140689695037184)
>08:52:44,776 DEBUG anaconda: ui: devices=['/LiveOS/rootfs.img', '/overlay (deleted)', '/tmp/curl_fetch_url0/my_comps_squashfs.img (deleted)', 'boot_0', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'root_0', 'swap_0', 'vda', 'vda1', 'vda2', 'vda3', 'vdb', 'vdb1', 'vdb2', 'vdb3', 'vdc', 'vdc1', 'vdc2', 'vdc3', 'vdd', 'vdd1', 'vdd2', 'vdd3']
>08:52:44,805 DEBUG anaconda: ui: unused=[]
>08:52:44,805 DEBUG anaconda: ui: new_devices=[]
>08:52:45,511 DEBUG anaconda: page clicked: New Fedora 21 Installation
>08:52:45,512 DEBUG anaconda: show mountpoint: New Fedora 21 Installation
>08:52:45,514 DEBUG anaconda: show mountpoint: New Fedora 21 Installation
>08:52:45,833 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7ff4d9e45090>
>08:52:45,841 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7ff4d9e45090> status to: Saving storage configuration...
>08:52:45,867 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7ff4d9e45090>
>08:52:47,125 DEBUG anaconda: page clicked: Fedora Linux 21 for x86_64
>08:52:47,201 DEBUG anaconda: show mountpoint: Fedora Linux 21 for x86_64
>08:52:47,252 DEBUG anaconda: populate_right_side: existing 512.94 MiB mdarray boot_0 (10) with existing ext4 filesystem
>08:52:47,623 DEBUG anaconda: updated device_disks to ['vda', 'vdb', 'vdc', 'vdd']
>08:52:47,624 DEBUG anaconda: updated device_container_name to None
>08:52:47,625 DEBUG anaconda: updated device_container_raid_level to None
>08:52:47,625 DEBUG anaconda: updated device_container_encrypted to False
>08:52:47,626 DEBUG anaconda: updated device_container_size to 0
>08:52:47,701 DEBUG anaconda: fs type changed: ext4
>08:52:47,714 DEBUG anaconda: device_type_changed: 1 <gi.overrides.Gtk.TreeModelRow object at 0x7ff4d7e93390>
>08:52:47,742 DEBUG anaconda: populate_raid: 1, raid1
>08:52:47,808 DEBUG anaconda: populate_raid: 1, raid1
>08:52:47,810 DEBUG anaconda: populate_right_side: existing 512.94 MiB mdarray boot_0 (10) with existing ext4 filesystem
>08:52:48,234 DEBUG anaconda: updated device_disks to ['vda', 'vdb', 'vdc', 'vdd']
>08:52:48,236 DEBUG anaconda: updated device_container_name to None
>08:52:48,238 DEBUG anaconda: updated device_container_raid_level to None
>08:52:48,238 DEBUG anaconda: updated device_container_encrypted to False
>08:52:48,239 DEBUG anaconda: updated device_container_size to 0
>08:52:48,378 DEBUG anaconda: populate_raid: 1, raid1
>08:52:49,861 INFO anaconda: Thread Done: AnaCheckSoftwareThread (140689584740096)
>08:52:49,929 INFO anaconda: setting <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7ff4d9d8a790> status to: Error checking software dependencies
>08:52:49,937 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7ff4d9d8a790>
>08:52:49,941 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7ff4d9d25ad0>
>08:52:50,069 DEBUG anaconda: removing device 'existing 512.94 MiB mdarray boot_0 (10) with existing ext4 filesystem' from page Fedora Linux 21 for x86_64
>08:52:50,516 INFO anaconda: Thread Done: AnaSourceWatcher (140689703429888)
>08:52:50,585 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7ff4d9d25ad0>
>08:52:52,950 INFO anaconda: ui: removed device boot_0
>08:52:52,998 DEBUG anaconda: ui: devices=['/LiveOS/rootfs.img', '/overlay (deleted)', '/tmp/curl_fetch_url0/my_comps_squashfs.img (deleted)', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'vda', 'vdb', 'vdc', 'vdd']
>08:52:53,000 DEBUG anaconda: ui: unused=[]
>08:52:53,000 DEBUG anaconda: ui: new_devices=[]
>08:52:53,018 DEBUG anaconda: page clicked: New Fedora 21 Installation
>08:52:53,019 DEBUG anaconda: show mountpoint: New Fedora 21 Installation
>08:52:53,021 DEBUG anaconda: show mountpoint: New Fedora 21 Installation
>08:52:56,021 DEBUG anaconda: running automatic partitioning
>08:52:56,331 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>08:52:56,331 DEBUG anaconda: _is_valid_size(vda) returning True
>08:52:56,332 DEBUG anaconda: _is_valid_location(vda) returning True
>08:52:56,332 DEBUG anaconda: _is_valid_format(vda) returning True
>08:52:56,333 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>08:52:58,118 DEBUG anaconda: new disk order: []
>08:52:58,289 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>08:52:58,290 DEBUG anaconda: _is_valid_size(vda) returning True
>08:52:58,291 DEBUG anaconda: _is_valid_location(vda) returning True
>08:52:58,291 DEBUG anaconda: _is_valid_format(vda) returning True
>08:52:58,292 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>08:52:58,292 DEBUG anaconda: finished automatic partitioning
>08:52:58,299 DEBUG anaconda: _is_valid_disklabel(vda) returning True
>08:52:58,299 DEBUG anaconda: _is_valid_size(vda) returning True
>08:52:58,300 DEBUG anaconda: _is_valid_location(vda) returning True
>08:52:58,300 DEBUG anaconda: _is_valid_format(vda) returning True
>08:52:58,301 DEBUG anaconda: is_valid_stage1_device(vda) returning True
>08:52:58,301 DEBUG anaconda: _is_valid_disklabel(vda1) returning True
>08:52:58,302 DEBUG anaconda: _is_valid_size(vda1) returning True
>08:52:58,303 DEBUG anaconda: _is_valid_location(vda1) returning True
>08:52:58,304 DEBUG anaconda: _is_valid_partition(vda1) returning True
>08:52:58,305 DEBUG anaconda: _is_valid_format(vda1) returning True
>08:52:58,306 DEBUG anaconda: is_valid_stage2_device(vda1) returning True
>08:52:58,309 DEBUG anaconda: refreshing ui
>08:52:58,321 DEBUG anaconda: ui: devices=['/LiveOS/rootfs.img', '/overlay (deleted)', '/tmp/curl_fetch_url0/my_comps_squashfs.img (deleted)', 'live-base', 'live-rw', 'loop0', 'loop1', 'loop2', 'vda', 'vda1', 'vda2', 'vdb', 'vdb1', 'vdc', 'vdd']
>08:52:58,323 DEBUG anaconda: ui: unused=[]
>08:52:58,324 DEBUG anaconda: ui: new_devices=['vda1', 'vda2', 'vdb1']
>08:52:58,403 DEBUG anaconda: page clicked: New Fedora 21 Installation
>08:52:58,404 DEBUG anaconda: show mountpoint: New Fedora 21 Installation
>08:52:58,405 DEBUG anaconda: populate_right_side: non-existent 500 MiB partition vda1 (314) with non-existent ext4 filesystem mounted at /boot
>08:52:58,449 DEBUG anaconda: updated device_disks to ['vda', 'vdb', 'vdc', 'vdd']
>08:52:58,450 DEBUG anaconda: updated device_container_name to None
>08:52:58,451 DEBUG anaconda: updated device_container_raid_level to None
>08:52:58,451 DEBUG anaconda: updated device_container_encrypted to False
>08:52:58,452 DEBUG anaconda: updated device_container_size to 0
>08:52:58,467 DEBUG anaconda: device_type_changed: 2 <gi.overrides.Gtk.TreeModelRow object at 0x7ff4d7e94950>
>08:52:58,470 DEBUG anaconda: populate_raid: 2, None
>08:52:58,473 DEBUG anaconda: populate_raid: 2, None
>08:52:58,474 DEBUG anaconda: populate_right_side: non-existent 500 MiB partition vda1 (314) with non-existent ext4 filesystem mounted at /boot
>08:52:58,519 DEBUG anaconda: updated device_disks to ['vda', 'vdb', 'vdc', 'vdd']
>08:52:58,520 DEBUG anaconda: updated device_container_name to None
>08:52:58,520 DEBUG anaconda: updated device_container_raid_level to None
>08:52:58,521 DEBUG anaconda: updated device_container_encrypted to False
>08:52:58,521 DEBUG anaconda: updated device_container_size to 0
>08:52:58,535 DEBUG anaconda: populate_raid: 2, None
>08:52:58,539 DEBUG anaconda: show mountpoint: New Fedora 21 Installation
>08:52:58,539 DEBUG anaconda: finished refreshing ui
>08:52:58,540 DEBUG anaconda: updating space display
>08:52:58,570 DEBUG anaconda: finished updating space display
>08:52:59,797 DEBUG anaconda: removing device 'non-existent 500 MiB partition vda1 (314) with non-existent ext4 filesystem mounted at /boot' from page New Fedora 21 Installation
>08:52:59,874 DEBUG anaconda: running handleException
>08:52:59,896 DEBUG anaconda: Traceback (most recent call last):
>
>  File "/tmp/updates/pyanaconda/ui/gui/spokes/custom.py", line 1783, in on_remove_clicked
>    self._destroy_device(device)
>
>  File "/tmp/updates/pyanaconda/ui/gui/spokes/custom.py", line 120, in decorated
>    return func(*args, **kwargs)
>
>  File "/tmp/updates/pyanaconda/ui/gui/spokes/custom.py", line 1678, in _destroy_device
>    self._storage_playground.destroyDevice(device)
>
>  File "/tmp/updates/blivet/__init__.py", line 1297, in destroyDevice
>    self.devicetree.registerAction(action)
>
>  File "/tmp/updates/blivet/devicetree.py", line 489, in registerAction
>    self._removeDevice(action.device)
>
>  File "/tmp/updates/blivet/devicetree.py", line 431, in _removeDevice
>    dev.disk.format.removePartition(dev.partedPartition)
>
>  File "/tmp/updates/blivet/formats/disklabel.py", line 328, in removePartition
>    self.partedDisk.removePartition(partition)
>
>  File "/usr/lib64/python2.7/site-packages/parted/decorators.py", line 41, in new
>    ret = fn(*args, **kwds)
>
>  File "/usr/lib64/python2.7/site-packages/parted/disk.py", line 269, in removePartition
>    if self.__disk.remove_partition(partition.getPedPartition()):
>
>PartitionException: Partition is not part of the disk it is being removed from
>
>08:52:59,899 DEBUG anaconda: Gtk running, queuing exception handler to the main loop
>08:53:02,765 INFO anaconda: Running kickstart %%traceback script(s)
>08:53:02,766 INFO anaconda: All kickstart %%traceback script(s) have been run

Actions: View

Attachments on bug 1098185: 895901 | 895902 | 895903 | 895904 | 895905 | 895906 | 895907 | 895908 | 895909 | 895910 | 895911