Attachment 907203 Details for Bug 1107667 – File: anaconda.log

File: anaconda.log

anaconda.log (text/plain), 16.18 KB, created by Radek Vykydal on 2014-06-10 12:36:09 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Radek Vykydal

Created: 2014-06-10 12:36:09 UTC

Size: 16.18 KB

patch

obsolete

>12:30:33,407 INFO anaconda: /sbin/anaconda 21.39-1
>12:30:33,876 INFO anaconda: 4063232 kB (3968 MB) are available
>12:30:33,877 INFO anaconda: check_memory(): total:3968, needed:512, graphical:512
>12:30:33,903 INFO anaconda: anaconda called with cmdline = ['/sbin/anaconda']
>12:30:33,904 INFO anaconda: Default encoding = utf-8 
>12:30:33,965 INFO anaconda: Parsing kickstart: /run/install/ks.cfg
>12:30:35,635 INFO anaconda: Display mode = g
>12:30:35,637 INFO anaconda: 4063232 kB (3968 MB) are available
>12:30:35,637 INFO anaconda: check_memory(): total:3968, needed:512, graphical:512
>12:30:37,256 DEBUG anaconda: X server has signalled a successful start.
>12:30:37,258 WARN anaconda: logo image is missing, using a substitute
>12:30:37,268 INFO anaconda: Starting window manager, pid 1986.
>12:30:37,344 INFO anaconda: Started spice-vdagent.
>12:30:37,910 INFO anaconda: Running Thread: AnaXklWrapperInitThread (140444288210688)
>12:30:38,468 WARN anaconda: module import of __init__ failed: <type 'exceptions.AttributeError'>
>12:30:38,472 WARN anaconda: module import of __init__ failed: <type 'exceptions.AttributeError'>
>12:30:38,473 INFO anaconda: using only installclass _Fedora
>12:30:38,475 INFO anaconda: bootloader EXTLINUX on X86 platform
>12:30:38,527 INFO anaconda: bootloader EXTLINUX on X86 platform
>12:30:38,625 DEBUG anaconda: network: devices found ['em1', 'p3p1', 'p3p2']
>12:30:40,042 DEBUG anaconda: updating hostname gasoline.anaconda.englab.brq.redhat.com
>12:30:40,090 INFO anaconda: Running Thread: AnaStorageThread (140444143580928)
>12:30:40,091 INFO anaconda: Running Thread: AnaTimeInitThread (140444135188224)
>12:30:40,091 INFO anaconda: Running Thread: AnaWaitForConnectingNMThread (140444126795520)
>12:30:40,092 INFO anaconda: Running Thread: AnaPayloadThread (140444118402816)
>12:30:40,323 INFO anaconda: got 6 NTP servers from DHCP
>12:30:40,328 INFO anaconda: Thread Done: AnaWaitForConnectingNMThread (140444126795520)
>12:30:40,329 INFO anaconda: Running Thread: AnaGeolocationRefreshThread (140444126795520)
>12:30:40,331 INFO anaconda: Starting geolocation lookup
>12:30:40,332 INFO anaconda: Geolocation provider: Fedora GeoIP
>12:30:41,002 INFO anaconda: Thread Done: AnaTimeInitThread (140444135188224)
>12:30:42,208 INFO anaconda: Thread Done: AnaXklWrapperInitThread (140444288210688)
>12:30:42,283 INFO anaconda: Geolocation lookup finished in 2.0 seconds
>12:30:42,284 INFO anaconda: got results from geolocation
>12:30:42,285 INFO anaconda: Thread Done: AnaGeolocationRefreshThread (140444126795520)
>12:30:42,757 DEBUG anaconda: network standalone spoke (init): completed: ['p3p2', 'p3p1', 'em1']
>12:30:42,912 DEBUG anaconda: Entered hub: SummaryHub
>12:30:43,926 INFO anaconda: Running Thread: AnaNTPserver1 (140444126795520)
>12:30:43,929 INFO anaconda: Running Thread: AnaNTPserver2 (140444288210688)
>12:30:43,930 INFO anaconda: Running Thread: AnaDateTimeThread (140444135188224)
>12:30:44,037 INFO anaconda: Thread Done: AnaDateTimeThread (140444135188224)
>12:30:44,135 INFO anaconda: Running Thread: AnaAddLayoutsInitThread (140444135188224)
>12:30:44,144 DEBUG anaconda: Starting applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7fbbb0bce850>> on <dictionary-keyiterator object at 0x7fbbb0bc0f18>
>12:30:44,147 INFO anaconda: Running Thread: AnaGtkBatchPre1 (140444056622848)
>12:30:44,191 INFO anaconda: Thread Done: AnaNTPserver2 (140444288210688)
>12:30:44,192 INFO anaconda: Thread Done: AnaNTPserver1 (140444126795520)
>12:30:44,225 INFO anaconda: Thread Done: AnaGtkBatchPre1 (140444056622848)
>12:30:44,393 INFO anaconda: Running Thread: AnaKeyboardThread (140444056622848)
>12:30:44,873 INFO anaconda: Running Thread: AnaStorageWatcher (140444288210688)
>12:30:44,914 INFO anaconda: Running Thread: AnaCustomStorageInit (140444126795520)
>12:30:45,504 DEBUG anaconda: network: GUI, device configuration added: connection 6649fada-82ce-4c29-9540-61f09d52b189 device p3p2
>12:30:45,811 DEBUG anaconda: network: GUI, device configuration added: connection e9b9dad9-55d1-481c-bf5d-23b61a704d28 device p3p1
>12:30:46,125 DEBUG anaconda: network: GUI, device configuration added: connection 65224f90-c732-47b4-9d21-5027e62db648 device em1
>12:30:46,379 DEBUG anaconda: network: GUI, connection 4275dbb3-55e5-4f39-9763-429c053b9e20 found
>12:30:46,490 DEBUG anaconda: network: GUI, connection 78430ed9-e824-4b41-8813-287a36a6dc53 found
>12:30:46,589 DEBUG anaconda: network: GUI, connection b169dd7c-522a-4259-ae48-fa5f75c1ea76 found
>12:30:46,684 DEBUG anaconda: network: GUI, connection 65224f90-c732-47b4-9d21-5027e62db648 found
>12:30:46,685 DEBUG anaconda: network: GUI, connection e9b9dad9-55d1-481c-bf5d-23b61a704d28 found
>12:30:46,686 DEBUG anaconda: network: GUI, connection 6649fada-82ce-4c29-9540-61f09d52b189 found
>12:30:47,696 DEBUG anaconda: updating hostname gasoline.anaconda.englab.brq.redhat.com
>12:30:48,592 INFO anaconda: setting installation environment hostname to gasoline.anaconda.englab.brq.redhat.com
>12:30:52,626 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.datetime_spoke.DatetimeSpoke object at 0x7fbbb1441cd0>
>12:30:52,728 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7fbbb0bc2790>
>12:30:52,729 INFO anaconda: setting <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7fbbb0bc2790> status to: Getting list of layouts...
>12:30:52,730 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650> status to: Probing storage...
>12:30:54,572 INFO anaconda: Thread Done: AnaCustomStorageInit (140444126795520)
>12:30:55,249 DEBUG anaconda: Entered spoke: DatetimeSpoke
>12:30:55,253 WARN anaconda: /tmp/updates/pyanaconda/ui/gui/spokes/datetime_spoke.py:753: Warning: Source ID 2288 was not found when attempting to remove it
>  GLib.source_remove(self._update_datetime_timer_id)
>
>12:30:55,393 INFO anaconda: Running Thread: AnaSyncTime_clock.util.phx2.redhat.com (140444126795520)
>12:30:55,000 INFO anaconda: System time set to Tue Jun 10 12:30:55 2014
>12:30:55,000 INFO anaconda: Thread Done: AnaSyncTime_clock.util.phx2.redhat.com (140444126795520)
>12:30:55,405 INFO anaconda: Thread Done: AnaStorageThread (140444143580928)
>12:30:55,406 INFO anaconda: Thread Done: AnaPayloadThread (140444118402816)
>12:30:55,435 INFO anaconda: Thread Done: AnaStorageWatcher (140444288210688)
>12:30:55,555 INFO anaconda: Running Thread: AnaNTPserver3 (140444288210688)
>12:30:55,556 INFO anaconda: Running Thread: AnaNTPserver4 (140444118402816)
>12:30:55,565 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650>
>12:30:55,565 INFO anaconda: Running Thread: AnaExecuteStorageThread (140444143580928)
>12:30:55,630 DEBUG anaconda: new disk order: []
>12:30:55,673 DEBUG anaconda: new disk order: []
>12:30:55,799 INFO anaconda: Thread Done: AnaNTPserver3 (140444288210688)
>12:30:55,806 INFO anaconda: Thread Done: AnaNTPserver4 (140444118402816)
>12:30:55,940 DEBUG anaconda: stage1 device cannot be of type lvmvg
>12:30:55,942 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:30:55,944 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:30:55,946 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:30:55,950 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>12:30:55,951 DEBUG anaconda: _is_valid_size(sda) returning True
>12:30:55,951 DEBUG anaconda: _is_valid_location(sda) returning True
>12:30:55,952 DEBUG anaconda: _is_valid_format(sda) returning True
>12:30:55,953 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>12:30:55,953 INFO anaconda: Thread Done: AnaExecuteStorageThread (140444143580928)
>12:30:55,970 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650>
>12:30:55,971 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650> status to: Saving storage configuration...
>12:30:55,980 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650>
>14:30:57,843 WARN anaconda: /tmp/updates/pyanaconda/ui/gui/spokes/datetime_spoke.py:515: Warning: Source ID 2288 was not found when attempting to remove it
>  GLib.source_remove(self._update_datetime_timer_id)
>
>14:30:57,884 DEBUG anaconda: Left spoke: DatetimeSpoke
>14:30:58,319 DEBUG anaconda: Finished applying <bound method AddLayoutDialog._addLayout of <pyanaconda.ui.gui.spokes.keyboard.AddLayoutDialog object at 0x7fbbb0bce850>> on <dictionary-keyiterator object at 0x7fbbb0bc0f18>
>14:30:58,320 INFO anaconda: Thread Done: AnaAddLayoutsInitThread (140444135188224)
>14:30:58,321 INFO anaconda: Thread Done: AnaKeyboardThread (140444056622848)
>14:30:58,462 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.keyboard.KeyboardSpoke object at 0x7fbbb0bc2790>
>14:30:58,909 INFO anaconda: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fbbb0bd1c50> status to: Connected: p3p2, p3p1, em1
>14:30:59,678 DEBUG anaconda: Entered spoke: KeyboardSpoke
>12:30:59,000 INFO anaconda: System time set to Tue Jun 10 12:30:59 2014
>12:31:00,659 DEBUG anaconda: Left spoke: KeyboardSpoke
>12:31:01,848 DEBUG anaconda: Entered spoke: StorageSpoke
>12:31:02,963 DEBUG anaconda: disk free: 1016.5 KiB  fs free: 419.78 MiB  sw needs: 476.84 MiB  auto swap: 0 B
>12:31:06,333 INFO anaconda: Running Thread: AnaExecuteStorageThread (140444056622848)
>12:31:06,333 DEBUG anaconda: Left spoke: StorageSpoke
>12:31:06,406 DEBUG anaconda: new disk order: []
>12:31:06,795 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>12:31:06,795 DEBUG anaconda: _is_valid_size(sda) returning True
>12:31:06,796 DEBUG anaconda: _is_valid_location(sda) returning True
>12:31:06,796 DEBUG anaconda: _is_valid_format(sda) returning True
>12:31:06,797 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>12:31:06,951 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650>
>12:31:06,953 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650> status to: Saving storage configuration...
>12:31:08,040 DEBUG anaconda: new disk order: []
>12:31:08,190 DEBUG anaconda: stage1 device cannot be of type lvmvg
>12:31:08,191 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:31:08,192 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:31:08,193 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:31:08,194 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>12:31:08,195 DEBUG anaconda: _is_valid_size(sda) returning True
>12:31:08,195 DEBUG anaconda: _is_valid_location(sda) returning True
>12:31:08,195 DEBUG anaconda: _is_valid_format(sda) returning True
>12:31:08,195 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>12:31:08,199 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>12:31:08,199 DEBUG anaconda: _is_valid_size(sda) returning True
>12:31:08,199 DEBUG anaconda: _is_valid_location(sda) returning True
>12:31:08,200 DEBUG anaconda: _is_valid_format(sda) returning True
>12:31:08,200 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>12:31:08,200 DEBUG anaconda: _is_valid_disklabel(sda1) returning True
>12:31:08,201 DEBUG anaconda: _is_valid_size(sda1) returning True
>12:31:08,201 DEBUG anaconda: _is_valid_location(sda1) returning True
>12:31:08,202 DEBUG anaconda: _is_valid_partition(sda1) returning True
>12:31:08,202 DEBUG anaconda: _is_valid_format(sda1) returning True
>12:31:08,202 DEBUG anaconda: is_valid_stage2_device(sda1) returning True
>12:31:08,214 DEBUG anaconda: new disk order: []
>12:31:08,362 DEBUG anaconda: stage1 device cannot be of type lvmvg
>12:31:08,362 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:31:08,363 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:31:08,364 DEBUG anaconda: stage1 device cannot be of type lvmlv
>12:31:08,365 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>12:31:08,366 DEBUG anaconda: _is_valid_size(sda) returning True
>12:31:08,366 DEBUG anaconda: _is_valid_location(sda) returning True
>12:31:08,366 DEBUG anaconda: _is_valid_format(sda) returning True
>12:31:08,367 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>12:31:08,367 INFO anaconda: Running Thread: AnaCheckStorageThread (140444135188224)
>12:31:08,368 INFO anaconda: Thread Done: AnaExecuteStorageThread (140444056622848)
>12:31:08,374 DEBUG anaconda: _is_valid_disklabel(sda) returning True
>12:31:08,374 DEBUG anaconda: _is_valid_size(sda) returning True
>12:31:08,375 DEBUG anaconda: _is_valid_location(sda) returning True
>12:31:08,375 DEBUG anaconda: _is_valid_format(sda) returning True
>12:31:08,375 DEBUG anaconda: is_valid_stage1_device(sda) returning True
>12:31:08,376 DEBUG anaconda: _is_valid_disklabel(sda1) returning True
>12:31:08,376 DEBUG anaconda: _is_valid_size(sda1) returning True
>12:31:08,377 DEBUG anaconda: _is_valid_location(sda1) returning True
>12:31:08,377 DEBUG anaconda: _is_valid_partition(sda1) returning True
>12:31:08,378 DEBUG anaconda: _is_valid_format(sda1) returning True
>12:31:08,378 DEBUG anaconda: is_valid_stage2_device(sda1) returning True
>12:31:08,379 INFO anaconda: Thread Done: AnaCheckStorageThread (140444135188224)
>12:31:08,444 INFO anaconda: fs space: 50 GiB  needed: 476.84 MiB
>12:31:08,484 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650>
>12:31:08,489 INFO anaconda: fs space: 50 GiB  needed: 476.84 MiB
>12:31:08,529 INFO anaconda: spoke is not ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650>
>12:31:08,530 INFO anaconda: setting <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650> status to: Checking storage configuration...
>12:31:08,535 INFO anaconda: fs space: 50 GiB  needed: 476.84 MiB
>12:31:08,575 INFO anaconda: spoke is ready: <pyanaconda.ui.gui.spokes.storage.StorageSpoke object at 0x7fbbb0bd1650>
>12:31:09,889 DEBUG anaconda: Left hub: SummaryHub
>12:31:09,889 DEBUG anaconda: Entered hub: ProgressHub
>12:31:09,967 INFO anaconda: Running Thread: AnaInstallThread (140444135188224)
>12:31:11,504 INFO anaconda: Setting up the installation environment
>12:31:12,938 DEBUG anaconda: Entered spoke: PasswordSpoke
>12:31:16,085 INFO anaconda: Creating disklabel on /dev/sda
>12:31:16,832 INFO anaconda: Creating ext4 on /dev/sda1
>12:31:18,027 INFO anaconda: Creating lvmpv on /dev/sda2
>12:31:18,311 DEBUG anaconda: Left spoke: PasswordSpoke
>12:31:19,176 INFO anaconda: Creating swap on /dev/mapper/fedora_gasoline-swap
>12:31:19,887 INFO anaconda: Creating ext4 on /dev/mapper/fedora_gasoline-home
>12:31:24,453 INFO anaconda: Creating ext4 on /dev/mapper/fedora_gasoline-root
>12:31:29,040 INFO anaconda: executing ostreesetup=<pykickstart.commands.ostreesetup.F21_OSTreeSetup object at 0x7fbbbc9c9950>
>14:32:43,013 INFO anaconda: ostree admin deploy starting
>14:33:59,412 INFO anaconda: ostree admin deploy complete
>14:33:59,414 INFO anaconda: Copying bootloader data: extlinux
>14:34:05,715 DEBUG anaconda: running handleException
>14:34:05,716 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 227, in doInstall
>    storage.mountFilesystems(skipRoot=True)
>
>  File "/usr/lib/python2.7/site-packages/blivet/__init__.py", line 1777, in mountFilesystems
>    readOnly=readOnly, skipRoot=skipRoot)
>
>  File "/usr/lib/python2.7/site-packages/blivet/__init__.py", line 2740, in mountFilesystems
>    chroot=rootPath)
>
>  File "/usr/lib/python2.7/site-packages/blivet/formats/fs.py", line 832, in setup
>    return self.mount(**kwargs)
>
>  File "/usr/lib/python2.7/site-packages/blivet/formats/fs.py", line 568, in mount
>    util.makedirs(chrootedMountpoint)
>
>  File "/usr/lib/python2.7/site-packages/blivet/util.py", line 281, in makedirs
>    os.makedirs(path, 0755)
>
>  File "/usr/lib64/python2.7/os.py", line 157, in makedirs
>    mkdir(name, mode)
>
>OSError: [Errno 17] File exists: '/mnt/sysimage/ostree/deploy/fedora-atomic/deploy/60506ba30bdd1a5070dda106492f4bacd2cf947026f615343e78437608947c71.0/home'
>
>14:34:05,718 DEBUG anaconda: Gtk running, queuing exception handler to the main loop
>14:34:05,719 INFO anaconda: Thread Done: AnaInstallThread (140444135188224)
>14:34:12,359 INFO anaconda: Running kickstart %%traceback script(s)
>14:34:12,359 INFO anaconda: All kickstart %%traceback script(s) have been run

Actions: View

Attachments on bug 1107667: 907202 | 907203 | 907204 | 907205 | 907206 | 907207 | 907208 | 907209 | 907210 | 907211 | 907212