Attachment 893262 Details for Bug 1095329 – File: anaconda.log

File: anaconda.log

anaconda.log (text/plain), 17.48 KB, created by Igor Gnatenko on 2014-05-07 13:16:59 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Igor Gnatenko

Created: 2014-05-07 13:16:59 UTC

Size: 17.48 KB

patch

obsolete

>12:57:34,900 INFO anaconda: /sbin/anaconda 21.35-1
>12:57:35,615 INFO anaconda: 4063232 kB (3968 MB) are available
>12:57:35,616 INFO anaconda: check_memory(): total:3968, needed:512, graphical:512
>12:57:35,645 INFO anaconda: anaconda called with cmdline = ['/sbin/anaconda']
>12:57:35,646 INFO anaconda: Default encoding = utf-8 
>12:57:35,733 INFO anaconda: Parsing kickstart: /usr/share/anaconda/interactive-defaults.ks
>12:57:36,630 INFO anaconda: Display mode = g
>12:57:36,633 INFO anaconda: 4063232 kB (3968 MB) are available
>12:57:36,634 INFO anaconda: check_memory(): total:3968, needed:512, graphical:512
>12:57:37,961 DEBUG anaconda: X server has signalled a successful start.
>12:57:37,967 WARN anaconda: logo image is missing, using a substitute
>12:57:37,984 INFO anaconda: Starting window manager, pid 1266.
>12:57:38,109 INFO anaconda: Started spice-vdagent.
>12:57:39,568 INFO anaconda: Running Thread: AnaXklWrapperInitThread (140114516145920)
>12:57:39,655 WARN anaconda: module import of __init__ failed: <type 'exceptions.AttributeError'>
>12:57:39,659 WARN anaconda: module import of __init__ failed: <type 'exceptions.AttributeError'>
>12:57:39,661 INFO anaconda: using only installclass _Fedora
>12:57:40,671 INFO anaconda: bootloader GRUB2 on X86 platform
>12:57:40,779 INFO anaconda: bootloader GRUB2 on X86 platform
>12:57:40,871 DEBUG anaconda: network: devices found ['p2p1']
>12:57:41,565 DEBUG anaconda: updating hostname localhost.localdomain
>12:57:41,613 INFO anaconda: Running Thread: AnaStorageThread (140114320340736)
>12:57:41,617 INFO anaconda: Running Thread: AnaTimeInitThread (140114311948032)
>12:57:41,620 INFO anaconda: Running Thread: AnaWaitForConnectingNMThread (140114303555328)
>12:57:41,630 INFO anaconda: Running Thread: AnaPayloadThread (140114295162624)
>12:57:42,020 DEBUG anaconda: updating hostname localhost.localdomain
>12:57:42,182 INFO anaconda: got 0 NTP servers from DHCP
>12:57:42,186 INFO anaconda: Thread Done: AnaWaitForConnectingNMThread (140114303555328)
>12:57:42,204 INFO anaconda: Running Thread: AnaGeolocationRefreshThread (140114303555328)
>12:57:42,247 INFO anaconda: Starting geolocation lookup
>12:57:42,248 INFO anaconda: Geolocation provider: Fedora GeoIP
>12:57:42,363 DEBUG anaconda: Geoloc: URLError for Fedora GeoIP API lookup:
><urlopen error [Errno -2] Name or service not known>
>12:57:42,365 INFO anaconda: Geolocation lookup finished in 0.1 seconds
>12:57:42,368 INFO anaconda: no results from geolocation
>12:57:42,370 INFO anaconda: Thread Done: AnaGeolocationRefreshThread (140114303555328)
>12:57:46,565 INFO anaconda: Thread Done: AnaXklWrapperInitThread (140114516145920)
>12:57:47,544 DEBUG anaconda: Entered spoke: WelcomeLanguageSpoke
>12:57:53,471 INFO anaconda: Thread Done: AnaStorageThread (140114320340736)
>12:57:54,006 INFO anaconda: Thread Done: AnaTimeInitThread (140114311948032)
>12:58:08,316 DEBUG anaconda: network standalone spoke (init): completed: ['p2p1']
>12:58:08,373 DEBUG anaconda: Left spoke: WelcomeLanguageSpoke
>12:58:08,374 DEBUG anaconda: Entered hub: SummaryHub
>12:58:09,148 INFO anaconda: Running Thread: AnaNTPserver1 (140114311948032)
>12:58:09,148 INFO anaconda: Running Thread: AnaNTPserver2 (140114320340736)
>12:58:09,159 INFO anaconda: Running Thread: AnaNTPserver3 (140114516145920)
>12:58:09,164 INFO anaconda: Running Thread: AnaNTPserver4 (140114303555328)
>12:58:09,179 INFO anaconda: fs space: 0 B  needed: 2861.02 MiB
>12:58:09,177 INFO anaconda: Running Thread: AnaDateTimeThread (140114211972864)
>12:58:09,336 INFO anaconda: Thread Done: AnaNTPserver1 (140114311948032)
>12:58:09,347 INFO anaconda: Thread Done: AnaNTPserver2 (140114320340736)
>12:58:09,366 INFO anaconda: Thread Done: AnaNTPserver4 (140114303555328)
>12:58:09,376 INFO anaconda: Thread Done: AnaNTPserver3 (140114516145920)
>12:58:09,460 INFO anaconda: Running Thread: AnaAddLayoutsInitThread (140114516145920)
>12:58:09,463 DEBUG anaconda: Starting applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f6ee382e9d0>> on <dictionary-keyiterator object at 0x7f6ee384ee68>
>12:58:09,465 INFO anaconda: Thread Done: AnaDateTimeThread (140114211972864)
>12:58:09,471 INFO anaconda: Running Thread: AnaGtkBatchPre1 (140114303555328)
>12:58:09,492 INFO anaconda: Thread Done: AnaGtkBatchPre1 (140114303555328)
>12:58:09,588 INFO anaconda: Running Thread: AnaKeyboardThread (140114303555328)
>12:58:09,599 INFO anaconda: fs space: 0 B  needed: 2861.02 MiB
>12:58:09,760 INFO anaconda: fs space: 0 B  needed: 2861.02 MiB
>12:58:09,851 INFO anaconda: Running Thread: AnaSourceWatcher (140114211972864)
>12:58:09,882 INFO anaconda: Running Thread: AnaSoftwareWatcher (140114320340736)
>12:58:09,945 INFO anaconda: Running Thread: AnaStorageWatcher (140114311948032)
>12:58:09,953 INFO anaconda: Thread Done: AnaStorageWatcher (140114311948032)
>12:58:10,002 INFO anaconda: Running Thread: AnaCustomStorageInit (140114311948032)
>12:58:10,643 DEBUG anaconda: network: GUI, device configuration added: connection a920b0c1-23ba-4e0d-bdf2-c77fd8fe6f4c device p2p1
>12:58:10,729 DEBUG anaconda: network: GUI, connection a920b0c1-23ba-4e0d-bdf2-c77fd8fe6f4c found
>12:58:11,073 DEBUG anaconda: updating hostname localhost.localdomain
>12:58:15,360 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.datetime_spoke.DatetimeSpoke object at 0x7f6ee3814910>
>12:58:15,373 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7f6ee3828250>
>12:58:15,374 INFO anaconda: setting <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7f6ee3828250> status to: Getting list of layouts...
>12:58:15,376 INFO anaconda: setting <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7f6ee384fd90> status to: Probing storage...
>12:58:15,377 INFO anaconda: setting <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7f6ee384fd90> status to: Downloading package metadata...
>12:58:15,379 INFO anaconda: setting <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7f6ee384fed0> status to: Downloading package metadata...
>12:58:15,380 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f6ee37d3550> status to: Probing storage...
>12:58:15,391 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f6ee37d3550>
>08:58:19,032 INFO anaconda: Thread Done: AnaCustomStorageInit (140114311948032)
>08:58:23,685 DEBUG anaconda: Finished applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7f6ee382e9d0>> on <dictionary-keyiterator object at 0x7f6ee384ee68>
>08:58:23,686 INFO anaconda: Thread Done: AnaAddLayoutsInitThread (140114516145920)
>08:58:23,688 INFO anaconda: Thread Done: AnaKeyboardThread (140114303555328)
>08:58:23,889 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7f6ee3828250>
>08:58:24,031 DEBUG anaconda: updating hostname localhost.localdomain
>08:58:24,271 DEBUG anaconda: updating hostname localhost.localdomain
>08:58:24,273 INFO anaconda: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7f6ee8f59890> status to: Wired (p2p1) connected
>08:58:25,026 DEBUG anaconda: Entered spoke: KeyboardSpoke
>08:59:07,696 DEBUG anaconda: Left spoke: KeyboardSpoke
>08:59:10,155 DEBUG anaconda: Entered spoke: LangsupportSpoke
>08:59:19,514 INFO anaconda: Thread Done: AnaPayloadThread (140114295162624)
>08:59:20,338 DEBUG anaconda: Setting up repos: []
>08:59:20,474 INFO anaconda: setting <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7f6ee384fed0> status to: Downloading group metadata...
>08:59:20,497 INFO anaconda: Thread Done: AnaSoftwareWatcher (140114320340736)
>08:59:20,498 INFO anaconda: Running Thread: AnaCheckSoftwareThread (140114295162624)
>08:59:20,612 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7f6ee384fed0>
>08:59:20,624 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7f6ee384fed0>
>08:59:20,636 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7f6ee384fd90>
>08:59:20,638 INFO anaconda: setting <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7f6ee384fed0> status to: Checking software dependencies...
>08:59:21,777 DEBUG anaconda: Left spoke: LangsupportSpoke
>08:59:23,577 DEBUG anaconda: Entered spoke: DatetimeSpoke
>08:59:23,584 WARN anaconda: /usr/lib64/python2.7/site-packages/pyanaconda/ui/gui/spokes/datetime_spoke.py:753: Warning: Source ID 4598 was not found when attempting to remove it
>  GLib.source_remove(self._update_datetime_timer_id)
>
>08:59:23,763 INFO anaconda: Running Thread: AnaSyncTime_0.fedora.pool.ntp.org (140114320340736)
>08:59:24,399 INFO anaconda: Running Thread: AnaNTPserver5 (140114303555328)
>08:59:24,406 INFO anaconda: Running Thread: AnaNTPserver6 (140114516145920)
>08:59:24,415 INFO anaconda: Running Thread: AnaNTPserver7 (140114311948032)
>08:59:24,427 INFO anaconda: Running Thread: AnaNTPserver8 (140114136205056)
>08:59:24,580 INFO anaconda: Thread Done: AnaNTPserver6 (140114516145920)
>08:59:24,604 INFO anaconda: Thread Done: AnaNTPserver5 (140114303555328)
>08:59:24,611 INFO anaconda: Thread Done: AnaNTPserver8 (140114136205056)
>08:59:24,612 INFO anaconda: Thread Done: AnaNTPserver7 (140114311948032)
>16:59:27,658 WARN anaconda: /usr/lib64/python2.7/site-packages/pyanaconda/ui/gui/spokes/datetime_spoke.py:515: Warning: Source ID 4598 was not found when attempting to remove it
>  GLib.source_remove(self._update_datetime_timer_id)
>
>16:59:27,773 DEBUG anaconda: Left spoke: DatetimeSpoke
>08:59:28,000 INFO anaconda: System time set to Wed May  7 08:59:28 2014
>16:59:28,000 INFO anaconda: System time set to Wed May  7 16:59:28 2014
>16:59:28,005 INFO anaconda: Thread Done: AnaSyncTime_0.fedora.pool.ntp.org (140114320340736)
>16:59:29,129 DEBUG anaconda: Entered spoke: NetworkSpoke
>16:59:37,943 INFO anaconda: Thread Done: AnaCheckSoftwareThread (140114295162624)
>16:59:37,984 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.software.SoftwareSelectionSpoke object at 0x7f6ee384fed0>
>16:59:37,992 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7f6ee384fd90>
>16:59:38,560 INFO anaconda: Thread Done: AnaSourceWatcher (140114211972864)
>16:59:38,575 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.source.SourceSpoke object at 0x7f6ee384fd90>
>16:59:38,994 DEBUG anaconda: updating hostname admin.postdep.ru
>16:59:39,016 DEBUG anaconda: network: apply ksdata # Network information
>network  --bootproto=dhcp --device=p2p1 --noipv6 --activate
>network  --hostname=admin.postdep.ru
>
>16:59:39,017 INFO anaconda: setting installation environment hostname to admin.postdep.ru
>16:59:39,469 DEBUG anaconda: Left spoke: NetworkSpoke
>16:59:46,008 DEBUG anaconda: Entered spoke: StorageSpoke
>17:04:04,137 DEBUG anaconda: disk free: 1848.5 KiB  fs free: 486.28 MiB  sw needs: 4212.12 MiB  auto swap: 0 B
>17:04:12,093 INFO anaconda: Running Thread: AnaExecuteStorageThread (140114211972864)
>17:04:12,093 DEBUG anaconda: Left spoke: StorageSpoke
>17:04:12,147 DEBUG anaconda: new disk order: []
>17:04:12,577 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>17:04:12,579 DEBUG anaconda: _is_valid_size(sda) returning True
>17:04:12,580 DEBUG anaconda: _is_valid_location(sda) returning True
>17:04:12,581 DEBUG anaconda: _is_valid_format(sda) returning True
>17:04:12,582 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>17:04:12,715 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f6ee37d3550>
>17:04:12,723 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f6ee37d3550> status to: Saving storage configuration...
>17:04:14,552 DEBUG anaconda: new disk order: []
>17:14:15,628 DEBUG anaconda: stage1 device cannot be of type lvmvg
>17:14:15,629 DEBUG anaconda: stage1 device cannot be of type lvmlv
>17:14:15,630 DEBUG anaconda: stage1 device cannot be of type lvmlv
>17:14:15,632 DEBUG anaconda: stage1 device cannot be of type lvmlv
>17:14:15,635 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>17:14:15,635 DEBUG anaconda: _is_valid_size(sda) returning True
>17:14:15,636 DEBUG anaconda: _is_valid_location(sda) returning True
>17:14:15,636 DEBUG anaconda: _is_valid_format(sda) returning True
>17:14:15,637 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>17:14:15,642 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>17:14:15,643 DEBUG anaconda: _is_valid_size(sda) returning True
>17:14:15,643 DEBUG anaconda: _is_valid_location(sda) returning True
>17:14:15,644 DEBUG anaconda: _is_valid_format(sda) returning True
>17:14:15,644 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>17:14:15,645 DEBUG anaconda: _is_valid_disklabel(sda1) returning True
>17:14:15,646 DEBUG anaconda: _is_valid_size(sda1) returning True
>17:14:15,647 DEBUG anaconda: _is_valid_location(sda1) returning True
>17:14:15,648 DEBUG anaconda: _is_valid_partition(sda1) returning True
>17:14:15,649 DEBUG anaconda: _is_valid_format(sda1) returning True
>17:14:15,649 DEBUG anaconda: is_valid_stage2_device(sda1) returning True
>17:14:15,652 DEBUG anaconda: new disk order: []
>17:14:32,294 DEBUG anaconda: stage1 device cannot be of type lvmvg
>17:14:32,296 DEBUG anaconda: stage1 device cannot be of type lvmlv
>17:14:32,297 DEBUG anaconda: stage1 device cannot be of type lvmlv
>17:14:32,299 DEBUG anaconda: stage1 device cannot be of type lvmlv
>17:14:32,302 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>17:14:32,302 DEBUG anaconda: _is_valid_size(sda) returning True
>17:14:32,303 DEBUG anaconda: _is_valid_location(sda) returning True
>17:14:32,303 DEBUG anaconda: _is_valid_format(sda) returning True
>17:14:32,304 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>17:14:32,305 INFO anaconda: Running Thread: AnaCheckStorageThread (140114295162624)
>17:14:32,305 INFO anaconda: Thread Done: AnaExecuteStorageThread (140114211972864)
>17:14:32,313 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>17:14:32,313 DEBUG anaconda: _is_valid_size(sda) returning True
>17:14:32,314 DEBUG anaconda: _is_valid_location(sda) returning True
>17:14:32,314 DEBUG anaconda: _is_valid_format(sda) returning True
>17:14:32,315 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>17:14:32,315 DEBUG anaconda: _is_valid_disklabel(sda1) returning True
>17:14:32,316 DEBUG anaconda: _is_valid_size(sda1) returning True
>17:14:32,317 DEBUG anaconda: _is_valid_location(sda1) returning True
>17:14:32,318 DEBUG anaconda: _is_valid_partition(sda1) returning True
>17:14:32,319 DEBUG anaconda: _is_valid_format(sda1) returning True
>17:14:32,319 DEBUG anaconda: is_valid_stage2_device(sda1) returning True
>17:14:32,321 INFO anaconda: Thread Done: AnaCheckStorageThread (140114295162624)
>17:14:32,356 INFO anaconda: fs space: 50 GiB  needed: 4212.12 MiB
>17:14:32,427 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f6ee37d3550>
>17:14:32,433 INFO anaconda: fs space: 50 GiB  needed: 4212.12 MiB
>17:14:32,502 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f6ee37d3550>
>17:14:32,503 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f6ee37d3550> status to: Checking storage configuration...
>17:14:32,509 INFO anaconda: fs space: 50 GiB  needed: 4212.12 MiB
>17:14:32,578 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7f6ee37d3550>
>17:14:47,912 DEBUG anaconda: Left hub: SummaryHub
>17:14:47,913 DEBUG anaconda: Entered hub: ProgressHub
>17:14:48,023 INFO anaconda: Running Thread: AnaInstallThread (140114295162624)
>17:14:48,981 DEBUG anaconda: Entered spoke: PasswordSpoke
>17:14:49,506 INFO anaconda: Setting up the installation environment
>17:14:52,103 INFO anaconda: Creating disklabel on /dev/sda
>17:14:53,666 INFO anaconda: Creating ext4 on /dev/sda1
>17:14:55,683 DEBUG anaconda: Left spoke: PasswordSpoke
>17:14:55,937 INFO anaconda: Creating lvmpv on /dev/sda2
>17:14:58,663 INFO anaconda: Creating swap on /dev/mapper/fedora_admin-swap
>17:14:59,869 INFO anaconda: Creating ext4 on /dev/mapper/fedora_admin-home
>17:15:04,639 INFO anaconda: Creating ext4 on /dev/mapper/fedora_admin-root
>17:15:09,853 DEBUG anaconda: running handleException
>17:15:09,854 DEBUG anaconda: Traceback (most recent call last):
>
>  File "/usr/lib64/python2.7/site-packages/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 "/usr/lib64/python2.7/site-packages/pyanaconda/install.py", line 167, in doInstall
>    turnOnFilesystems(storage, mountOnly=flags.flags.dirInstall)
>
>  File "/usr/lib/python2.7/site-packages/blivet/__init__.py", line 170, in turnOnFilesystems
>    storage.doIt()
>
>  File "/usr/lib/python2.7/site-packages/blivet/__init__.py", line 371, in doIt
>    self.dumpState("final")
>
>  File "/usr/lib/python2.7/site-packages/blivet/__init__.py", line 1694, in dumpState
>    shelf[key] = [d.dict for d in self.devices]
>
>  File "/usr/lib/python2.7/site-packages/blivet/devices.py", line 701, in dict
>    "format": self.format.dict, "removable": self.removable,
>
>  File "/usr/lib/python2.7/site-packages/blivet/formats/disklabel.py", line 127, in dict
>    "sectorSize": self.partedDevice.sectorSize,
>
>AttributeError: 'NoneType' object has no attribute 'sectorSize'
>
>17:15:09,855 DEBUG anaconda: Gtk running, queuing exception handler to the main loop
>17:15:09,856 INFO anaconda: Thread Done: AnaInstallThread (140114295162624)
>17:15:24,079 INFO anaconda: Running kickstart %%traceback script(s)
>17:15:24,080 INFO anaconda: All kickstart %%traceback script(s) have been run

Actions: View

Attachments on bug 1095329: 893261 | 893262 | 893263 | 893264 | 893265 | 893266 | 893267 | 893268 | 893269 | 893270 | 893271