Attachment 626767 Details for Bug 866114 – File: anaconda-tb

File: anaconda-tb

anaconda-tb (text/plain), 245.68 KB, created by Reartes Guillermo on 2012-10-14 03:59:28 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Reartes Guillermo

Created: 2012-10-14 03:59:28 UTC

Size: 245.68 KB

patch

obsolete

>anaconda 18.16 exception report
>Traceback (most recent call first):
>  File "/usr/lib64/python2.7/site-packages/pyanaconda/storage/devicelibs/mdraid.py", line 146, in get_member_space
>    % (level, min_members))
>  File "/usr/lib64/python2.7/site-packages/pyanaconda/storage/__init__.py", line 3312, in device_size
>    level=self.raid_level)
>  File "/usr/lib64/python2.7/site-packages/pyanaconda/storage/__init__.py", line 1848, in setContainerMembers
>    device_space = factory.device_size
>  File "/usr/lib64/python2.7/site-packages/pyanaconda/storage/__init__.py", line 2035, in newDevice
>    members=members)
>  File "/usr/lib64/python2.7/site-packages/pyanaconda/ui/gui/spokes/custom.py", line 834, in _replace_device
>    self.__storage.newDevice(*args, **kwargs)
>  File "/usr/lib64/python2.7/site-packages/pyanaconda/ui/gui/spokes/custom.py", line 978, in _save_right_side
>    selector=selector)
>  File "/usr/lib64/python2.7/site-packages/pyanaconda/ui/gui/spokes/custom.py", line 1506, in on_finish_clicked
>    self._save_right_side(self._current_selector)
>ValueError: raid1 requires at least 2 disks
>
>Local variables in innermost frame:
>size: 500.0
>level: 1
>space: 0
>disks: 1
>min_members: 2
>
>
>Anaconda instance, containing members:
>_instClass: DefaultInstall instance, containing members:
>_intf: GraphicalUserInterface instance, containing members:
>  _intf.instclass: Already dumped (DefaultInstall instance)
>  _intf._hubs: [<class 'pyanaconda.ui.gui.hubs.summary.SummaryHub'>
>, <class 'pyanaconda.ui.gui.hubs.progress.ProgressHub'>
>]
>  _intf.storage: Storage instance, containing members:
>    _intf.storage.clearPartChoice: None
>    _intf.storage.eddDict: {'vda': 128, 'vdb': 129}
>    _intf.storage.dasd: DASD instance, containing members:
>      _intf.storage.dasd.dasdfmt: /sbin/dasdfmt
>      _intf.storage.dasd.commonArgv: [-y, -d, cdl, -b, 4096]
>      _intf.storage.dasd.started: True
>      _intf.storage.dasd.totalCylinders: 0
>      _intf.storage.dasd._maxFormatJobs: 0
>      _intf.storage.dasd._devices: []
>      _intf.storage.dasd._completedCylinders: 0.0
>      _intf.storage.dasd._dasdlist: []
>    _intf.storage.roots: []
>    _intf.storage.zfcp: ZFCP instance, containing members:
>      _intf.storage.zfcp.down: False
>      _intf.storage.zfcp.hasReadConfig: True
>      _intf.storage.zfcp.intf: None
>      _intf.storage.zfcp.fcpdevs: set([])
>    _intf.storage.autoPartEscrowCert: None
>    _intf.storage.autoPartAddBackupPassphrase: False
>    _intf.storage.escrowCertificates: {}
>    _intf.storage.fsset: FSSet instance, containing members:
>      _intf.storage.fsset.origFStab: None
>      _intf.storage.fsset._usb: None
>      _intf.storage.fsset.devicetree: DeviceTree instance, containing members:
>        _intf.storage.fsset.devicetree.populated: True
>        _intf.storage.fsset.devicetree._hidden: []
>        _intf.storage.fsset.devicetree.names: [sr0, vda, vdb, loop0, /run/install/repo/LiveOS/squashfs.img, loop1, /LiveOS/rootfs.img, loop2, /overlay (deleted), loop3, loop4, loop5, loop6, loop7, live-rw]
>        _intf.storage.fsset.devicetree.liveBackingDevice: None
>        _intf.storage.fsset.devicetree.dasd: Already dumped (DASD instance)
>        _intf.storage.fsset.devicetree._cleanup: False
>        _intf.storage.fsset.devicetree.iscsi: iscsi instance, containing members:
>          _intf.storage.fsset.devicetree.iscsi.initiatorSet: False
>          _intf.storage.fsset.devicetree.iscsi.ifaces: {}
>          _intf.storage.fsset.devicetree.iscsi.started: False
>          _intf.storage.fsset.devicetree.iscsi._initiator: Skipped
>          _intf.storage.fsset.devicetree.iscsi.discovered_targets: {}
>          _intf.storage.fsset.devicetree.iscsi.ibftNodes: []
>        _intf.storage.fsset.devicetree.platform: X86 instance, containing members:
>        _intf.storage.fsset.devicetree.shouldClear: <bound method Storage.shouldClear of <pyanaconda.storage.Storage object at 0x1337d50>>
>        _intf.storage.fsset.devicetree.exclusiveDisks: []
>        _intf.storage.fsset.devicetree.unusedRaidMembers: []
>        _intf.storage.fsset.devicetree.mpathFriendlyNames: True
>        _intf.storage.fsset.devicetree.topology: MultipathTopology instance, containing members:
>          _intf.storage.fsset.devicetree.topology._multipaths: []
>          _intf.storage.fsset.devicetree.topology._nondisks: [sr0]
>          _intf.storage.fsset.devicetree.topology._devmap: {'vda': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/pci0000:00/0000:00:06.0/virtio2/block/vda
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/pci0000:00/0000:00:06.0/virtio2/block/vda
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/vda
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: vda
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: None
>, 'sr0': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/sr0
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: sr0
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 0
>, 'vdb': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/pci0000:00/0000:00:07.0/virtio3/block/vdb
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/pci0000:00/0000:00:07.0/virtio3/block/vdb
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/vdb
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: vdb
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: None
>, 'dm-0': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/virtual/block/dm-0
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/virtual/block/dm-0
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/dm-0
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: dm-0
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 0
>, 'loop3': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/virtual/block/loop3
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/virtual/block/loop3
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/loop3
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: loop3
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 3
>, 'loop2': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/virtual/block/loop2
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/virtual/block/loop2
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/loop2
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: loop2
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 2
>, 'loop1': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/virtual/block/loop1
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/virtual/block/loop1
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/loop1
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: loop1
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 1
>, 'loop0': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/virtual/block/loop0
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/virtual/block/loop0
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/loop0
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: loop0
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 0
>, 'loop7': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/virtual/block/loop7
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/virtual/block/loop7
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/loop7
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: loop7
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 7
>, 'loop6': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/virtual/block/loop6
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/virtual/block/loop6
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/loop6
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: loop6
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 6
>, 'loop5': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/virtual/block/loop5
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/virtual/block/loop5
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/loop5
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: loop5
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 5
>, 'loop4': UdevDevice instance, containing members:
>            _intf.storage.fsset.devicetree.topology._devmap.subsystem: block
>            _intf.storage.fsset.devicetree.topology._devmap.syspath: /sys/devices/virtual/block/loop4
>            _intf.storage.fsset.devicetree.topology._devmap.devpath: /devices/virtual/block/loop4
>            _intf.storage.fsset.devicetree.topology._devmap.devnode: /dev/loop4
>            _intf.storage.fsset.devicetree.topology._devmap.sysname: loop4
>            _intf.storage.fsset.devicetree.topology._devmap.devtype: disk
>            _intf.storage.fsset.devicetree.topology._devmap.sysnum: 4
>}
>          _intf.storage.fsset.devicetree.topology._devices: [Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>, Already dumped (UdevDevice instance)
>]
>          _intf.storage.fsset.devicetree.topology._singlepaths: [vda, vdb, loop0, loop1, loop2, loop3, loop4, loop5, loop6, loop7, dm-0]
>          _intf.storage.fsset.devicetree.topology._mpath_topology: {}
>        _intf.storage.fsset.devicetree._ignoredDisks: [loop3, loop4, loop5, loop6, loop7]
>        _intf.storage.fsset.devicetree.protectedDevSpecs: []
>        _intf.storage.fsset.devicetree._devices: [OpticalDevice instance, containing members:
>          _model: QEMU_DVD-ROM
>          controllable: True
>          major: 11
>          kids: 0
>          _partedDevice: parted.Device instance --
>  model: QEMU QEMU DVD-ROM  path: /dev/sr0  type: 1
>  sectorSize: 2048  physicalSectorSize:  2048
>  length: 2187776  openCount: 0  readOnly: True
>  externalMode: False  dirty: False  bootDirty: False
>  host: 2  did: 0  busy: True
>  hardwareGeometry: (136, 255, 63)  biosGeometry: (136, 255, 63)
>  PedDevice: <_ped.Device object at 0x7f7b2f1245f0>
>          uuid: None
>          exists: True
>          fstabComment: Skipped
>          _format: Iso9660FS instance, containing members:
>            _format.uuid: 2012-10-13-20-49-28-00
>            _format.exists: True
>            _format._mountpoint: None
>            _format._majorminor: 011000
>            _format._minInstanceSize: None
>            _format._mountType: iso9660
>            _format.fsprofile: None
>            _format.label: Fedora_18-Beta-TC4_x86_64
>            _format._targetSize: 0
>            _format._size: 0
>            _format.mountopts: None
>            _format.mountpoint: None
>            _format._migrate: False
>            _format._device: /dev/sr0
>          sysfsPath: /devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0
>          _vendor: None
>          _name: sr0
>          _targetSize: 0
>          protected: False
>          parents: []
>          originalFormat: Iso9660FS instance, containing members:
>            originalFormat.uuid: 2012-10-13-20-49-28-00
>            originalFormat.exists: True
>            originalFormat._mountpoint: None
>            originalFormat.mountpoint: None
>            originalFormat._majorminor: None
>            originalFormat.fsprofile: None
>            originalFormat.label: Fedora_18-Beta-TC4_x86_64
>            originalFormat._targetSize: 0
>            originalFormat._minInstanceSize: None
>            originalFormat.mountopts: None
>            originalFormat._size: 0
>            originalFormat._migrate: False
>            originalFormat._device: /dev/sr0
>          bus: Skipped
>          _size: 4273.0
>          _serial: None
>          id: 0
>          minor: 0
>, existing 8000MB disk vda (1) with non-existent msdos disklabel
>, existing 8000MB disk vdb (2) with non-existent msdos disklabel
>, FileDevice instance, containing members:
>          _model: Skipped
>          controllable: False
>          major: 0
>          kids: 1
>          _partedDevice: None
>          uuid: None
>          exists: True
>          fstabComment: Skipped
>          _format: existing None
>          sysfsPath: Skipped
>          _vendor: Skipped
>          _name: /run/install/repo/LiveOS/squashfs.img
>          _targetSize: 0
>          protected: False
>          parents: []
>          originalFormat: existing None
>          bus: Skipped
>          _size: 0
>          _serial: None
>          id: 3
>          minor: 0
>, LoopDevice instance, containing members:
>          _model: Skipped
>          controllable: False
>          major: 0
>          kids: 0
>          _partedDevice: None
>          uuid: None
>          exists: True
>          fstabComment: Skipped
>          _format: existing None
>          sysfsPath: Skipped
>          _vendor: Skipped
>          _name: loop0
>          _targetSize: 0
>          protected: False
>          parents: [Already dumped (FileDevice instance)
>]
>          originalFormat: existing None
>          bus: Skipped
>          _size: 0
>          _serial: None
>          id: 4
>          minor: 0
>, FileDevice instance, containing members:
>          _model: Skipped
>          controllable: False
>          major: 0
>          kids: 1
>          _partedDevice: None
>          uuid: None
>          exists: True
>          fstabComment: Skipped
>          _format: existing None
>          sysfsPath: Skipped
>          _vendor: Skipped
>          _name: /LiveOS/rootfs.img
>          _targetSize: 0
>          protected: False
>          parents: []
>          originalFormat: existing None
>          bus: Skipped
>          _size: 0
>          _serial: None
>          id: 5
>          minor: 0
>, LoopDevice instance, containing members:
>          _model: Skipped
>          controllable: False
>          major: 0
>          kids: 0
>          _partedDevice: None
>          uuid: None
>          exists: True
>          fstabComment: Skipped
>          _format: Ext4FS instance, containing members:
>            _format.uuid: efc02627-ea01-42a6-b346-ceb7c908a182
>            _format.exists: True
>            _format._mountpoint: None
>            _format._majorminor: None
>            _format._minInstanceSize: 0.0
>            _format._mountType: ext4
>            _format.fsprofile: None
>            _format.label: Anaconda
>            _format._targetSize: 1024.0
>            _format._size: 1024.0
>            _format.mountopts: None
>            _format.mountpoint: None
>            _format._migrate: False
>            _format._device: /dev/loop1
>          sysfsPath: Skipped
>          _vendor: Skipped
>          _name: loop1
>          _targetSize: 0
>          protected: False
>          parents: [Already dumped (FileDevice instance)
>]
>          originalFormat: Ext4FS instance, containing members:
>            originalFormat.uuid: efc02627-ea01-42a6-b346-ceb7c908a182
>            originalFormat.exists: True
>            originalFormat._mountpoint: None
>            originalFormat.mountpoint: None
>            originalFormat._majorminor: None
>            originalFormat._mountType: ext4
>            originalFormat.fsprofile: None
>            originalFormat.label: Anaconda
>            originalFormat._targetSize: 1024.0
>            originalFormat._minInstanceSize: 0.0
>            originalFormat.mountopts: None
>            originalFormat._size: 1024.0
>            originalFormat._migrate: False
>            originalFormat._device: /dev/loop1
>          bus: Skipped
>          _size: 0
>          _serial: None
>          id: 6
>          minor: 0
>, FileDevice instance, containing members:
>          _model: Skipped
>          controllable: False
>          major: 0
>          kids: 1
>          _partedDevice: None
>          uuid: None
>          exists: True
>          fstabComment: Skipped
>          _format: existing None
>          sysfsPath: Skipped
>          _vendor: Skipped
>          _name: /overlay (deleted)
>          _targetSize: 0
>          protected: False
>          parents: []
>          originalFormat: existing None
>          bus: Skipped
>          _size: 0
>          _serial: None
>          id: 7
>          minor: 0
>, LoopDevice instance, containing members:
>          _model: Skipped
>          controllable: False
>          major: 0
>          kids: 1
>          _partedDevice: None
>          uuid: None
>          exists: True
>          fstabComment: Skipped
>          _format: existing None
>          sysfsPath: Skipped
>          _vendor: Skipped
>          _name: loop2
>          _targetSize: 0
>          protected: False
>          parents: [Already dumped (FileDevice instance)
>]
>          originalFormat: existing None
>          bus: Skipped
>          _size: 0
>          _serial: None
>          id: 8
>          minor: 0
>, existing 1024MB dm live-rw (9) with existing ext4 filesystem
>, non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>, non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot
>, non-existent 2016MB partition vda2 (12) with non-existent swap
>]
>        _intf.storage.fsset.devicetree.protectedDevNames: []
>        _intf.storage.fsset.devicetree.diskImages: {}
>        _intf.storage.fsset.devicetree._actions: [ActionDestroyFormat instance, containing members:
>          device: Already dumped (DiskDevice instance)
>          origFormat: existing None
>          id: 0
>, ActionCreateFormat instance, containing members:
>          device: Already dumped (DiskDevice instance)
>          origFormat: existing None
>          id: 1
>, ActionDestroyFormat instance, containing members:
>          device: Already dumped (DiskDevice instance)
>          origFormat: existing None
>          id: 2
>, ActionCreateFormat instance, containing members:
>          device: Already dumped (DiskDevice instance)
>          origFormat: existing None
>          id: 3
>, ActionCreateDevice instance, containing members:
>          device: Already dumped (PartitionDevice instance)
>          id: 4
>, ActionCreateFormat instance, containing members:
>          device: Already dumped (PartitionDevice instance)
>          origFormat: non-existent None
>          id: 5
>, ActionCreateDevice instance, containing members:
>          device: Already dumped (PartitionDevice instance)
>          id: 6
>, ActionCreateFormat instance, containing members:
>          device: Already dumped (PartitionDevice instance)
>          origFormat: non-existent None
>          id: 7
>, ActionCreateDevice instance, containing members:
>          device: Already dumped (PartitionDevice instance)
>          id: 8
>, ActionCreateFormat instance, containing members:
>          device: Already dumped (PartitionDevice instance)
>          origFormat: non-existent None
>          id: 9
>]
>      _intf.storage.fsset.preserveLines: []
>      _intf.storage.fsset._devshm: None
>      _intf.storage.fsset._dev: None
>      _intf.storage.fsset.blkidTab: None
>      _intf.storage.fsset._proc: None
>      _intf.storage.fsset.active: False
>      _intf.storage.fsset.cryptTab: None
>      _intf.storage.fsset._devpts: None
>      _intf.storage.fsset._sysfs: None
>      _intf.storage.fsset._selinux: None
>    _intf.storage.platform: X86 instance, containing members:
>    _intf.storage.config: StorageDiscoveryConfig instance, containing members:
>      _intf.storage.config.clearPartType: 2
>      _intf.storage.config.clearNonExistent: False
>      _intf.storage.config.ignoredDisks: []
>      _intf.storage.config.protectedDevSpecs: []
>      _intf.storage.config.ignoreDiskInteractive: False
>      _intf.storage.config.exclusiveDisks: [vda, vdb]
>      _intf.storage.config.clearPartDevices: []
>      _intf.storage.config.zeroMbr: False
>      _intf.storage.config.diskImages: {}
>      _intf.storage.config.clearPartDisks: [vda, vdb]
>      _intf.storage.config.mpathFriendlyNames: True
>      _intf.storage.config.initializeDisks: True
>    _intf.storage.size_sets: []
>    _intf.storage.autoPartType: 0
>    _intf.storage._bootloader: GRUB2 instance, containing members:
>      _intf.storage._bootloader._disk_order: []
>      _intf.storage._bootloader.console_options: Skipped
>      _intf.storage._bootloader.console: Skipped
>      _intf.storage._bootloader.skip_bootloader: False
>      _intf.storage._bootloader.warnings: []
>      _intf.storage._bootloader.chain_images: []
>      _intf.storage._bootloader.stage2_is_preferred_stage1: False
>      _intf.storage._bootloader.stage2_device: Already dumped (PartitionDevice instance)
>      _intf.storage._bootloader.disks: [Already dumped (DiskDevice instance)
>, Already dumped (DiskDevice instance)
>]
>      _intf.storage._bootloader._update_only: False
>      _intf.storage._bootloader._default_image: None
>      _intf.storage._bootloader.platform: Already dumped (X86 instance)
>      _intf.storage._bootloader.encrypted_password: Skipped
>      _intf.storage._bootloader.errors: []
>      _intf.storage._bootloader.stage1_device: Already dumped (DiskDevice instance)
>      _intf.storage._bootloader._timeout: None
>      _intf.storage._bootloader.stage1_disk: Already dumped (DiskDevice instance)
>      _intf.storage._bootloader.password: None
>      _intf.storage._bootloader.dracut_args: 
>      _intf.storage._bootloader.boot_args: $([ -x /usr/sbin/rhcrashkernel-param ] && /usr/sbin/rhcrashkernel-param || :)
>      _intf.storage._bootloader.linux_images: []
>    _intf.storage.devicetree: Already dumped (DeviceTree instance)
>    _intf.storage.defaultFSType: ext4
>    _intf.storage._dumpFile: /tmp/storage.state
>    _intf.storage.services: set([])
>    _intf.storage.data: Skipped
>    _intf.storage.encryptionPassphrase: Skipped
>    _intf.storage.encryptionRetrofit: False
>    _intf.storage._nextID: 3
>    _intf.storage.fcoe: fcoe instance, containing members:
>      _intf.storage.fcoe.started: True
>      _intf.storage.fcoe.nics: []
>      _intf.storage.fcoe.lldpadStarted: False
>    _intf.storage.autoPartitionRequests: [PartSpec instance (0x7f7b2419e7d0) -- 
>  mountpoint = /  lv = True  singlePV = False  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 1024  maxSize = 51200  grow = True
>
>, PartSpec instance (0x7f7b22f7bd50) -- 
>  mountpoint = /home  lv = True  singlePV = False  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 500  maxSize = None  grow = True
>
>, PartSpec instance (0x7f7b257d3e90) -- 
>  mountpoint = /boot  lv = False  singlePV = False  btrfs = False
>  weight = 2000  fstype = ext4  encrypted = False
>  size = 500  maxSize = None  grow = False
>
>, PartSpec instance (0x7f7b22ae7450) -- 
>  mountpoint = None  lv = False  singlePV = False  btrfs = False
>  weight = 5000  fstype = biosboot  encrypted = False
>  size = 1  maxSize = None  grow = False
>
>, PartSpec instance (0x7f7b28e95590) -- 
>  mountpoint = None  lv = True  singlePV = False  btrfs = False
>  weight = 0  fstype = swap  encrypted = True
>  size = 2016  maxSize = None  grow = False
>
>]
>    _intf.storage.doAutoPart: True
>    _intf.storage.iscsi: Already dumped (iscsi instance)
>    _intf.storage.encryptedAutoPart: False
>  _intf._ui: None
>  _intf._actions: Skipped
>  _intf.payload: YumPayload instance, containing members:
>    _intf.payload._groups: Skipped
>    _intf.payload._yum: Skipped
>    _intf.payload.install_device: Already dumped (OpticalDevice instance)
>    _intf.payload._root_dir: /tmp/yum.root
>    _intf.payload._repos_dir: /etc/yum.repos.d,/etc/anaconda.repos.d,/tmp/updates/anaconda.repos.d,/tmp/product/anaconda.repos.d
>    _intf.payload._packages: []
>    _intf.payload._requiredPackages: []
>    _intf.payload._requiredGroups: []
>    _intf.payload._setup: True
>    _intf.payload.txID: 1350186794.63
>    _intf.payload._createdInitrds: False
>    _intf.payload._space_required: 480.26 MB
>    _intf.payload.data: Skipped
>    _intf.payload._kernelVersionList: []
>updateSrc: None
>firewall: Firewall instance, containing members:
>  firewall.portlist: []
>  firewall.servicelist: []
>  firewall.trustdevs: []
>  firewall.enabled: 1
>rootParts: None
>upgradeRoot: None
>_platform: Already dumped (X86 instance)
>id: None
>rescue: False
>mediaDevice: None
>upgrade: False
>_network: None
>methodstr: None
>proxyPassword: None
>desktop: Desktop instance, containing members:
>  desktop.info: {}
>  desktop.always_quote: False
>  desktop.write_quote: True
>  desktop.filename: None
>  desktop.read_unquote: True
>  desktop.runlevel: 3
>  desktop._lines: []
>canReIPL: False
>xdriver: None
>stage2: hd:LABEL=Fedorax2018-Beta-TC4x20x86_64
>rescue_mount: True
>_bootloader: GRUB2 instance, containing members:
>  _bootloader._disk_order: []
>  _bootloader.console_options: Skipped
>  _bootloader.console: Skipped
>  _bootloader.skip_bootloader: False
>  _bootloader.warnings: []
>  _bootloader.chain_images: []
>  _bootloader.stage2_is_preferred_stage1: False
>  _bootloader.disks: []
>  _bootloader._update_only: False
>  _bootloader._default_image: None
>  _bootloader.platform: Already dumped (X86 instance)
>  _bootloader.encrypted_password: Skipped
>  _bootloader.errors: []
>  _bootloader.stage1_device: None
>  _bootloader._timeout: None
>  _bootloader.stage1_disk: None
>  _bootloader.password: None
>  _bootloader.dracut_args: 
>  _bootloader.boot_args: $([ -x /usr/sbin/rhcrashkernel-param ] && /usr/sbin/rhcrashkernel-param || :)
>  _bootloader.linux_images: []
>_payload: Already dumped (YumPayload instance)
>displayMode: g
>simpleFilter: True
>ksdata: Skipped
>proxyUsername: None
>extraModules: []
>mehConfig: Config instance, containing members:
>  mehConfig.programName: anaconda
>  mehConfig.programVersion: 18.16
>  mehConfig.attrSkipList: [_intf._actions, _intf.storage.bootloader.password, _intf.storage.data, _bootloader.encrypted_password, _bootloader.password, payload._groups, payload._yum]
>  mehConfig.localSkipList: [passphrase, password]
>  mehConfig.fileList: [/tmp/anaconda.log, /tmp/packaging.log, /tmp/program.log, /tmp/storage.log, /tmp/ifcfg.log, /tmp/yum.log, /mnt/sysimage/root/install.log, /mnt/sysimage/root/upgrade.log, /proc/cmdline, /tmp/syslog]
>_backend: None
>isHeadless: False
>reIPLMessage: None
>_storage: Already dumped (Storage instance)
>dir: None
>opts: {'noipv6': False, 'noipv4': False, 'updateSrc': None, 'selinux': True, 'module': [], 'syslog': None, 'leavebootorder': False, 'images': [], 'serial': False, 'dmraid': True, 'armPlatform': None, 'virtpconsole': None, 'memcheck': True, 'iscsi': False, 'ksfile': None, 'kbdtype': None, 'runres': None, 'xdriver': None, 'display_mode': 'g', 'stage2': 'hd:LABEL=Fedorax2018-Beta-TC4x20x86_64', 'method': None, 'vncpassword': '', 'vnc': False, 'rescue': False, 'noverifyssl': False, 'autostep': False, 'proxy': None, 'dlabel': False, 'vncconnect': None, 'lang': None, 'liveinst': False, 'loglevel': None, 'isHeadless': False, 'multiLib': False, 'rescue_nomount': False, 'keymap': None, 'mpath': True, 'ibft': True, 'debug': False, 'nofb': None, 'targetArch': None}
>proxy: None
>
>
>/tmp/anaconda.log:
>03:52:12,269 INFO anaconda: /sbin/anaconda 18.16
>03:52:13,438 INFO anaconda: 1048576 kB (1024 MB) are available
>03:52:13,438 INFO anaconda: check_memory(): total:1024, needed:512, graphical:512
>03:52:13,456 INFO anaconda: anaconda called with cmdline = ['/sbin/anaconda']
>03:52:13,457 INFO anaconda: Default encoding = utf-8 
>03:52:13,579 INFO anaconda: ISCSID is /sbin/iscsid
>03:52:13,581 INFO anaconda: no initiator set
>03:52:13,763 INFO anaconda: No FCoE EDD info found: No FCoE boot disk information is found in EDD!
>03:52:13,764 INFO anaconda: no /etc/zfcp.conf; not configuring zfcp
>03:52:13,933 INFO anaconda: Display mode = g
>03:52:13,935 INFO anaconda: 1048576 kB (1024 MB) are available
>03:52:13,935 INFO anaconda: check_memory(): total:1024, needed:512, graphical:512
>03:52:15,656 DEBUG anaconda: X server has signalled a successful start.
>03:52:15,660 INFO anaconda: Starting window manager, pid 676.
>03:52:17,169 INFO anaconda: using only installclass _Fedora
>03:52:18,059 INFO anaconda: Detected 1008M of memory
>03:52:18,059 INFO anaconda: Swap attempt of 2016M
>03:52:18,085 INFO anaconda: Running Thread: AnaPayloadThread (140167195694848)
>03:52:18,088 INFO anaconda: Running Thread: AnaStorageThread (140167187302144)
>03:52:18,327 INFO anaconda: no initiator set
>03:52:18,773 DEBUG anaconda: required disklabel type for vda (15) is None
>03:52:18,773 DEBUG anaconda: default disklabel type for vda is msdos
>03:52:18,774 DEBUG anaconda: selecting msdos disklabel for vda based on size
>03:52:18,881 DEBUG anaconda: required disklabel type for vdb (15) is None
>03:52:18,882 DEBUG anaconda: default disklabel type for vdb is msdos
>03:52:18,883 DEBUG anaconda: selecting msdos disklabel for vdb based on size
>03:52:20,606 INFO anaconda: Thread Done: AnaStorageThread (140167187302144)
>03:52:21,101 INFO anaconda: Thread Done: AnaPayloadThread (140167195694848)
>23:52:37,181 INFO anaconda: Running Thread: AnaSourceWatcher (140166970066688)
>23:52:37,314 INFO anaconda: Running Thread: AnaSoftwareWatcher (140166939596544)
>23:52:37,319 INFO anaconda: Running Thread: AnaStorageWatcher (140166876821248)
>23:52:37,689 INFO anaconda: Thread Done: AnaSourceWatcher (140166970066688)
>23:52:38,960 INFO anaconda: Thread Done: AnaSoftwareWatcher (140166939596544)
>23:52:38,977 INFO anaconda: Running Thread: AnaCheckSoftwareThread (140166970066688)
>23:52:38,983 INFO anaconda: Thread Done: AnaStorageWatcher (140166876821248)
>23:52:39,430 INFO anaconda: Running Thread: AnaNTPserver0 (140166876821248)
>23:52:39,504 INFO anaconda: Running Thread: AnaNTPserver1 (140166939596544)
>23:52:39,613 INFO anaconda: Running Thread: AnaNTPserver2 (140167195694848)
>23:52:39,625 INFO anaconda: Running Thread: AnaNTPserver3 (140166860035840)
>23:52:39,852 INFO anaconda: Thread Done: AnaNTPserver0 (140166876821248)
>23:52:40,053 INFO anaconda: Thread Done: AnaNTPserver3 (140166860035840)
>23:52:40,063 INFO anaconda: Thread Done: AnaNTPserver2 (140167195694848)
>23:52:44,982 INFO anaconda: Thread Done: AnaCheckSoftwareThread (140166970066688)
>23:52:49,564 INFO anaconda: Thread Done: AnaNTPserver1 (140166939596544)
>23:53:14,624 INFO anaconda: Running Thread: AnaCheckSoftwareThread (140167187302144)
>23:53:15,398 INFO anaconda: Thread Done: AnaCheckSoftwareThread (140167187302144)
>23:53:58,491 WARN anaconda: /usr/lib64/python2.7/site-packages/gi/types.py:47: Warning: g_object_disconnect: invalid signal spec "button-release-event"
>  return info.invoke(*args, **kwargs)
>
>23:54:05,763 INFO anaconda: Running Thread: AnaExecuteStorageThread (140167187302144)
>23:54:05,777 DEBUG anaconda: required disklabel type for vda (15) is None
>23:54:05,778 DEBUG anaconda: default disklabel type for vda is msdos
>23:54:05,779 DEBUG anaconda: selecting msdos disklabel for vda based on size
>23:54:05,806 DEBUG anaconda: required disklabel type for vdb (15) is None
>23:54:05,808 DEBUG anaconda: default disklabel type for vdb is msdos
>23:54:05,809 DEBUG anaconda: selecting msdos disklabel for vdb based on size
>23:54:05,909 INFO anaconda: Detected 1008M of memory
>23:54:05,910 INFO anaconda: Swap attempt of 2016M
>23:54:06,182 INFO anaconda: Thread Done: AnaExecuteStorageThread (140167187302144)
>23:54:06,183 INFO anaconda: Running Thread: AnaCheckStorageThread (140166970066688)
>23:54:06,189 INFO anaconda: Thread Done: AnaCheckStorageThread (140166970066688)
>23:54:13,142 INFO anaconda: Running Thread: AnaExecuteStorageThread (140166970066688)
>23:54:13,280 INFO anaconda: Thread Done: AnaExecuteStorageThread (140166970066688)
>23:54:13,324 DEBUG anaconda: ui: devices=['/LiveOS/rootfs.img', '/overlay (deleted)', '/run/install/repo/LiveOS/squashfs.img', 'live-rw', 'loop0', 'loop1', 'loop2', 'sr0', 'vda', 'vda1', 'vda2', 'vdb', 'vdb1']
>23:54:13,324 DEBUG anaconda: ui: unused=[]
>23:54:13,325 DEBUG anaconda: ui: new_devices=['vda2', 'vda1', 'vdb1']
>23:54:13,335 DEBUG anaconda: page clicked: New Fedora 18-Beta-TC4 Installation
>23:54:13,336 DEBUG anaconda: show first mountpoint: New Fedora 18-Beta-TC4 Installation
>23:54:13,336 DEBUG anaconda: populate_right_side: non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot
>23:54:13,339 DEBUG anaconda: min: 1.0  max: 500.0  current: 500.0
>23:54:13,340 DEBUG anaconda: fs type changed: ext4
>23:54:13,344 INFO anaconda: getting device type for Standard Partition
>23:54:13,344 DEBUG anaconda: device_type_changed: 2 Standard Partition
>23:54:13,345 INFO anaconda: getting device type for Standard Partition
>23:54:13,345 DEBUG anaconda: populate_raid: 2, None
>23:54:13,346 INFO anaconda: getting device type for Standard Partition
>23:54:13,347 DEBUG anaconda: populate_raid: 2, None
>23:54:15,770 DEBUG anaconda: current selector: non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot
>23:54:15,772 DEBUG anaconda: new selector: non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>23:54:15,774 DEBUG anaconda: notebook page = 1
>23:54:15,775 INFO anaconda: ui: saving changes to device vda1
>23:54:15,777 DEBUG anaconda: new size: 500.0
>23:54:15,778 DEBUG anaconda: old size: 500.0
>23:54:15,780 INFO anaconda: getting device type for Standard Partition
>23:54:15,781 DEBUG anaconda: new device type: 0
>23:54:15,783 DEBUG anaconda: new fs type: ext4
>23:54:15,784 DEBUG anaconda: old encryption setting: False
>23:54:15,785 DEBUG anaconda: new encryption setting: False
>23:54:15,785 DEBUG anaconda: old mountpoint: /boot
>23:54:15,786 DEBUG anaconda: new mountpoint: /boot
>23:54:15,790 DEBUG anaconda: populate_right_side: non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>23:54:15,792 DEBUG anaconda: min: 1.0  max: 13483.938476  current: 7999.0
>23:54:15,794 INFO anaconda: getting device type for Standard Partition
>23:54:15,794 DEBUG anaconda: populate_raid: 2, None
>23:54:17,441 DEBUG anaconda: current selector: non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>23:54:17,443 DEBUG anaconda: new selector: non-existent 2016MB partition vda2 (12) with non-existent swap
>23:54:17,445 DEBUG anaconda: notebook page = 1
>23:54:17,447 INFO anaconda: ui: saving changes to device vdb1
>23:54:17,449 DEBUG anaconda: new size: 7999.0
>23:54:17,450 DEBUG anaconda: old size: 7999.0
>23:54:17,452 INFO anaconda: getting device type for Standard Partition
>23:54:17,453 DEBUG anaconda: new device type: 0
>23:54:17,455 DEBUG anaconda: new fs type: ext4
>23:54:17,455 DEBUG anaconda: old encryption setting: False
>23:54:17,456 DEBUG anaconda: new encryption setting: False
>23:54:17,457 DEBUG anaconda: old mountpoint: /
>23:54:17,457 DEBUG anaconda: new mountpoint: /
>23:54:17,461 DEBUG anaconda: populate_right_side: non-existent 2016MB partition vda2 (12) with non-existent swap
>23:54:17,463 DEBUG anaconda: min: 1.0  max: 7500.938476  current: 2016.0
>23:54:17,464 DEBUG anaconda: fs type changed: swap
>23:54:17,469 INFO anaconda: getting device type for Standard Partition
>23:54:17,469 DEBUG anaconda: populate_raid: 2, None
>23:54:18,721 DEBUG anaconda: current selector: non-existent 2016MB partition vda2 (12) with non-existent swap
>23:54:18,724 DEBUG anaconda: new selector: non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>23:54:18,725 DEBUG anaconda: notebook page = 1
>23:54:18,726 INFO anaconda: ui: saving changes to device vda2
>23:54:18,726 DEBUG anaconda: new size: 2016.0
>23:54:18,727 DEBUG anaconda: old size: 2016.0
>23:54:18,727 INFO anaconda: getting device type for Standard Partition
>23:54:18,728 DEBUG anaconda: new device type: 0
>23:54:18,728 DEBUG anaconda: new fs type: swap
>23:54:18,729 DEBUG anaconda: old encryption setting: False
>23:54:18,729 DEBUG anaconda: new encryption setting: False
>23:54:18,730 DEBUG anaconda: old mountpoint: 
>23:54:18,730 DEBUG anaconda: new mountpoint: None
>23:54:18,734 DEBUG anaconda: populate_right_side: non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>23:54:18,736 DEBUG anaconda: min: 1.0  max: 13483.938476  current: 7999.0
>23:54:18,737 DEBUG anaconda: fs type changed: ext4
>23:54:18,742 INFO anaconda: getting device type for Standard Partition
>23:54:18,742 DEBUG anaconda: populate_raid: 2, None
>23:54:19,169 DEBUG anaconda: current selector: non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>23:54:19,171 DEBUG anaconda: new selector: non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot
>23:54:19,172 DEBUG anaconda: notebook page = 1
>23:54:19,174 INFO anaconda: ui: saving changes to device vdb1
>23:54:19,175 DEBUG anaconda: new size: 7999.0
>23:54:19,176 DEBUG anaconda: old size: 7999.0
>23:54:19,176 INFO anaconda: getting device type for Standard Partition
>23:54:19,177 DEBUG anaconda: new device type: 0
>23:54:19,177 DEBUG anaconda: new fs type: ext4
>23:54:19,178 DEBUG anaconda: old encryption setting: False
>23:54:19,178 DEBUG anaconda: new encryption setting: False
>23:54:19,179 DEBUG anaconda: old mountpoint: /
>23:54:19,180 DEBUG anaconda: new mountpoint: /
>23:54:19,183 DEBUG anaconda: populate_right_side: non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot
>23:54:19,185 DEBUG anaconda: min: 1.0  max: 5984.938476  current: 500.0
>23:54:19,187 INFO anaconda: getting device type for Standard Partition
>23:54:19,188 DEBUG anaconda: populate_raid: 2, None
>23:54:21,425 DEBUG anaconda: current selector: non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot
>23:54:21,428 DEBUG anaconda: new selector: non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>23:54:21,429 DEBUG anaconda: notebook page = 1
>23:54:21,430 INFO anaconda: ui: saving changes to device vda1
>23:54:21,432 DEBUG anaconda: new size: 500.0
>23:54:21,433 DEBUG anaconda: old size: 500.0
>23:54:21,435 INFO anaconda: getting device type for Standard Partition
>23:54:21,435 DEBUG anaconda: new device type: 0
>23:54:21,436 DEBUG anaconda: new fs type: ext4
>23:54:21,436 DEBUG anaconda: old encryption setting: False
>23:54:21,437 DEBUG anaconda: new encryption setting: False
>23:54:21,437 DEBUG anaconda: old mountpoint: /boot
>23:54:21,438 DEBUG anaconda: new mountpoint: /boot
>23:54:21,442 DEBUG anaconda: populate_right_side: non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>23:54:21,444 DEBUG anaconda: min: 1.0  max: 13483.938476  current: 7999.0
>23:54:21,446 INFO anaconda: getting device type for Standard Partition
>23:54:21,446 DEBUG anaconda: populate_raid: 2, None
>23:54:25,677 INFO anaconda: getting device type for BTRFS
>23:54:25,679 DEBUG anaconda: device_type_changed: 3 BTRFS
>23:54:25,687 INFO anaconda: getting device type for BTRFS
>23:54:25,688 DEBUG anaconda: populate_raid: 3, single
>23:54:25,692 DEBUG anaconda: fs type changed: btrfs
>23:54:29,529 DEBUG anaconda: current selector: non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at /
>23:54:29,531 DEBUG anaconda: new selector: non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot
>23:54:29,533 DEBUG anaconda: notebook page = 1
>23:54:29,534 INFO anaconda: ui: saving changes to device vdb1
>23:54:29,536 DEBUG anaconda: new size: 7999.0
>23:54:29,537 DEBUG anaconda: old size: 7999.0
>23:54:29,539 INFO anaconda: getting device type for BTRFS
>23:54:29,540 DEBUG anaconda: new device type: 1
>23:54:29,542 DEBUG anaconda: new fs type: btrfs
>23:54:29,543 DEBUG anaconda: old encryption setting: False
>23:54:29,544 DEBUG anaconda: new encryption setting: False
>23:54:29,545 DEBUG anaconda: old mountpoint: /
>23:54:29,546 DEBUG anaconda: new mountpoint: /
>23:54:29,549 INFO anaconda: changing device type from 0 to 1
>23:54:29,554 DEBUG anaconda: disks: ['vda', 'vdb']
>23:54:29,936 DEBUG anaconda: populate_right_side: non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot
>23:54:29,937 DEBUG anaconda: min: 1.0  max: 501.938476  current: 500.0
>23:54:29,938 DEBUG anaconda: fs type changed: ext4
>23:54:29,942 INFO anaconda: getting device type for Standard Partition
>23:54:29,943 DEBUG anaconda: device_type_changed: 2 Standard Partition
>23:54:29,943 INFO anaconda: getting device type for Standard Partition
>23:54:29,944 DEBUG anaconda: populate_raid: 2, None
>23:54:29,945 INFO anaconda: getting device type for Standard Partition
>23:54:29,945 DEBUG anaconda: populate_raid: 2, None
>23:54:35,165 INFO anaconda: getting device type for RAID
>23:54:35,167 DEBUG anaconda: device_type_changed: 1 RAID
>23:54:35,171 INFO anaconda: getting device type for RAID
>23:54:35,173 DEBUG anaconda: populate_raid: 1, raid0
>23:54:39,145 INFO anaconda: ui: saving changes to device vda1
>23:54:39,147 DEBUG anaconda: new size: 500.0
>23:54:39,148 DEBUG anaconda: old size: 500.0
>23:54:39,149 INFO anaconda: getting device type for RAID
>23:54:39,150 DEBUG anaconda: new device type: None
>23:54:39,151 DEBUG anaconda: new fs type: ext4
>23:54:39,152 DEBUG anaconda: old encryption setting: False
>23:54:39,153 DEBUG anaconda: new encryption setting: False
>23:54:39,154 DEBUG anaconda: old mountpoint: /boot
>23:54:39,155 DEBUG anaconda: new mountpoint: /boot
>23:54:39,157 INFO anaconda: changing device type from 0 to None
>23:54:39,161 DEBUG anaconda: disks: ['vda']
>
>
>/tmp/packaging.log:
>03:52:17,994 DEBUG packaging: getting release version from tree at None (18)
>03:52:17,995 DEBUG packaging: got a release version of 18
>03:52:20,613 INFO packaging: updating base repo
>03:52:20,872 DEBUG packaging: getting release version from tree at None (18)
>03:52:20,876 DEBUG packaging: got a release version of 18
>03:52:20,877 INFO packaging: configuring base repo
>03:52:20,877 DEBUG packaging: /dev/sr0 is mounted on /run/install/repo
>03:52:20,930 DEBUG packaging: getting release version from tree at file:///run/install/repo (18)
>03:52:20,930 DEBUG packaging: retrieving treeinfo from file:///run/install/repo (proxies: {} ; sslverify: True)
>03:52:20,935 DEBUG packaging: got a release version of 18
>03:52:20,981 DEBUG packaging: adding yum repo anaconda with baseurl file:///run/install/repo and mirrorlist None
>03:52:20,996 DEBUG packaging: disabling repo fedora
>03:52:20,997 DEBUG packaging: disabling repo updates-testing
>03:52:20,997 DEBUG packaging: disabling repo updates
>03:52:20,997 INFO packaging: gathering repo metadata
>03:52:21,001 DEBUG packaging: getting repo metadata for anaconda
>03:52:21,045 DEBUG packaging: getting group info for anaconda
>03:52:21,101 INFO packaging: metadata retrieval complete
>23:52:38,945 DEBUG packaging: deleting package sacks
>23:52:38,978 INFO packaging: checking software selection
>23:52:38,978 DEBUG packaging: deleting package sacks
>23:52:38,979 DEBUG packaging: deleting yum transaction info
>23:52:38,980 DEBUG packaging: select group core
>23:52:39,601 DEBUG packaging: select group core
>23:52:39,602 DEBUG packaging: select group gnome-desktop
>23:52:39,743 DEBUG packaging: select group multimedia
>23:52:39,763 DEBUG packaging: select group input-methods
>23:52:39,770 DEBUG packaging: select group base-x
>23:52:39,784 DEBUG packaging: select group fonts
>23:52:39,842 DEBUG packaging: select group hardware-support
>23:52:39,858 DEBUG packaging: select group dial-up
>23:52:39,863 DEBUG packaging: select group printing
>23:52:39,948 DEBUG packaging: select group firefox
>23:52:39,950 DEBUG packaging: select group standard
>23:52:40,003 DEBUG packaging: select package kernel-PAE
>23:52:40,021 INFO packaging: no kernel-PAE package
>23:52:40,022 DEBUG packaging: select package kernel
>23:52:40,023 INFO packaging: selected kernel
>23:52:40,023 INFO packaging: checking dependencies
>23:52:44,946 DEBUG packaging: success
>23:52:44,981 INFO packaging: 1166 packages selected totalling 2.76 GB
>23:53:14,626 INFO packaging: checking software selection
>23:53:14,626 DEBUG packaging: deleting package sacks
>23:53:14,626 DEBUG packaging: deleting yum transaction info
>23:53:14,655 DEBUG packaging: select group core
>23:53:14,657 DEBUG packaging: select group core
>23:53:14,660 DEBUG packaging: select package kernel-PAE
>23:53:14,977 INFO packaging: no kernel-PAE package
>23:53:14,978 DEBUG packaging: select package kernel
>23:53:14,981 INFO packaging: selected kernel
>23:53:14,981 INFO packaging: checking dependencies
>23:53:15,394 DEBUG packaging: success
>23:53:15,398 INFO packaging: 110 packages selected totalling 480.26 MB
>
>
>/tmp/program.log:
>03:52:13,556 INFO program: Running... udevadm trigger --action=change --subsystem-match=block
>03:52:13,582 INFO program: Running... modprobe fcoe
>03:52:13,711 INFO program: Running... /usr/libexec/fcoe/fcoe_edd.sh -i
>03:52:13,763 INFO program: No FCoE boot disk information is found in EDD!
>03:52:13,765 INFO program: Running... udevadm settle --timeout=300
>03:52:13,833 INFO program: Running... multipath -d
>03:52:13,881 INFO program: Running... multipath -ll
>03:52:15,687 INFO program: Running... metacity --display :1 --sm-disable
>03:52:16,419 ERR program: GLib-GIO-Message: Using the 'memory' GSettings backend.  Your settings will not be saved or shared with other applications.
>03:52:16,581 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,603 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,607 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,609 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,610 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,612 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,616 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,618 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,619 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,620 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,622 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,626 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,628 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,629 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,631 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,635 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,637 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,638 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,639 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,640 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,645 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,651 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,652 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:16,653 ERR program: Window manager warning: Log level 16: Unable to locate theme engine in module_path: "pixmap",
>03:52:18,089 INFO program: Running... udevadm control --property=ANACONDA=1
>03:52:18,130 INFO program: Running... udevadm trigger --action=change --subsystem-match=block
>03:52:18,140 INFO program: Running... systemctl start chronyd.service
>03:52:18,330 INFO program: Running... udevadm settle --timeout=300
>03:52:18,444 INFO program: Running... multipath -d
>03:52:18,497 INFO program: Running... multipath -ll
>03:52:19,556 INFO program: Running... dumpe2fs -h /dev/loop1
>03:52:19,692 INFO program: Filesystem volume name:   Anaconda
>03:52:19,693 INFO program: Last mounted on:          /
>03:52:19,694 INFO program: Filesystem UUID:          efc02627-ea01-42a6-b346-ceb7c908a182
>03:52:19,694 INFO program: Filesystem magic number:  0xEF53
>03:52:19,695 INFO program: Filesystem revision #:    1 (dynamic)
>03:52:19,695 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype extent flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>03:52:19,696 INFO program: Filesystem flags:         signed_directory_hash 
>03:52:19,696 INFO program: Default mount options:    user_xattr acl
>03:52:19,696 INFO program: Filesystem state:         clean
>03:52:19,697 INFO program: Errors behavior:          Continue
>03:52:19,697 INFO program: Filesystem OS type:       Linux
>03:52:19,698 INFO program: Inode count:              65536
>03:52:19,698 INFO program: Block count:              1048576
>03:52:19,699 INFO program: Reserved block count:     0
>03:52:19,699 INFO program: Free blocks:              303262
>03:52:19,699 INFO program: Free inodes:              38174
>03:52:19,700 INFO program: First block:              1
>03:52:19,700 INFO program: Block size:               1024
>03:52:19,701 INFO program: Fragment size:            1024
>03:52:19,701 INFO program: Reserved GDT blocks:      256
>03:52:19,702 INFO program: Blocks per group:         8192
>03:52:19,702 INFO program: Fragments per group:      8192
>03:52:19,703 INFO program: Inodes per group:         512
>03:52:19,703 INFO program: Inode blocks per group:   128
>03:52:19,703 INFO program: Flex block group size:    16
>03:52:19,704 INFO program: Filesystem created:       Sat Oct 13 20:44:50 2012
>03:52:19,704 INFO program: Last mount time:          Sat Oct 13 20:45:04 2012
>03:52:19,705 INFO program: Last write time:          Sat Oct 13 20:45:11 2012
>03:52:19,705 INFO program: Mount count:              2
>03:52:19,706 INFO program: Maximum mount count:      -1
>03:52:19,706 INFO program: Last checked:             Sat Oct 13 20:44:50 2012
>03:52:19,706 INFO program: Check interval:           0 (<none>)
>03:52:19,707 INFO program: Lifetime writes:          32 MB
>03:52:19,707 INFO program: Reserved blocks uid:      0 (user root)
>03:52:19,708 INFO program: Reserved blocks gid:      0 (group root)
>03:52:19,708 INFO program: First inode:              11
>03:52:19,709 INFO program: Inode size:	          256
>03:52:19,709 INFO program: Required extra isize:     28
>03:52:19,710 INFO program: Desired extra isize:      28
>03:52:19,710 INFO program: Journal inode:            8
>03:52:19,711 INFO program: Default directory hash:   half_md4
>03:52:19,711 INFO program: Directory Hash Seed:      05fb4ec8-8c2e-4ade-ad9a-d61ec4b49245
>03:52:19,711 INFO program: Journal backup:           inode blocks
>03:52:19,712 INFO program: Journal features:         (none)
>03:52:19,713 INFO program: Journal size:             32M
>03:52:19,713 INFO program: Journal length:           32768
>03:52:19,713 INFO program: Journal sequence:         0x00000007
>03:52:19,714 INFO program: Journal start:            0
>03:52:19,714 INFO program: 
>03:52:19,715 ERR program: dumpe2fs 1.42.5 (29-Jul-2012)
>03:52:19,716 INFO program: Running... dumpe2fs -h /dev/loop1
>03:52:19,754 INFO program: Filesystem volume name:   Anaconda
>03:52:19,754 INFO program: Last mounted on:          /
>03:52:19,755 INFO program: Filesystem UUID:          efc02627-ea01-42a6-b346-ceb7c908a182
>03:52:19,755 INFO program: Filesystem magic number:  0xEF53
>03:52:19,756 INFO program: Filesystem revision #:    1 (dynamic)
>03:52:19,756 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype extent flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>03:52:19,757 INFO program: Filesystem flags:         signed_directory_hash 
>03:52:19,757 INFO program: Default mount options:    user_xattr acl
>03:52:19,758 INFO program: Filesystem state:         clean
>03:52:19,758 INFO program: Errors behavior:          Continue
>03:52:19,759 INFO program: Filesystem OS type:       Linux
>03:52:19,759 INFO program: Inode count:              65536
>03:52:19,760 INFO program: Block count:              1048576
>03:52:19,760 INFO program: Reserved block count:     0
>03:52:19,760 INFO program: Free blocks:              303262
>03:52:19,761 INFO program: Free inodes:              38174
>03:52:19,761 INFO program: First block:              1
>03:52:19,762 INFO program: Block size:               1024
>03:52:19,762 INFO program: Fragment size:            1024
>03:52:19,763 INFO program: Reserved GDT blocks:      256
>03:52:19,763 INFO program: Blocks per group:         8192
>03:52:19,764 INFO program: Fragments per group:      8192
>03:52:19,765 INFO program: Inodes per group:         512
>03:52:19,765 INFO program: Inode blocks per group:   128
>03:52:19,766 INFO program: Flex block group size:    16
>03:52:19,766 INFO program: Filesystem created:       Sat Oct 13 20:44:50 2012
>03:52:19,767 INFO program: Last mount time:          Sat Oct 13 20:45:04 2012
>03:52:19,767 INFO program: Last write time:          Sat Oct 13 20:45:11 2012
>03:52:19,768 INFO program: Mount count:              2
>03:52:19,768 INFO program: Maximum mount count:      -1
>03:52:19,769 INFO program: Last checked:             Sat Oct 13 20:44:50 2012
>03:52:19,769 INFO program: Check interval:           0 (<none>)
>03:52:19,770 INFO program: Lifetime writes:          32 MB
>03:52:19,770 INFO program: Reserved blocks uid:      0 (user root)
>03:52:19,771 INFO program: Reserved blocks gid:      0 (group root)
>03:52:19,771 INFO program: First inode:              11
>03:52:19,772 INFO program: Inode size:	          256
>03:52:19,772 INFO program: Required extra isize:     28
>03:52:19,773 INFO program: Desired extra isize:      28
>03:52:19,773 INFO program: Journal inode:            8
>03:52:19,773 INFO program: Default directory hash:   half_md4
>03:52:19,774 INFO program: Directory Hash Seed:      05fb4ec8-8c2e-4ade-ad9a-d61ec4b49245
>03:52:19,775 INFO program: Journal backup:           inode blocks
>03:52:19,775 INFO program: Journal features:         (none)
>03:52:19,776 INFO program: Journal size:             32M
>03:52:19,776 INFO program: Journal length:           32768
>03:52:19,777 INFO program: Journal sequence:         0x00000007
>03:52:19,777 INFO program: Journal start:            0
>03:52:19,778 INFO program: 
>03:52:19,778 ERR program: dumpe2fs 1.42.5 (29-Jul-2012)
>03:52:19,778 INFO program: Running... resize2fs -P /dev/loop1
>03:52:19,806 INFO program: Couldn't find valid filesystem superblock.
>03:52:19,807 ERR program: resize2fs 1.42.5 (29-Jul-2012)
>03:52:19,807 ERR program: resize2fs: Device or resource busy while trying to open /dev/loop1
>03:52:19,944 INFO program: Running... udevadm settle --timeout=300
>03:52:20,040 INFO program: Running... dumpe2fs -h /dev/mapper/live-rw
>03:52:20,058 INFO program: Filesystem volume name:   Anaconda
>03:52:20,059 INFO program: Last mounted on:          /sysroot
>03:52:20,059 INFO program: Filesystem UUID:          efc02627-ea01-42a6-b346-ceb7c908a182
>03:52:20,060 INFO program: Filesystem magic number:  0xEF53
>03:52:20,060 INFO program: Filesystem revision #:    1 (dynamic)
>03:52:20,060 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype needs_recovery extent flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>03:52:20,060 INFO program: Filesystem flags:         signed_directory_hash 
>03:52:20,061 INFO program: Default mount options:    user_xattr acl
>03:52:20,061 INFO program: Filesystem state:         clean
>03:52:20,061 INFO program: Errors behavior:          Continue
>03:52:20,061 INFO program: Filesystem OS type:       Linux
>03:52:20,062 INFO program: Inode count:              65536
>03:52:20,062 INFO program: Block count:              1048576
>03:52:20,062 INFO program: Reserved block count:     0
>03:52:20,062 INFO program: Free blocks:              303262
>03:52:20,063 INFO program: Free inodes:              38174
>03:52:20,063 INFO program: First block:              1
>03:52:20,063 INFO program: Block size:               1024
>03:52:20,063 INFO program: Fragment size:            1024
>03:52:20,064 INFO program: Reserved GDT blocks:      256
>03:52:20,064 INFO program: Blocks per group:         8192
>03:52:20,064 INFO program: Fragments per group:      8192
>03:52:20,064 INFO program: Inodes per group:         512
>03:52:20,065 INFO program: Inode blocks per group:   128
>03:52:20,065 INFO program: Flex block group size:    16
>03:52:20,068 INFO program: Filesystem created:       Sat Oct 13 20:44:50 2012
>03:52:20,069 INFO program: Last mount time:          Sun Oct 14 03:52:06 2012
>03:52:20,069 INFO program: Last write time:          Sun Oct 14 03:52:06 2012
>03:52:20,069 INFO program: Mount count:              3
>03:52:20,070 INFO program: Maximum mount count:      -1
>03:52:20,070 INFO program: Last checked:             Sat Oct 13 20:44:50 2012
>03:52:20,070 INFO program: Check interval:           0 (<none>)
>03:52:20,070 INFO program: Lifetime writes:          32 MB
>03:52:20,071 INFO program: Reserved blocks uid:      0 (user root)
>03:52:20,071 INFO program: Reserved blocks gid:      0 (group root)
>03:52:20,071 INFO program: First inode:              11
>03:52:20,071 INFO program: Inode size:	          256
>03:52:20,072 INFO program: Required extra isize:     28
>03:52:20,072 INFO program: Desired extra isize:      28
>03:52:20,072 INFO program: Journal inode:            8
>03:52:20,073 INFO program: Default directory hash:   half_md4
>03:52:20,073 INFO program: Directory Hash Seed:      05fb4ec8-8c2e-4ade-ad9a-d61ec4b49245
>03:52:20,073 INFO program: Journal backup:           inode blocks
>03:52:20,073 INFO program: Journal features:         (none)
>03:52:20,074 INFO program: Journal size:             32M
>03:52:20,074 INFO program: Journal length:           32768
>03:52:20,074 INFO program: Journal sequence:         0x00000008
>03:52:20,075 INFO program: Journal start:            1
>03:52:20,075 INFO program: 
>03:52:20,075 ERR program: dumpe2fs 1.42.5 (29-Jul-2012)
>03:52:20,079 INFO program: Running... dumpe2fs -h /dev/mapper/live-rw
>03:52:20,095 INFO program: Filesystem volume name:   Anaconda
>03:52:20,095 INFO program: Last mounted on:          /sysroot
>03:52:20,095 INFO program: Filesystem UUID:          efc02627-ea01-42a6-b346-ceb7c908a182
>03:52:20,096 INFO program: Filesystem magic number:  0xEF53
>03:52:20,096 INFO program: Filesystem revision #:    1 (dynamic)
>03:52:20,096 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype needs_recovery extent flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>03:52:20,096 INFO program: Filesystem flags:         signed_directory_hash 
>03:52:20,097 INFO program: Default mount options:    user_xattr acl
>03:52:20,097 INFO program: Filesystem state:         clean
>03:52:20,097 INFO program: Errors behavior:          Continue
>03:52:20,098 INFO program: Filesystem OS type:       Linux
>03:52:20,098 INFO program: Inode count:              65536
>03:52:20,098 INFO program: Block count:              1048576
>03:52:20,098 INFO program: Reserved block count:     0
>03:52:20,099 INFO program: Free blocks:              303262
>03:52:20,099 INFO program: Free inodes:              38174
>03:52:20,099 INFO program: First block:              1
>03:52:20,099 INFO program: Block size:               1024
>03:52:20,100 INFO program: Fragment size:            1024
>03:52:20,100 INFO program: Reserved GDT blocks:      256
>03:52:20,100 INFO program: Blocks per group:         8192
>03:52:20,100 INFO program: Fragments per group:      8192
>03:52:20,101 INFO program: Inodes per group:         512
>03:52:20,101 INFO program: Inode blocks per group:   128
>03:52:20,101 INFO program: Flex block group size:    16
>03:52:20,101 INFO program: Filesystem created:       Sat Oct 13 20:44:50 2012
>03:52:20,102 INFO program: Last mount time:          Sun Oct 14 03:52:06 2012
>03:52:20,102 INFO program: Last write time:          Sun Oct 14 03:52:06 2012
>03:52:20,105 INFO program: Mount count:              3
>03:52:20,106 INFO program: Maximum mount count:      -1
>03:52:20,106 INFO program: Last checked:             Sat Oct 13 20:44:50 2012
>03:52:20,106 INFO program: Check interval:           0 (<none>)
>03:52:20,107 INFO program: Lifetime writes:          32 MB
>03:52:20,107 INFO program: Reserved blocks uid:      0 (user root)
>03:52:20,107 INFO program: Reserved blocks gid:      0 (group root)
>03:52:20,107 INFO program: First inode:              11
>03:52:20,108 INFO program: Inode size:	          256
>03:52:20,108 INFO program: Required extra isize:     28
>03:52:20,108 INFO program: Desired extra isize:      28
>03:52:20,109 INFO program: Journal inode:            8
>03:52:20,109 INFO program: Default directory hash:   half_md4
>03:52:20,109 INFO program: Directory Hash Seed:      05fb4ec8-8c2e-4ade-ad9a-d61ec4b49245
>03:52:20,109 INFO program: Journal backup:           inode blocks
>03:52:20,110 INFO program: Journal features:         (none)
>03:52:20,110 INFO program: Journal size:             32M
>03:52:20,110 INFO program: Journal length:           32768
>03:52:20,110 INFO program: Journal sequence:         0x00000008
>03:52:20,111 INFO program: Journal start:            1
>03:52:20,111 INFO program: 
>03:52:20,111 ERR program: dumpe2fs 1.42.5 (29-Jul-2012)
>03:52:20,112 INFO program: Running... resize2fs -P /dev/mapper/live-rw
>03:52:20,131 INFO program: Estimated minimum size of the filesystem: 742936
>03:52:20,132 ERR program: resize2fs 1.42.5 (29-Jul-2012)
>03:52:20,164 INFO program: Running... udevadm settle --timeout=300
>03:52:20,209 INFO program: Running... udevadm settle --timeout=300
>03:52:20,312 INFO program: Running... udevadm settle --timeout=300
>03:52:20,371 INFO program: Running... udevadm settle --timeout=300
>23:52:37,384 INFO program: Running... modprobe btrfs
>23:52:37,477 INFO program: Running... modprobe xfs
>23:52:37,578 INFO program: Running... modprobe vfat
>23:54:05,830 INFO program: Running... udevadm settle --timeout=300
>23:54:13,146 INFO program: Running... udevadm settle --timeout=300
>
>
>/tmp/storage.log:
>03:52:12,850 DEBUG storage: registered device format class SwapSpace as swap
>03:52:12,852 DEBUG storage: registered device format class PPCPRePBoot as prepboot
>03:52:12,853 DEBUG storage: registered device format class LVMPhysicalVolume as lvmpv
>03:52:12,883 DEBUG storage: registered device format class Ext2FS as ext2
>03:52:12,883 DEBUG storage: registered device format class Ext3FS as ext3
>03:52:12,884 DEBUG storage: registered device format class Ext4FS as ext4
>03:52:12,885 DEBUG storage: registered device format class FATFS as vfat
>03:52:12,885 DEBUG storage: registered device format class EFIFS as efi
>03:52:12,886 DEBUG storage: registered device format class BTRFS as btrfs
>03:52:12,886 DEBUG storage: registered device format class GFS2 as gfs2
>03:52:12,887 DEBUG storage: registered device format class JFS as jfs
>03:52:12,887 DEBUG storage: registered device format class ReiserFS as reiserfs
>03:52:12,888 DEBUG storage: registered device format class XFS as xfs
>03:52:12,888 DEBUG storage: registered device format class HFS as hfs
>03:52:12,889 DEBUG storage: registered device format class AppleBootstrapFS as appleboot
>03:52:12,890 DEBUG storage: registered device format class HFSPlus as hfs+
>03:52:12,890 DEBUG storage: registered device format class NTFS as ntfs
>03:52:12,891 DEBUG storage: registered device format class NFS as nfs
>03:52:12,891 DEBUG storage: registered device format class NFSv4 as nfs4
>03:52:12,892 DEBUG storage: registered device format class Iso9660FS as iso9660
>03:52:12,892 DEBUG storage: registered device format class NoDevFS as nodev
>03:52:12,893 DEBUG storage: registered device format class DevPtsFS as devpts
>03:52:12,893 DEBUG storage: registered device format class ProcFS as proc
>03:52:12,894 DEBUG storage: registered device format class SysFS as sysfs
>03:52:12,894 DEBUG storage: registered device format class TmpFS as tmpfs
>03:52:12,895 DEBUG storage: registered device format class BindFS as bind
>03:52:12,895 DEBUG storage: registered device format class SELinuxFS as selinuxfs
>03:52:12,896 DEBUG storage: registered device format class USBFS as usbfs
>03:52:12,929 DEBUG storage: registered device format class LUKS as luks
>03:52:12,931 DEBUG storage: registered device format class DMRaidMember as dmraidmember
>03:52:12,933 DEBUG storage: registered device format class MultipathMember as multipath_member
>03:52:12,938 DEBUG storage: registered device format class DiskLabel as disklabel
>03:52:12,939 DEBUG storage: registered device format class BIOSBoot as biosboot
>03:52:12,941 DEBUG storage: registered device format class MDRaidMember as mdmember
>03:52:13,815 DEBUG storage:      MultipathTopology._build_topology:
>03:52:13,816 DEBUG storage: /etc/multipath.conf contents:
>03:52:13,816 DEBUG storage: # multipath.conf written by anaconda
>03:52:13,817 DEBUG storage: 
>03:52:13,817 DEBUG storage: defaults {
>03:52:13,818 DEBUG storage: 	user_friendly_names yes
>03:52:13,818 DEBUG storage: }
>03:52:13,819 DEBUG storage: blacklist {
>03:52:13,819 DEBUG storage: 	devnode "^(ram|raw|loop|fd|md|dm-|sr|scd|st)[0-9]*"
>03:52:13,820 DEBUG storage: 	devnode "^hd[a-z]"
>03:52:13,820 DEBUG storage: 	devnode "^dcssblk[0-9]*"
>03:52:13,821 DEBUG storage: 	device {
>03:52:13,821 DEBUG storage: 		vendor "DGC"
>03:52:13,822 DEBUG storage: 		product "LUNZ"
>03:52:13,822 DEBUG storage: 	}
>03:52:13,823 DEBUG storage: 	device {
>03:52:13,823 DEBUG storage: 		vendor "IBM"
>03:52:13,824 DEBUG storage: 		product "S/390.*"
>03:52:13,824 DEBUG storage: 	}
>03:52:13,825 DEBUG storage: 	# don't count normal SATA devices as multipaths
>03:52:13,825 DEBUG storage: 	device {
>03:52:13,826 DEBUG storage: 		vendor  "ATA"
>03:52:13,826 DEBUG storage: 	}
>03:52:13,827 DEBUG storage: 	# don't count 3ware devices as multipaths
>03:52:13,827 DEBUG storage: 	device {
>03:52:13,827 DEBUG storage: 		vendor  "3ware"
>03:52:13,828 DEBUG storage: 	}
>03:52:13,828 DEBUG storage: 	device {
>03:52:13,829 DEBUG storage: 		vendor  "AMCC"
>03:52:13,829 DEBUG storage: 	}
>03:52:13,830 DEBUG storage: 	# nor highpoint devices
>03:52:13,830 DEBUG storage: 	device {
>03:52:13,831 DEBUG storage: 		vendor  "HPT"
>03:52:13,831 DEBUG storage: 	}
>03:52:13,832 DEBUG storage: }
>03:52:13,832 DEBUG storage: multipaths {
>03:52:13,833 DEBUG storage: }
>03:52:13,833 DEBUG storage: (end of /etc/multipath.conf)
>03:52:13,898 INFO storage: MultipathTopology: found non-disk device: sr0
>03:52:13,899 INFO storage: MultipathTopology: found singlepath device: vda
>03:52:13,899 INFO storage: MultipathTopology: found singlepath device: vdb
>03:52:13,900 INFO storage: MultipathTopology: found singlepath device: loop0
>03:52:13,901 INFO storage: MultipathTopology: found singlepath device: loop1
>03:52:13,901 INFO storage: MultipathTopology: found singlepath device: loop2
>03:52:13,902 INFO storage: MultipathTopology: found singlepath device: loop3
>03:52:13,903 INFO storage: MultipathTopology: found singlepath device: loop4
>03:52:13,904 INFO storage: MultipathTopology: found singlepath device: loop5
>03:52:13,905 INFO storage: MultipathTopology: found singlepath device: loop6
>03:52:13,905 INFO storage: MultipathTopology: found singlepath device: loop7
>03:52:13,906 INFO storage: MultipathTopology: found singlepath device: dm-0
>03:52:18,328 INFO storage: not going to create backup copy of non-existent /etc/mdadm.conf
>03:52:18,328 INFO storage: DeviceTree.populate: ignoredDisks is [] ; exclusiveDisks is []
>03:52:18,405 DEBUG storage:           MultipathTopology._build_topology:
>03:52:18,415 DEBUG storage: /etc/multipath.conf contents:
>03:52:18,416 DEBUG storage: # multipath.conf written by anaconda
>03:52:18,416 DEBUG storage: 
>03:52:18,417 DEBUG storage: defaults {
>03:52:18,417 DEBUG storage: 	user_friendly_names yes
>03:52:18,418 DEBUG storage: }
>03:52:18,418 DEBUG storage: blacklist {
>03:52:18,419 DEBUG storage: 	devnode "^(ram|raw|loop|fd|md|dm-|sr|scd|st)[0-9]*"
>03:52:18,419 DEBUG storage: 	devnode "^hd[a-z]"
>03:52:18,420 DEBUG storage: 	devnode "^dcssblk[0-9]*"
>03:52:18,420 DEBUG storage: 	device {
>03:52:18,421 DEBUG storage: 		vendor "DGC"
>03:52:18,421 DEBUG storage: 		product "LUNZ"
>03:52:18,422 DEBUG storage: 	}
>03:52:18,422 DEBUG storage: 	device {
>03:52:18,423 DEBUG storage: 		vendor "IBM"
>03:52:18,423 DEBUG storage: 		product "S/390.*"
>03:52:18,424 DEBUG storage: 	}
>03:52:18,424 DEBUG storage: 	# don't count normal SATA devices as multipaths
>03:52:18,425 DEBUG storage: 	device {
>03:52:18,425 DEBUG storage: 		vendor  "ATA"
>03:52:18,427 DEBUG storage: 	}
>03:52:18,428 DEBUG storage: 	# don't count 3ware devices as multipaths
>03:52:18,428 DEBUG storage: 	device {
>03:52:18,429 DEBUG storage: 		vendor  "3ware"
>03:52:18,429 DEBUG storage: 	}
>03:52:18,430 DEBUG storage: 	device {
>03:52:18,430 DEBUG storage: 		vendor  "AMCC"
>03:52:18,431 DEBUG storage: 	}
>03:52:18,440 DEBUG storage: 	# nor highpoint devices
>03:52:18,440 DEBUG storage: 	device {
>03:52:18,441 DEBUG storage: 		vendor  "HPT"
>03:52:18,442 DEBUG storage: 	}
>03:52:18,442 DEBUG storage: }
>03:52:18,442 DEBUG storage: multipaths {
>03:52:18,443 DEBUG storage: }
>03:52:18,443 DEBUG storage: (end of /etc/multipath.conf)
>03:52:18,555 INFO storage: MultipathTopology: found non-disk device: sr0
>03:52:18,555 INFO storage: MultipathTopology: found singlepath device: vda
>03:52:18,556 INFO storage: MultipathTopology: found singlepath device: vdb
>03:52:18,556 INFO storage: MultipathTopology: found singlepath device: loop0
>03:52:18,557 INFO storage: MultipathTopology: found singlepath device: loop1
>03:52:18,558 INFO storage: MultipathTopology: found singlepath device: loop2
>03:52:18,558 INFO storage: MultipathTopology: found singlepath device: loop3
>03:52:18,559 INFO storage: MultipathTopology: found singlepath device: loop4
>03:52:18,559 INFO storage: MultipathTopology: found singlepath device: loop5
>03:52:18,560 INFO storage: MultipathTopology: found singlepath device: loop6
>03:52:18,560 INFO storage: MultipathTopology: found singlepath device: loop7
>03:52:18,561 INFO storage: MultipathTopology: found singlepath device: dm-0
>03:52:18,561 INFO storage: devices to scan: ['sr0', 'vda', 'vdb', 'loop0', 'loop1', 'loop2', 'loop3', 'loop4', 'loop5', 'loop6', 'loop7', 'dm-0']
>03:52:18,565 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVLINKS': '/dev/cdrom /dev/disk/by-id/ata-QEMU_DVD-ROM_QM00003 /dev/disk/by-label/Fedora\\x2018-Beta-TC4\\x20x86_64 /dev/disk/by-uuid/2012-10-13-20-49-28-00',
> 'DEVNAME': 'sr0',
> 'DEVPATH': '/devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0',
> 'DEVTYPE': 'disk',
> 'ID_ATA': '1',
> 'ID_BUS': 'ata',
> 'ID_CDROM': '1',
> 'ID_CDROM_DVD': '1',
> 'ID_CDROM_MEDIA': '1',
> 'ID_CDROM_MEDIA_DVD': '1',
> 'ID_CDROM_MEDIA_TRACK_COUNT_DATA': '1',
> 'ID_FS_LABEL': 'Fedora_18-Beta-TC4_x86_64',
> 'ID_FS_LABEL_ENC': 'Fedora\\x2018-Beta-TC4\\x20x86_64',
> 'ID_FS_TYPE': 'iso9660',
> 'ID_FS_USAGE': 'filesystem',
> 'ID_FS_UUID': '2012-10-13-20-49-28-00',
> 'ID_FS_UUID_ENC': '2012-10-13-20-49-28-00',
> 'ID_MODEL': 'QEMU_DVD-ROM',
> 'ID_MODEL_ENC': 'QEMU\\x20DVD-ROM\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20',
> 'ID_PART_TABLE_TYPE': 'dos',
> 'ID_REVISION': '1.0.1',
> 'ID_SERIAL': 'QEMU_DVD-ROM_QM00003',
> 'ID_SERIAL_SHORT': 'QM00003',
> 'ID_TYPE': 'cd',
> 'MAJOR': '11',
> 'MINOR': '0',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'TAGS': ':seat:systemd:uaccess:',
> 'USEC_INITIALIZED': '64138',
> 'name': 'sr0',
> 'symlinks': ['/dev/cdrom',
>              '/dev/disk/by-id/ata-QEMU_DVD-ROM_QM00003',
>              '/dev/disk/by-label/Fedora\\x2018-Beta-TC4\\x20x86_64',
>              '/dev/disk/by-uuid/2012-10-13-20-49-28-00'],
> 'sysfs_path': '/devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0'} ; name: sr0 ;
>03:52:18,574 INFO storage: scanning sr0 (/devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0)...
>03:52:18,575 DEBUG storage:           DeviceTree.getDeviceByName: name: sr0 ;
>03:52:18,577 DEBUG storage:           DeviceTree.getDeviceByName returned None
>03:52:18,577 INFO storage: sr0 is a cdrom
>03:52:18,578 DEBUG storage:           DeviceTree.addUdevOpticalDevice:
>03:52:18,586 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:18,589 DEBUG storage:               OpticalDevice._setFormat: sr0 ; current: None ; type: None ;
>03:52:18,595 INFO storage: added cdrom sr0 (id 0) to device tree
>03:52:18,597 DEBUG storage:           OpticalDevice.mediaPresent: sr0 ; status: True ;
>03:52:18,601 DEBUG storage:           DeviceTree.handleUdevDeviceFormat: name: sr0 ;
>03:52:18,602 DEBUG storage:            DeviceTree.handleUdevDiskLabelFormat: device: sr0 ; label_type: dos ;
>03:52:18,608 DEBUG storage:             OpticalDevice.setup: sr0 ; status: True ; controllable: True ; orig: False ;
>03:52:18,610 DEBUG storage:              DiskLabel.__init__: device: /dev/sr0 ; labelType: dos ; exists: True ;
>03:52:18,636 WARN storage: disklabel detected but not usable on sr0
>03:52:18,637 INFO storage: type detected on 'sr0' is 'iso9660'
>03:52:18,660 DEBUG storage:              Iso9660FS.supported: supported: True ;
>03:52:18,662 DEBUG storage: getFormat('iso9660') returning Iso9660FS instance
>03:52:18,677 DEBUG storage:             OpticalDevice._setFormat: sr0 ; current: None ; type: iso9660 ;
>03:52:18,684 DEBUG storage:              OpticalDevice.mediaPresent: sr0 ; status: True ;
>03:52:18,694 DEBUG storage: looking up parted Device: /dev/sr0
>03:52:18,696 INFO storage: got device: OpticalDevice instance (0x7f7b2f0e0490) --
>  name = sr0  status = True  kids = 0 id = 0
>  parents = []
>  uuid = None  size = 4273.0
>  format = existing iso9660 filesystem
>  major = 11  minor = 0  exists = True  protected = False
>  sysfs path = /devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:0/1:0:0:0/block/sr0  partedDevice = parted.Device instance --
>  model: QEMU QEMU DVD-ROM  path: /dev/sr0  type: 1
>  sectorSize: 2048  physicalSectorSize:  2048
>  length: 2187776  openCount: 0  readOnly: True
>  externalMode: False  dirty: False  bootDirty: False
>  host: 2  did: 0  busy: True
>  hardwareGeometry: (136, 255, 63)  biosGeometry: (136, 255, 63)
>  PedDevice: <_ped.Device object at 0x7f7b2f1245f0>
>  target size = 0  path = /dev/sr0
>  format args = []  originalFormat = None
>03:52:18,698 DEBUG storage:             Iso9660FS.supported: supported: True ;
>03:52:18,702 INFO storage: got format: Iso9660FS instance (0x7f7b2f0ff6d0) --
>  type = iso9660  name = iso9660  status = False
>  device = /dev/sr0  uuid = 2012-10-13-20-49-28-00  exists = True
>  options = ro  supported = True  formattable = False  resizable = False
>  mountpoint = None  mountopts = None
>  label = Fedora_18-Beta-TC4_x86_64  size = 0  targetSize = 0
>
>03:52:18,704 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVNAME': 'vda',
> 'DEVPATH': '/devices/pci0000:00/0000:00:06.0/virtio2/block/vda',
> 'DEVTYPE': 'disk',
> 'MAJOR': '252',
> 'MINOR': '0',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'TAGS': ':systemd:',
> 'USEC_INITIALIZED': '4248',
> 'name': 'vda',
> 'symlinks': [],
> 'sysfs_path': '/devices/pci0000:00/0000:00:06.0/virtio2/block/vda'} ; name: vda ;
>03:52:18,705 INFO storage: scanning vda (/devices/pci0000:00/0000:00:06.0/virtio2/block/vda)...
>03:52:18,706 DEBUG storage:           DeviceTree.getDeviceByName: name: vda ;
>03:52:18,711 DEBUG storage:           DeviceTree.getDeviceByName returned None
>03:52:18,712 DEBUG storage:           DeviceTree.addUdevDiskDevice: name: vda ;
>03:52:18,714 INFO storage: vda is a disk
>03:52:18,714 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:18,750 DEBUG storage:               DiskDevice._setFormat: vda ; current: None ; type: None ;
>03:52:18,755 INFO storage: added disk vda (id 1) to device tree
>03:52:18,756 DEBUG storage: looking up parted Device: /dev/vda
>03:52:18,758 DEBUG storage:           DeviceTree.handleUdevDeviceFormat: name: vda ;
>03:52:18,762 DEBUG storage:            DeviceTree.handleUdevDiskLabelFormat: device: vda ; label_type: None ;
>03:52:18,763 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:18,768 DEBUG storage:             DiskDevice.setup: vda ; status: True ; controllable: True ; orig: False ;
>03:52:18,776 DEBUG storage:              DiskLabel.__init__: device: /dev/vda ; labelType: msdos ; exists: True ;
>03:52:18,805 INFO storage: no usable disklabel on vda
>03:52:18,806 DEBUG storage: no type or existing type for vda, bailing
>03:52:18,808 INFO storage: got device: DiskDevice instance (0x7f7b2f0d9e90) --
>  name = vda  status = True  kids = 0 id = 1
>  parents = []
>  uuid = None  size = 8000.0
>  format = existing None
>  major = 252  minor = 0  exists = True  protected = False
>  sysfs path = /devices/pci0000:00/0000:00:06.0/virtio2/block/vda  partedDevice = parted.Device instance --
>  model: Virtio Block Device  path: /dev/vda  type: 15
>  sectorSize: 512  physicalSectorSize:  512
>  length: 16384000  openCount: 0  readOnly: False
>  externalMode: False  dirty: False  bootDirty: False
>  host: 13107  did: 13107  busy: False
>  hardwareGeometry: (16253, 16, 63)  biosGeometry: (1019, 255, 63)
>  PedDevice: <_ped.Device object at 0x7f7b2f124710>
>  target size = 0  path = /dev/vda
>  format args = []  originalFormat = None  removable = False  partedDevice = <parted.device.Device object at 0x7f7b2f017450>
>03:52:18,816 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVNAME': 'vdb',
> 'DEVPATH': '/devices/pci0000:00/0000:00:07.0/virtio3/block/vdb',
> 'DEVTYPE': 'disk',
> 'MAJOR': '252',
> 'MINOR': '16',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'TAGS': ':systemd:',
> 'USEC_INITIALIZED': '35034',
> 'name': 'vdb',
> 'symlinks': [],
> 'sysfs_path': '/devices/pci0000:00/0000:00:07.0/virtio3/block/vdb'} ; name: vdb ;
>03:52:18,819 INFO storage: scanning vdb (/devices/pci0000:00/0000:00:07.0/virtio3/block/vdb)...
>03:52:18,823 DEBUG storage:           DeviceTree.getDeviceByName: name: vdb ;
>03:52:18,828 DEBUG storage:           DeviceTree.getDeviceByName returned None
>03:52:18,829 DEBUG storage:           DeviceTree.addUdevDiskDevice: name: vdb ;
>03:52:18,830 INFO storage: vdb is a disk
>03:52:18,831 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:18,833 DEBUG storage:               DiskDevice._setFormat: vdb ; current: None ; type: None ;
>03:52:18,843 INFO storage: added disk vdb (id 2) to device tree
>03:52:18,844 DEBUG storage: looking up parted Device: /dev/vdb
>03:52:18,854 DEBUG storage:           DeviceTree.handleUdevDeviceFormat: name: vdb ;
>03:52:18,871 DEBUG storage:            DeviceTree.handleUdevDiskLabelFormat: device: vdb ; label_type: None ;
>03:52:18,872 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:18,876 DEBUG storage:             DiskDevice.setup: vdb ; status: True ; controllable: True ; orig: False ;
>03:52:18,885 DEBUG storage:              DiskLabel.__init__: device: /dev/vdb ; labelType: msdos ; exists: True ;
>03:52:18,898 INFO storage: no usable disklabel on vdb
>03:52:18,940 DEBUG storage: no type or existing type for vdb, bailing
>03:52:18,942 INFO storage: got device: DiskDevice instance (0x7f7b2f017690) --
>  name = vdb  status = True  kids = 0 id = 2
>  parents = []
>  uuid = None  size = 8000.0
>  format = existing None
>  major = 252  minor = 16  exists = True  protected = False
>  sysfs path = /devices/pci0000:00/0000:00:07.0/virtio3/block/vdb  partedDevice = parted.Device instance --
>  model: Virtio Block Device  path: /dev/vdb  type: 15
>  sectorSize: 512  physicalSectorSize:  512
>  length: 16384000  openCount: 0  readOnly: False
>  externalMode: False  dirty: False  bootDirty: False
>  host: 13107  did: 13107  busy: False
>  hardwareGeometry: (16253, 16, 63)  biosGeometry: (1019, 255, 63)
>  PedDevice: <_ped.Device object at 0x7f7b2f124830>
>  target size = 0  path = /dev/vdb
>  format args = []  originalFormat = None  removable = False  partedDevice = <parted.device.Device object at 0x7f7b2f017710>
>03:52:18,948 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVNAME': 'loop0',
> 'DEVPATH': '/devices/virtual/block/loop0',
> 'DEVTYPE': 'disk',
> 'ID_FS_TYPE': 'squashfs',
> 'ID_FS_USAGE': 'filesystem',
> 'ID_FS_VERSION': '1024.0',
> 'MAJOR': '7',
> 'MINOR': '0',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'USEC_INITIALIZED': '295',
> 'name': 'loop0',
> 'symlinks': [],
> 'sysfs_path': '/devices/virtual/block/loop0'} ; name: loop0 ;
>03:52:18,950 INFO storage: scanning loop0 (/devices/virtual/block/loop0)...
>03:52:18,952 DEBUG storage:           DeviceTree.getDeviceByName: name: loop0 ;
>03:52:18,955 DEBUG storage:           DeviceTree.getDeviceByName returned None
>03:52:18,956 INFO storage: loop0 is a loop device
>03:52:18,957 DEBUG storage:           DeviceTree.addUdevLoopDevice: name: loop0 ;
>03:52:19,000 DEBUG storage:            DeviceTree.getDeviceByName: name: /run/install/repo/LiveOS/squashfs.img ;
>03:52:19,005 DEBUG storage:            DeviceTree.getDeviceByName returned None
>03:52:19,005 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:19,007 DEBUG storage:               FileDevice._setFormat: /run/install/repo/LiveOS/squashfs.img ; current: None ; type: None ;
>03:52:19,008 INFO storage: added file /run/install/repo/LiveOS/squashfs.img (id 3) to device tree
>03:52:19,010 DEBUG storage:               FileDevice.addChild: kids: 0 ; name: /run/install/repo/LiveOS/squashfs.img ;
>03:52:19,015 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:19,020 DEBUG storage:               LoopDevice._setFormat: loop0 ; current: None ; type: None ;
>03:52:19,049 INFO storage: added loop loop0 (id 4) to device tree
>03:52:19,051 DEBUG storage:           DeviceTree.handleUdevDeviceFormat: name: loop0 ;
>03:52:19,053 DEBUG storage:            DeviceTree.handleUdevDiskLabelFormat: device: loop0 ; label_type: None ;
>03:52:19,069 DEBUG storage: getFormat('squashfs') returning DeviceFormat instance
>03:52:19,292 DEBUG storage:             LoopDevice.setup: loop0 ; status: False ; controllable: False ; orig: False ;
>03:52:19,449 DEBUG storage:              DiskLabel.__init__: device: /dev/loop0 ; labelType: None ; exists: True ;
>03:52:19,475 WARN storage: disklabel detected but not usable on loop0
>03:52:19,482 INFO storage: type detected on 'loop0' is 'squashfs'
>03:52:19,482 DEBUG storage: getFormat('squashfs') returning DeviceFormat instance
>03:52:19,484 DEBUG storage:             LoopDevice._setFormat: loop0 ; current: None ; type: None ;
>03:52:19,488 INFO storage: got device: LoopDevice instance (0x7f7b2f017b90) --
>  name = loop0  status = False  kids = 0 id = 4
>  parents = ['existing 0MB file /run/install/repo/LiveOS/squashfs.img (3)']
>  uuid = None  size = 0
>  format = existing None
>  major = 0  minor = 0  exists = True  protected = False
>  sysfs path =   partedDevice = None
>  target size = 0  path = /dev/loop0
>  format args = []  originalFormat = None
>03:52:19,491 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVLINKS': '/dev/disk/by-label/Anaconda /dev/disk/by-uuid/efc02627-ea01-42a6-b346-ceb7c908a182',
> 'DEVNAME': 'loop1',
> 'DEVPATH': '/devices/virtual/block/loop1',
> 'DEVTYPE': 'disk',
> 'ID_FS_LABEL': 'Anaconda',
> 'ID_FS_LABEL_ENC': 'Anaconda',
> 'ID_FS_TYPE': 'ext4',
> 'ID_FS_USAGE': 'filesystem',
> 'ID_FS_UUID': 'efc02627-ea01-42a6-b346-ceb7c908a182',
> 'ID_FS_UUID_ENC': 'efc02627-ea01-42a6-b346-ceb7c908a182',
> 'ID_FS_VERSION': '1.0',
> 'MAJOR': '7',
> 'MINOR': '1',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'USEC_INITIALIZED': '319',
> 'name': 'loop1',
> 'symlinks': ['/dev/disk/by-label/Anaconda',
>              '/dev/disk/by-uuid/efc02627-ea01-42a6-b346-ceb7c908a182'],
> 'sysfs_path': '/devices/virtual/block/loop1'} ; name: loop1 ;
>03:52:19,496 INFO storage: scanning loop1 (/devices/virtual/block/loop1)...
>03:52:19,497 DEBUG storage:           DeviceTree.getDeviceByName: name: loop1 ;
>03:52:19,499 DEBUG storage:           DeviceTree.getDeviceByName returned None
>03:52:19,499 INFO storage: loop1 is a loop device
>03:52:19,501 DEBUG storage:           DeviceTree.addUdevLoopDevice: name: loop1 ;
>03:52:19,503 DEBUG storage:            DeviceTree.getDeviceByName: name: /LiveOS/rootfs.img ;
>03:52:19,510 DEBUG storage:            DeviceTree.getDeviceByName returned None
>03:52:19,512 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:19,514 DEBUG storage:               FileDevice._setFormat: /LiveOS/rootfs.img ; current: None ; type: None ;
>03:52:19,523 INFO storage: added file /LiveOS/rootfs.img (id 5) to device tree
>03:52:19,524 DEBUG storage:               FileDevice.addChild: kids: 0 ; name: /LiveOS/rootfs.img ;
>03:52:19,528 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:19,530 DEBUG storage:               LoopDevice._setFormat: loop1 ; current: None ; type: None ;
>03:52:19,534 INFO storage: added loop loop1 (id 6) to device tree
>03:52:19,536 DEBUG storage:           DeviceTree.handleUdevDeviceFormat: name: loop1 ;
>03:52:19,537 DEBUG storage:            DeviceTree.handleUdevDiskLabelFormat: device: loop1 ; label_type: None ;
>03:52:19,539 DEBUG storage:               Ext4FS.supported: supported: True ;
>03:52:19,554 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>03:52:19,555 DEBUG storage: device loop1 does not contain a disklabel
>03:52:19,555 INFO storage: type detected on 'loop1' is 'ext4'
>03:52:19,808 DEBUG storage: padding min size from 0 up to 0
>03:52:19,810 DEBUG storage:              Ext4FS.supported: supported: True ;
>03:52:19,810 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>03:52:19,811 DEBUG storage:             LoopDevice._setFormat: loop1 ; current: None ; type: ext4 ;
>03:52:19,821 INFO storage: got device: LoopDevice instance (0x7f7b2f0ff610) --
>  name = loop1  status = False  kids = 0 id = 6
>  parents = ['existing 0MB file /LiveOS/rootfs.img (5)']
>  uuid = None  size = 0
>  format = existing ext4 filesystem
>  major = 0  minor = 0  exists = True  protected = False
>  sysfs path =   partedDevice = None
>  target size = 0  path = /dev/loop1
>  format args = []  originalFormat = None
>03:52:19,823 DEBUG storage:             Ext4FS.supported: supported: True ;
>03:52:19,827 INFO storage: got format: Ext4FS instance (0x7f7b2f02cfd0) --
>  type = ext4  name = ext4  status = False
>  device = /dev/loop1  uuid = efc02627-ea01-42a6-b346-ceb7c908a182  exists = True
>  options = defaults  supported = True  formattable = True  resizable = True
>  mountpoint = None  mountopts = None
>  label = Anaconda  size = 1024.0  targetSize = 1024.0
>
>03:52:19,829 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVNAME': 'loop2',
> 'DEVPATH': '/devices/virtual/block/loop2',
> 'DEVTYPE': 'disk',
> 'ID_FS_TYPE': 'DM_snapshot_cow',
> 'ID_FS_USAGE': 'other',
> 'MAJOR': '7',
> 'MINOR': '2',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'USEC_INITIALIZED': '337',
> 'name': 'loop2',
> 'symlinks': [],
> 'sysfs_path': '/devices/virtual/block/loop2'} ; name: loop2 ;
>03:52:19,834 INFO storage: scanning loop2 (/devices/virtual/block/loop2)...
>03:52:19,835 DEBUG storage:           DeviceTree.getDeviceByName: name: loop2 ;
>03:52:19,836 DEBUG storage:           DeviceTree.getDeviceByName returned None
>03:52:19,837 INFO storage: loop2 is a loop device
>03:52:19,838 DEBUG storage:           DeviceTree.addUdevLoopDevice: name: loop2 ;
>03:52:19,839 DEBUG storage:            DeviceTree.getDeviceByName: name: /overlay (deleted) ;
>03:52:19,843 DEBUG storage:            DeviceTree.getDeviceByName returned None
>03:52:19,844 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:19,845 DEBUG storage:               FileDevice._setFormat: /overlay (deleted) ; current: None ; type: None ;
>03:52:19,853 INFO storage: added file /overlay (deleted) (id 7) to device tree
>03:52:19,860 DEBUG storage:               FileDevice.addChild: kids: 0 ; name: /overlay (deleted) ;
>03:52:19,861 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:19,862 DEBUG storage:               LoopDevice._setFormat: loop2 ; current: None ; type: None ;
>03:52:19,868 INFO storage: added loop loop2 (id 8) to device tree
>03:52:19,869 DEBUG storage:           DeviceTree.handleUdevDeviceFormat: name: loop2 ;
>03:52:19,872 DEBUG storage:            DeviceTree.handleUdevDiskLabelFormat: device: loop2 ; label_type: None ;
>03:52:19,873 DEBUG storage: getFormat('DM_snapshot_cow') returning DeviceFormat instance
>03:52:19,874 DEBUG storage:             LoopDevice.setup: loop2 ; status: False ; controllable: False ; orig: False ;
>03:52:19,881 DEBUG storage:              DiskLabel.__init__: device: /dev/loop2 ; labelType: None ; exists: True ;
>03:52:19,901 WARN storage: disklabel detected but not usable on loop2
>03:52:19,905 INFO storage: type detected on 'loop2' is 'DM_snapshot_cow'
>03:52:19,906 DEBUG storage: getFormat('DM_snapshot_cow') returning DeviceFormat instance
>03:52:19,907 DEBUG storage:             LoopDevice._setFormat: loop2 ; current: None ; type: None ;
>03:52:19,910 INFO storage: got device: LoopDevice instance (0x7f7b2f0316d0) --
>  name = loop2  status = False  kids = 0 id = 8
>  parents = ['existing 0MB file /overlay (deleted) (7)']
>  uuid = None  size = 0
>  format = existing None
>  major = 0  minor = 0  exists = True  protected = False
>  sysfs path =   partedDevice = None
>  target size = 0  path = /dev/loop2
>  format args = []  originalFormat = None
>03:52:19,912 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVNAME': 'loop3',
> 'DEVPATH': '/devices/virtual/block/loop3',
> 'DEVTYPE': 'disk',
> 'MAJOR': '7',
> 'MINOR': '3',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'USEC_INITIALIZED': '353',
> 'name': 'loop3',
> 'symlinks': [],
> 'sysfs_path': '/devices/virtual/block/loop3'} ; name: loop3 ;
>03:52:19,919 INFO storage: ignoring loop3 (/devices/virtual/block/loop3)
>03:52:19,920 DEBUG storage: lvm filter: adding loop3 to the reject list
>03:52:19,921 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVNAME': 'loop4',
> 'DEVPATH': '/devices/virtual/block/loop4',
> 'DEVTYPE': 'disk',
> 'MAJOR': '7',
> 'MINOR': '4',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'USEC_INITIALIZED': '13841',
> 'name': 'loop4',
> 'symlinks': [],
> 'sysfs_path': '/devices/virtual/block/loop4'} ; name: loop4 ;
>03:52:19,928 INFO storage: ignoring loop4 (/devices/virtual/block/loop4)
>03:52:19,928 DEBUG storage: lvm filter: adding loop4 to the reject list
>03:52:19,930 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVNAME': 'loop5',
> 'DEVPATH': '/devices/virtual/block/loop5',
> 'DEVTYPE': 'disk',
> 'MAJOR': '7',
> 'MINOR': '5',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'USEC_INITIALIZED': '13864',
> 'name': 'loop5',
> 'symlinks': [],
> 'sysfs_path': '/devices/virtual/block/loop5'} ; name: loop5 ;
>03:52:19,930 INFO storage: ignoring loop5 (/devices/virtual/block/loop5)
>03:52:19,935 DEBUG storage: lvm filter: adding loop5 to the reject list
>03:52:19,936 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVNAME': 'loop6',
> 'DEVPATH': '/devices/virtual/block/loop6',
> 'DEVTYPE': 'disk',
> 'MAJOR': '7',
> 'MINOR': '6',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'USEC_INITIALIZED': '13881',
> 'name': 'loop6',
> 'symlinks': [],
> 'sysfs_path': '/devices/virtual/block/loop6'} ; name: loop6 ;
>03:52:19,937 INFO storage: ignoring loop6 (/devices/virtual/block/loop6)
>03:52:19,937 DEBUG storage: lvm filter: adding loop6 to the reject list
>03:52:19,939 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVNAME': 'loop7',
> 'DEVPATH': '/devices/virtual/block/loop7',
> 'DEVTYPE': 'disk',
> 'MAJOR': '7',
> 'MINOR': '7',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'USEC_INITIALIZED': '14728',
> 'name': 'loop7',
> 'symlinks': [],
> 'sysfs_path': '/devices/virtual/block/loop7'} ; name: loop7 ;
>03:52:19,940 INFO storage: ignoring loop7 (/devices/virtual/block/loop7)
>03:52:19,943 DEBUG storage: lvm filter: adding loop7 to the reject list
>03:52:19,944 DEBUG storage: Skipping a device mapper drive (dm-0) for now
>03:52:19,984 INFO storage: devices to scan: ['dm-0']
>03:52:19,991 DEBUG storage:          DeviceTree.addUdevDevice: info: {'ANACBIN': '/sbin',
> 'DEVLINKS': '/dev/disk/by-id/dm-name-live-rw /dev/disk/by-label/Anaconda /dev/disk/by-uuid/efc02627-ea01-42a6-b346-ceb7c908a182 /dev/mapper/live-rw',
> 'DEVNAME': 'dm-0',
> 'DEVPATH': '/devices/virtual/block/dm-0',
> 'DEVTYPE': 'disk',
> 'DM_NAME': 'live-rw',
> 'DM_SUSPENDED': '0',
> 'DM_UDEV_DISABLE_LIBRARY_FALLBACK_FLAG': '1',
> 'DM_UDEV_PRIMARY_SOURCE_FLAG': '1',
> 'DM_UDEV_RULES_VSN': '2',
> 'ID_FS_LABEL': 'Anaconda',
> 'ID_FS_LABEL_ENC': 'Anaconda',
> 'ID_FS_TYPE': 'ext4',
> 'ID_FS_USAGE': 'filesystem',
> 'ID_FS_UUID': 'efc02627-ea01-42a6-b346-ceb7c908a182',
> 'ID_FS_UUID_ENC': 'efc02627-ea01-42a6-b346-ceb7c908a182',
> 'ID_FS_VERSION': '1.0',
> 'MAJOR': '253',
> 'MINOR': '0',
> 'MPATH_SBIN_PATH': '/sbin',
> 'SUBSYSTEM': 'block',
> 'TAGS': ':systemd:',
> 'USEC_INITIALIZED': '30140',
> 'name': 'dm-0',
> 'symlinks': ['/dev/disk/by-id/dm-name-live-rw',
>              '/dev/disk/by-label/Anaconda',
>              '/dev/disk/by-uuid/efc02627-ea01-42a6-b346-ceb7c908a182',
>              '/dev/mapper/live-rw'],
> 'sysfs_path': '/devices/virtual/block/dm-0'} ; name: live-rw ;
>03:52:19,992 INFO storage: scanning live-rw (/devices/virtual/block/dm-0)...
>03:52:19,994 DEBUG storage:           DeviceTree.getDeviceByName: name: live-rw ;
>03:52:19,998 DEBUG storage:           DeviceTree.getDeviceByName returned None
>03:52:19,999 INFO storage: live-rw is a device-mapper device
>03:52:20,000 DEBUG storage:           DeviceTree.addUdevDMDevice: name: live-rw ;
>03:52:20,001 DEBUG storage:            DeviceTree.getDeviceByName: name: loop1 ;
>03:52:20,008 DEBUG storage:            DeviceTree.getDeviceByName returned existing 0MB loop loop1 (6) with existing ext4 filesystem
>03:52:20,011 DEBUG storage:            DeviceTree.getDeviceByName: name: loop2 ;
>03:52:20,015 DEBUG storage:            DeviceTree.getDeviceByName returned existing 0MB loop loop2 (8)
>03:52:20,017 DEBUG storage:            DeviceTree.getDeviceByName: name: live-rw ;
>03:52:20,021 DEBUG storage:            DeviceTree.getDeviceByName returned None
>03:52:20,023 DEBUG storage:               LoopDevice.addChild: kids: 0 ; name: loop2 ;
>03:52:20,023 DEBUG storage: getFormat('None') returning DeviceFormat instance
>03:52:20,025 DEBUG storage:               DMDevice._setFormat: live-rw ; current: None ; type: None ;
>03:52:20,031 INFO storage: added dm live-rw (id 9) to device tree
>03:52:20,032 DEBUG storage:           DeviceTree.handleUdevDeviceFormat: name: live-rw ;
>03:52:20,033 DEBUG storage:            DeviceTree.handleUdevDiskLabelFormat: device: live-rw ; label_type: None ;
>03:52:20,039 DEBUG storage:               Ext4FS.supported: supported: True ;
>03:52:20,039 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>03:52:20,039 DEBUG storage: device live-rw does not contain a disklabel
>03:52:20,040 INFO storage: type detected on 'live-rw' is 'ext4'
>03:52:20,133 DEBUG storage: padding min size from 726 up to 798
>03:52:20,134 DEBUG storage:              Ext4FS.supported: supported: True ;
>03:52:20,135 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>03:52:20,136 DEBUG storage:             DMDevice._setFormat: live-rw ; current: None ; type: ext4 ;
>03:52:20,140 DEBUG storage: looking up parted Device: /dev/mapper/live-rw
>03:52:20,157 INFO storage: got device: DMDevice instance (0x7f7b2f031ed0) --
>  name = live-rw  status = True  kids = 0 id = 9
>  parents = ['existing 0MB loop loop2 (8)']
>  uuid = None  size = 1024.0
>  format = existing ext4 filesystem
>  major = 0  minor = 0  exists = True  protected = True
>  sysfs path = /devices/virtual/block/dm-0  partedDevice = parted.Device instance --
>  model: Linux device-mapper (snapshot)  path: /dev/mapper/live-rw  type: 12
>  sectorSize: 512  physicalSectorSize:  512
>  length: 2097152  openCount: 0  readOnly: False
>  externalMode: False  dirty: False  bootDirty: False
>  host: 13107  did: 13107  busy: True
>  hardwareGeometry: (130, 255, 63)  biosGeometry: (130, 255, 63)
>  PedDevice: <_ped.Device object at 0x7f7b2f0659e0>
>  target size = 0  path = /dev/mapper/live-rw
>  format args = []  originalFormat = None  target = None  dmUuid = None
>03:52:20,159 DEBUG storage:             Ext4FS.supported: supported: True ;
>03:52:20,159 INFO storage: got format: Ext4FS instance (0x7f7b2f031fd0) --
>  type = ext4  name = ext4  status = False
>  device = /dev/mapper/live-rw  uuid = efc02627-ea01-42a6-b346-ceb7c908a182  exists = True
>  options = defaults  supported = True  formattable = True  resizable = True
>  mountpoint = None  mountopts = None
>  label = Anaconda  size = 1024.0  targetSize = 1024.0
>
>03:52:20,208 DEBUG storage:           OpticalDevice.teardown: sr0 ; status: True ; controllable: True ;
>03:52:20,302 DEBUG storage:           DiskDevice.teardown: vda ; status: True ; controllable: True ;
>03:52:20,303 DEBUG storage:             DeviceFormat.teardown: device: /dev/vda ; status: False ; type: None ;
>03:52:20,305 DEBUG storage:             DeviceFormat.teardown: device: /dev/vda ; status: False ; type: None ;
>03:52:20,365 DEBUG storage:           DiskDevice.teardown: vdb ; status: True ; controllable: True ;
>03:52:20,366 DEBUG storage:             DeviceFormat.teardown: device: /dev/vdb ; status: False ; type: None ;
>03:52:20,368 DEBUG storage:             DeviceFormat.teardown: device: /dev/vdb ; status: False ; type: None ;
>03:52:20,427 DEBUG storage:           LoopDevice.teardown: loop0 ; status: False ; controllable: False ;
>03:52:20,429 DEBUG storage:           LoopDevice.teardown: loop1 ; status: False ; controllable: False ;
>03:52:20,430 DEBUG storage:           DMDevice.teardown: live-rw ; status: True ; controllable: False ;
>03:52:20,431 INFO storage: not going to restore from backup of non-existent /etc/mdadm.conf
>03:52:20,431 INFO storage: edd: collected mbr signatures: {}
>03:52:20,438 DEBUG storage: edd: data extracted from 0x80:
>	type: SCSI, ata_device: None
>	channel: 0, mbr_signature: None
>	pci_dev: 00:06.0, scsi_id: 0
>	scsi_lun: 0, sectors: 16384000
>03:52:20,439 DEBUG storage: edd: matched 0x80 to vda using pci_dev
>03:52:20,439 DEBUG storage: edd: data extracted from 0x81:
>	type: SCSI, ata_device: None
>	channel: 0, mbr_signature: None
>	pci_dev: 00:07.0, scsi_id: 0
>	scsi_lun: 0, sectors: 16384000
>03:52:20,440 DEBUG storage: edd: matched 0x81 to vdb using pci_dev
>03:52:20,572 DEBUG storage:             Ext4FS.supported: supported: True ;
>03:52:20,576 DEBUG storage:            Ext4FS.supported: supported: True ;
>03:52:20,590 DEBUG storage:            OpticalDevice.mediaPresent: sr0 ; status: True ;
>03:52:20,604 DEBUG storage:            Iso9660FS.supported: supported: True ;
>03:52:20,921 DEBUG storage:          DeviceTree.getDeviceByPath: path: /dev/sr0 ;
>03:52:20,923 DEBUG storage:              OpticalDevice.mediaPresent: sr0 ; status: True ;
>03:52:20,930 DEBUG storage:          DeviceTree.getDeviceByPath returned existing 4273MB cdrom sr0 (0) with existing iso9660 filesystem
>23:52:37,346 DEBUG storage:             Iso9660FS.supported: supported: True ;
>23:52:37,348 DEBUG storage:            Iso9660FS.supported: supported: True ;
>23:52:37,350 DEBUG storage:             NFSv4.supported: supported: False ;
>23:52:37,351 DEBUG storage:            NFSv4.supported: supported: False ;
>23:52:37,353 DEBUG storage:              SELinuxFS.supported: supported: False ;
>23:52:37,355 DEBUG storage:            SELinuxFS.supported: supported: False ;
>23:52:37,356 DEBUG storage:             Ext4FS.supported: supported: True ;
>23:52:37,358 DEBUG storage:            Ext4FS.supported: supported: True ;
>23:52:37,360 DEBUG storage:             Ext3FS.supported: supported: True ;
>23:52:37,361 DEBUG storage:            Ext3FS.supported: supported: True ;
>23:52:37,363 DEBUG storage:             Ext2FS.supported: supported: True ;
>23:52:37,365 DEBUG storage:            Ext2FS.supported: supported: True ;
>23:52:37,367 DEBUG storage:              SysFS.supported: supported: False ;
>23:52:37,368 DEBUG storage:            SysFS.supported: supported: False ;
>23:52:37,370 DEBUG storage:            MultipathMember.__init__:
>23:52:37,372 DEBUG storage:            SwapSpace.__init__:
>23:52:37,373 DEBUG storage:              ProcFS.supported: supported: False ;
>23:52:37,375 DEBUG storage:            ProcFS.supported: supported: False ;
>23:52:37,377 DEBUG storage:              NoDevFS.supported: supported: False ;
>23:52:37,378 DEBUG storage:            NoDevFS.supported: supported: False ;
>23:52:37,380 DEBUG storage:              DevPtsFS.supported: supported: False ;
>23:52:37,381 DEBUG storage:            DevPtsFS.supported: supported: False ;
>23:52:37,383 DEBUG storage:              BTRFS.supported: supported: True ;
>23:52:37,468 DEBUG storage:              USBFS.supported: supported: False ;
>23:52:37,470 DEBUG storage:            USBFS.supported: supported: False ;
>23:52:37,471 DEBUG storage:            DiskLabel.__init__:
>23:52:37,472 INFO storage: DiskLabel.partedDevice returning None
>23:52:37,474 DEBUG storage:             HFSPlus.supported: supported: False ;
>23:52:37,475 DEBUG storage:            HFSPlus.supported: supported: False ;
>23:52:37,477 DEBUG storage:             XFS.supported: supported: True ;
>23:52:37,555 DEBUG storage:            XFS.supported: supported: True ;
>23:52:37,558 DEBUG storage:              TmpFS.supported: supported: False ;
>23:52:37,559 DEBUG storage:            TmpFS.supported: supported: False ;
>23:52:37,561 DEBUG storage:            LUKS.__init__:
>23:52:37,563 DEBUG storage:             NTFS.supported: supported: False ;
>23:52:37,565 DEBUG storage:            NTFS.supported: supported: False ;
>23:52:37,566 DEBUG storage:             BindFS.supported: supported: False ;
>23:52:37,568 DEBUG storage:            BindFS.supported: supported: False ;
>23:52:37,569 DEBUG storage:             HFS.supported: supported: False ;
>23:52:37,571 DEBUG storage:            HFS.supported: supported: False ;
>23:52:37,572 DEBUG storage:            LVMPhysicalVolume.__init__:
>23:52:37,574 DEBUG storage:             NFS.supported: supported: False ;
>23:52:37,575 DEBUG storage:            NFS.supported: supported: False ;
>23:52:37,577 DEBUG storage:             FATFS.supported: supported: True ;
>23:52:37,611 DEBUG storage:            FATFS.supported: supported: True ;
>23:52:37,613 DEBUG storage:            DMRaidMember.__init__:
>23:52:37,615 DEBUG storage:            MDRaidMember.__init__:
>23:54:05,768 DEBUG storage: clearpart: initializing vda
>23:54:05,770 DEBUG storage:            DeviceFormat.teardown: device: /dev/vda ; status: False ; type: None ;
>23:54:05,771 DEBUG storage: getFormat('None') returning DeviceFormat instance
>23:54:05,773 DEBUG storage:             DiskDevice._setFormat: vda ; current: None ; type: None ;
>23:54:05,777 INFO storage: registered action: [0] Destroy Format None on disk vda (id 1)
>23:54:05,780 DEBUG storage:            DiskLabel.__init__: device: /dev/vda ; labelType: msdos ;
>23:54:05,782 DEBUG storage:              DiskLabel.freshPartedDisk: device: /dev/vda ; labelType: msdos ;
>23:54:05,783 DEBUG storage: Did not set pmbr_boot on parted.Disk instance --
>  type: msdos  primaryPartitionCount: 0
>  lastPartitionNumber: -1  maxPrimaryPartitionCount: 4
>  partitions: []
>  device: <parted.device.Device object at 0x7f7b2419eb50>
>  PedDisk: <_ped.Disk object at 0x7f7b22a77050>
>23:54:05,785 DEBUG storage: getFormat('disklabel') returning DiskLabel instance
>23:54:05,791 DEBUG storage:            DeviceFormat.teardown: device: /dev/vda ; status: False ; type: None ;
>23:54:05,795 DEBUG storage:             DiskDevice._setFormat: vda ; current: None ; type: disklabel ;
>23:54:05,796 INFO storage: registered action: [1] Create Format msdos disklabel on disk vda (id 1)
>23:54:05,797 DEBUG storage: clearpart: initializing vdb
>23:54:05,799 DEBUG storage:            DeviceFormat.teardown: device: /dev/vdb ; status: False ; type: None ;
>23:54:05,800 DEBUG storage: getFormat('None') returning DeviceFormat instance
>23:54:05,803 DEBUG storage:             DiskDevice._setFormat: vdb ; current: None ; type: None ;
>23:54:05,804 INFO storage: registered action: [2] Destroy Format None on disk vdb (id 2)
>23:54:05,811 DEBUG storage:            DiskLabel.__init__: device: /dev/vdb ; labelType: msdos ;
>23:54:05,813 DEBUG storage:              DiskLabel.freshPartedDisk: device: /dev/vdb ; labelType: msdos ;
>23:54:05,816 DEBUG storage: Did not set pmbr_boot on parted.Disk instance --
>  type: msdos  primaryPartitionCount: 0
>  lastPartitionNumber: -1  maxPrimaryPartitionCount: 4
>  partitions: []
>  device: <parted.device.Device object at 0x7f7b22e55b90>
>  PedDisk: <_ped.Disk object at 0x7f7b2437f3b0>
>23:54:05,818 DEBUG storage: getFormat('disklabel') returning DiskLabel instance
>23:54:05,820 DEBUG storage:            DeviceFormat.teardown: device: /dev/vdb ; status: False ; type: None ;
>23:54:05,825 DEBUG storage:             DiskDevice._setFormat: vdb ; current: None ; type: disklabel ;
>23:54:05,827 INFO storage: registered action: [3] Create Format msdos disklabel on disk vdb (id 2)
>23:54:05,829 DEBUG storage: new disk order: []
>23:54:05,899 DEBUG storage:         DeviceTree.getDeviceByName: name: vda ;
>23:54:05,904 DEBUG storage:         DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:05,911 DEBUG storage: doAutoPart: True
>23:54:05,911 DEBUG storage: encryptedAutoPart: False
>23:54:05,912 DEBUG storage: autoPartType: 0
>23:54:05,912 DEBUG storage: clearPartType: 2
>23:54:05,913 DEBUG storage: clearPartDisks: ['vda', 'vdb']
>23:54:05,913 DEBUG storage: autoPartitionRequests:
>PartSpec instance (0x7f7b2419e7d0) -- 
>  mountpoint = /  lv = True  singlePV = False  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 1024  maxSize = 51200  grow = True
>PartSpec instance (0x7f7b22f7bd50) -- 
>  mountpoint = /home  lv = True  singlePV = False  btrfs = True
>  weight = 0  fstype = ext4  encrypted = True
>  size = 500  maxSize = None  grow = True
>PartSpec instance (0x7f7b257d3e90) -- 
>  mountpoint = /boot  lv = False  singlePV = False  btrfs = False
>  weight = 2000  fstype = ext4  encrypted = False
>  size = 500  maxSize = None  grow = False
>PartSpec instance (0x7f7b22ae7450) -- 
>  mountpoint = None  lv = False  singlePV = False  btrfs = False
>  weight = 5000  fstype = biosboot  encrypted = False
>  size = 1  maxSize = None  grow = False
>PartSpec instance (0x7f7b28e95590) -- 
>  mountpoint = None  lv = True  singlePV = False  btrfs = False
>  weight = 0  fstype = swap  encrypted = True
>  size = 2016  maxSize = None  grow = False
>
>23:54:05,914 DEBUG storage: storage.disks: ['vda', 'vdb']
>23:54:05,915 DEBUG storage: storage.partitioned: ['vda', 'vdb']
>23:54:05,916 DEBUG storage: all names: ['/LiveOS/rootfs.img', '/overlay (deleted)', '/run/install/repo/LiveOS/squashfs.img', 'live-rw', 'loop0', 'loop1', 'loop2', 'sr0', 'vda', 'vdb']
>23:54:05,918 DEBUG storage:            DeviceTree.getDeviceByName: name: vda ;
>23:54:05,924 DEBUG storage:            DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:05,925 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:05,925 DEBUG storage: boot disk: vda
>23:54:05,928 DEBUG storage: candidate disks: [DiskDevice instance (0x7f7b2f0d9e90) --
>  name = vda  status = True  kids = 0 id = 1
>  parents = []
>  uuid = None  size = 8000.0
>  format = non-existent msdos disklabel
>  major = 252  minor = 0  exists = True  protected = False
>  sysfs path = /devices/pci0000:00/0000:00:06.0/virtio2/block/vda  partedDevice = parted.Device instance --
>  model: Virtio Block Device  path: /dev/vda  type: 15
>  sectorSize: 512  physicalSectorSize:  512
>  length: 16384000  openCount: 0  readOnly: False
>  externalMode: False  dirty: False  bootDirty: False
>  host: 13107  did: 13107  busy: False
>  hardwareGeometry: (16253, 16, 63)  biosGeometry: (1019, 255, 63)
>  PedDevice: <_ped.Device object at 0x7f7b2f124710>
>  target size = 0  path = /dev/vda
>  format args = []  originalFormat = None  removable = False  partedDevice = <parted.device.Device object at 0x7f7b2f017450>, DiskDevice instance (0x7f7b2f017690) --
>  name = vdb  status = True  kids = 0 id = 2
>  parents = []
>  uuid = None  size = 8000.0
>  format = non-existent msdos disklabel
>  major = 252  minor = 16  exists = True  protected = False
>  sysfs path = /devices/pci0000:00/0000:00:07.0/virtio3/block/vdb  partedDevice = parted.Device instance --
>  model: Virtio Block Device  path: /dev/vdb  type: 15
>  sectorSize: 512  physicalSectorSize:  512
>  length: 16384000  openCount: 0  readOnly: False
>  externalMode: False  dirty: False  bootDirty: False
>  host: 13107  did: 13107  busy: False
>  hardwareGeometry: (16253, 16, 63)  biosGeometry: (1019, 255, 63)
>  PedDevice: <_ped.Device object at 0x7f7b2f124830>
>  target size = 0  path = /dev/vdb
>  format args = []  originalFormat = None  removable = False  partedDevice = <parted.device.Device object at 0x7f7b2f017710>]
>23:54:05,931 DEBUG storage: devs: []
>23:54:05,933 DEBUG storage: _is_valid_disklabel(vda) returning True
>23:54:05,933 DEBUG storage: _is_valid_size(vda) returning True
>23:54:05,934 DEBUG storage: _is_valid_location(vda) returning True
>23:54:05,934 DEBUG storage: _is_valid_format(vda) returning True
>23:54:05,935 DEBUG storage: is_valid_stage1_device(vda) returning True
>23:54:05,937 DEBUG storage:              Ext4FS.supported: supported: True ;
>23:54:05,938 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:05,939 DEBUG storage:               DiskDevice.addChild: kids: 0 ; name: vda ;
>23:54:05,941 DEBUG storage:               DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:05,943 DEBUG storage:               PartitionDevice._setFormat: req0 ;
>23:54:05,945 DEBUG storage:                PartitionDevice._setFormat: req0 ; current: None ; type: ext4 ;
>23:54:05,946 DEBUG storage:             DiskDevice.removeChild: kids: 1 ; name: vda ;
>23:54:05,948 DEBUG storage:             DiskDevice.removeChild: kids: 1 ; name: vdb ;
>23:54:05,949 INFO storage: added partition req0 (id 10) to device tree
>23:54:05,949 INFO storage: registered action: [4] Create Device partition req0 (id 10)
>23:54:05,950 DEBUG storage: getFormat('None') returning DeviceFormat instance
>23:54:05,951 INFO storage: registered action: [5] Create Format ext4 filesystem mounted at / on partition req0 (id 10)
>23:54:05,952 DEBUG storage:              Ext4FS.supported: supported: True ;
>23:54:05,953 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:05,955 DEBUG storage:               DiskDevice.addChild: kids: 0 ; name: vda ;
>23:54:05,956 DEBUG storage:               DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:05,958 DEBUG storage:               PartitionDevice._setFormat: req1 ;
>23:54:05,959 DEBUG storage:                PartitionDevice._setFormat: req1 ; current: None ; type: ext4 ;
>23:54:05,961 DEBUG storage:             DiskDevice.removeChild: kids: 1 ; name: vda ;
>23:54:05,963 DEBUG storage:             DiskDevice.removeChild: kids: 1 ; name: vdb ;
>23:54:05,963 INFO storage: added partition req1 (id 11) to device tree
>23:54:05,964 INFO storage: registered action: [6] Create Device partition req1 (id 11)
>23:54:05,964 DEBUG storage: getFormat('None') returning DeviceFormat instance
>23:54:05,965 INFO storage: registered action: [7] Create Format ext4 filesystem mounted at /boot on partition req1 (id 11)
>23:54:05,966 INFO storage: skipping unneeded stage1 biosboot request
>23:54:05,966 DEBUG storage: PartSpec instance (0x7f7b22ae7450) -- 
>  mountpoint = None  lv = False  singlePV = False  btrfs = False
>  weight = 5000  fstype = biosboot  encrypted = False
>  size = 1  maxSize = None  grow = False
>
>23:54:05,967 DEBUG storage: existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:05,969 DEBUG storage:             SwapSpace.__init__: mountpoint: None ;
>23:54:05,969 DEBUG storage: getFormat('swap') returning SwapSpace instance
>23:54:05,971 DEBUG storage:               DiskDevice.addChild: kids: 0 ; name: vda ;
>23:54:05,973 DEBUG storage:               DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:05,977 DEBUG storage:               PartitionDevice._setFormat: req2 ;
>23:54:05,978 DEBUG storage:                PartitionDevice._setFormat: req2 ; current: None ; type: swap ;
>23:54:05,980 DEBUG storage:             DiskDevice.removeChild: kids: 1 ; name: vda ;
>23:54:05,982 DEBUG storage:             DiskDevice.removeChild: kids: 1 ; name: vdb ;
>23:54:05,982 INFO storage: added partition req2 (id 12) to device tree
>23:54:05,983 INFO storage: registered action: [8] Create Device partition req2 (id 12)
>23:54:05,983 DEBUG storage: getFormat('None') returning DeviceFormat instance
>23:54:05,984 INFO storage: registered action: [9] Create Format swap on partition req2 (id 12)
>23:54:05,986 DEBUG storage:           DiskDevice.setup: vda ; status: True ; controllable: True ; orig: False ;
>23:54:05,987 DEBUG storage:           DiskDevice.setup: vdb ; status: True ; controllable: True ; orig: False ;
>23:54:05,989 DEBUG storage: removing all non-preexisting partitions ['req0(id 10)', 'req1(id 11)', 'req2(id 12)'] from disk(s) ['vda', 'vdb']
>23:54:05,990 DEBUG storage: allocatePartitions: disks=['vda', 'vdb'] ; partitions=['req0(id 10)', 'req1(id 11)', 'req2(id 12)']
>23:54:05,990 DEBUG storage: removing all non-preexisting partitions ['req1(id 11)', 'req2(id 12)', 'req0(id 10)'] from disk(s) ['vda', 'vdb']
>23:54:05,992 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:05,994 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:05,994 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:05,996 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:05,998 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:05,998 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,000 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,001 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,002 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,004 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,005 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,006 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,006 DEBUG storage: allocating partition: req1 ; id: 11 ; disks: ['vda', 'vdb'] ;
>boot: True ; primary: False ; size: 500MB ; grow: False ; max_size: 0
>23:54:06,007 DEBUG storage: checking freespace on vda
>23:54:06,008 DEBUG storage: getBestFreeSpaceRegion: disk=/dev/vda part_type=0 req_size=500MB boot=True best=None grow=False
>23:54:06,009 DEBUG storage: current free range is 63-16383999 (7999MB)
>23:54:06,009 DEBUG storage: updating use_disk to vda, type: 0
>23:54:06,010 DEBUG storage: new free: 63-16383999 / 7999MB
>23:54:06,010 DEBUG storage: new free allows for 0 sectors of growth
>23:54:06,011 DEBUG storage: found free space for bootable request
>23:54:06,012 DEBUG storage: adjusted start sector from 63 to 2048
>23:54:06,013 DEBUG storage: created partition vda1 of 500MB and added it to /dev/vda
>23:54:06,015 DEBUG storage:             PartitionDevice._setPartedPartition: req1 ;
>23:54:06,015 DEBUG storage: device req1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b2419e490>  fileSystem: None
>  number: 1  path: /dev/vda1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b242c85d0>  PedPartition: <_ped.Partition object at 0x7f7b244c76b0>
>23:54:06,017 DEBUG storage:             PartitionDevice._setDisk: vda1 ; new: vda ; old: None ;
>23:54:06,019 DEBUG storage:              DiskDevice.addChild: kids: 0 ; name: vda ;
>23:54:06,021 DEBUG storage:             PartitionDevice._setPartedPartition: vda1 ;
>23:54:06,022 DEBUG storage: device vda1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b2419e490>  fileSystem: None
>  number: 1  path: /dev/vda1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b242c8d90>  PedPartition: <_ped.Partition object at 0x7f7b244c7710>
>23:54:06,023 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,025 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,026 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,027 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,029 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,030 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,031 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,033 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,034 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,035 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,037 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,038 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,038 DEBUG storage: allocating partition: req2 ; id: 12 ; disks: ['vda', 'vdb'] ;
>boot: False ; primary: False ; size: 2016MB ; grow: False ; max_size: 0
>23:54:06,039 DEBUG storage: checking freespace on vda
>23:54:06,040 DEBUG storage: getBestFreeSpaceRegion: disk=/dev/vda part_type=0 req_size=2016MB boot=False best=None grow=False
>23:54:06,041 DEBUG storage: current free range is 63-2047 (0MB)
>23:54:06,041 DEBUG storage: current free range is 1026048-16383999 (7499MB)
>23:54:06,042 DEBUG storage: updating use_disk to vda, type: 0
>23:54:06,043 DEBUG storage: new free: 1026048-16383999 / 7499MB
>23:54:06,043 DEBUG storage: new free allows for 0 sectors of growth
>23:54:06,044 DEBUG storage: checking freespace on vdb
>23:54:06,045 DEBUG storage: getBestFreeSpaceRegion: disk=/dev/vdb part_type=0 req_size=2016MB boot=False best=parted.Geometry instance --
>  start: 1026048  end: 16383999  length: 15357952
>  device: <parted.device.Device object at 0x7f7b242c8f50>  PedGeometry: <_ped.Geometry object at 0x7f7b242c8750> grow=False
>23:54:06,046 DEBUG storage: current free range is 63-16383999 (7999MB)
>23:54:06,046 DEBUG storage: not enough free space for primary -- trying logical
>23:54:06,048 DEBUG storage: created partition vda2 of 2016MB and added it to /dev/vda
>23:54:06,049 DEBUG storage:             PartitionDevice._setPartedPartition: req2 ;
>23:54:06,050 DEBUG storage: device req2 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b2419e490>  fileSystem: None
>  number: 2  path: /dev/vda2  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b242c8610>  PedPartition: <_ped.Partition object at 0x7f7b244c7650>
>23:54:06,052 DEBUG storage:             PartitionDevice._setDisk: vda2 ; new: vda ; old: None ;
>23:54:06,053 DEBUG storage:              DiskDevice.addChild: kids: 1 ; name: vda ;
>23:54:06,056 DEBUG storage:             PartitionDevice._setPartedPartition: vda2 ;
>23:54:06,057 DEBUG storage: device vda2 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b2419e490>  fileSystem: None
>  number: 2  path: /dev/vda2  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b242c8790>  PedPartition: <_ped.Partition object at 0x7f7b244c75f0>
>23:54:06,059 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,060 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,061 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,062 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,064 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,064 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,066 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,068 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,068 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,070 DEBUG storage:              DeviceTree.getDeviceByName: name: vda ;
>23:54:06,072 DEBUG storage:              DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,072 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,073 DEBUG storage: allocating partition: req0 ; id: 10 ; disks: ['vda', 'vdb'] ;
>boot: False ; primary: False ; size: 1024MB ; grow: True ; max_size: 51200
>23:54:06,073 DEBUG storage: checking freespace on vda
>23:54:06,074 DEBUG storage: getBestFreeSpaceRegion: disk=/dev/vda part_type=0 req_size=1024MB boot=False best=None grow=True
>23:54:06,075 DEBUG storage: current free range is 63-2047 (0MB)
>23:54:06,076 DEBUG storage: current free range is 5154816-16383999 (5483MB)
>23:54:06,077 DEBUG storage: evaluating growth potential for new layout
>23:54:06,077 DEBUG storage: calculating growth for disk /dev/vdb
>23:54:06,078 DEBUG storage: Chunk.growRequests: 16383937 on /dev/vdb start = 63  end = 16383999
>sectorSize = 512
>
>23:54:06,078 DEBUG storage: disk /dev/vdb growth: 0 (0MB)
>23:54:06,079 DEBUG storage: calculating growth for disk /dev/vda
>23:54:06,081 DEBUG storage:             PartitionDevice._setPartedPartition: req0 ;
>23:54:06,082 DEBUG storage: device req0 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b2419e490>  fileSystem: None
>  number: 3  path: /dev/vda3  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b242c8e10>  PedPartition: <_ped.Partition object at 0x7f7b244c7590>
>23:54:06,083 DEBUG storage:             PartitionDevice._setDisk: vda3 ; new: vda ; old: None ;
>23:54:06,085 DEBUG storage:              DiskDevice.addChild: kids: 2 ; name: vda ;
>23:54:06,086 DEBUG storage: adding request 11 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:06,086 DEBUG storage: adding request 12 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:06,087 DEBUG storage: adding request 10 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:06,088 DEBUG storage: Chunk.growRequests: 16383937 on /dev/vda start = 63  end = 16383999
>sectorSize = 512
>
>23:54:06,088 DEBUG storage: req: PartitionRequest instance --
>id = 11  name = vda1  growable = False
>base = 1024000  growth = 0  max_grow = 0
>done = True
>23:54:06,089 DEBUG storage: req: PartitionRequest instance --
>id = 12  name = vda2  growable = False
>base = 4128768  growth = 0  max_grow = 0
>done = True
>23:54:06,089 DEBUG storage: req: PartitionRequest instance --
>id = 10  name = vda3  growable = True
>base = 2097152  growth = 0  max_grow = 102760448
>done = False
>23:54:06,090 DEBUG storage: 1 requests and 9134017 (4459MB) left in chunk
>23:54:06,091 DEBUG storage: adding 9134017 (4459MB) to 10 (vda3)
>23:54:06,091 DEBUG storage: new grow amount for request 10 (vda3) is 9134017 units, or 4459MB
>23:54:06,092 DEBUG storage: request 11 (vda1) growth: 0 (0MB) size: 500MB
>23:54:06,092 DEBUG storage: request 12 (vda2) growth: 0 (0MB) size: 2016MB
>23:54:06,093 DEBUG storage: request 10 (vda3) growth: 9134017 (4459MB) size: 5483MB
>23:54:06,093 DEBUG storage: disk /dev/vda growth: 9134017 (4459MB)
>23:54:06,095 DEBUG storage:             PartitionDevice._setPartedPartition: vda3 ;
>23:54:06,096 DEBUG storage: device vda3 new partedPartition None
>23:54:06,097 DEBUG storage:             PartitionDevice._setDisk: req0 ; new: None ; old: vda ;
>23:54:06,099 DEBUG storage:              DiskDevice.removeChild: kids: 3 ; name: vda ;
>23:54:06,099 DEBUG storage: total growth: 9134017 sectors
>23:54:06,100 DEBUG storage: updating use_disk to vda, type: 0
>23:54:06,101 DEBUG storage: new free: 5154816-16383999 / 5483MB
>23:54:06,101 DEBUG storage: new free allows for 9134017 sectors of growth
>23:54:06,102 DEBUG storage: checking freespace on vdb
>23:54:06,102 DEBUG storage: getBestFreeSpaceRegion: disk=/dev/vdb part_type=0 req_size=1024MB boot=False best=None grow=True
>23:54:06,103 DEBUG storage: current free range is 63-16383999 (7999MB)
>23:54:06,104 DEBUG storage: evaluating growth potential for new layout
>23:54:06,105 DEBUG storage: calculating growth for disk /dev/vdb
>23:54:06,106 DEBUG storage: adjusted start sector from 63 to 2048
>23:54:06,108 DEBUG storage:             PartitionDevice._setPartedPartition: req0 ;
>23:54:06,108 DEBUG storage: device req0 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22e552d0>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b22e91110>  PedPartition: <_ped.Partition object at 0x7f7b244c7530>
>23:54:06,110 DEBUG storage:             PartitionDevice._setDisk: vdb1 ; new: vdb ; old: None ;
>23:54:06,112 DEBUG storage:              DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:06,112 DEBUG storage: adding request 10 to chunk 16383937 (63-16383999) on /dev/vdb
>23:54:06,113 DEBUG storage: Chunk.growRequests: 16383937 on /dev/vdb start = 63  end = 16383999
>sectorSize = 512
>
>23:54:06,113 DEBUG storage: req: PartitionRequest instance --
>id = 10  name = vdb1  growable = True
>base = 2097152  growth = 0  max_grow = 102760448
>done = False
>23:54:06,114 DEBUG storage: 1 requests and 14286785 (6975MB) left in chunk
>23:54:06,115 DEBUG storage: adding 14286785 (6975MB) to 10 (vdb1)
>23:54:06,115 DEBUG storage: new grow amount for request 10 (vdb1) is 14286785 units, or 6975MB
>23:54:06,116 DEBUG storage: request 10 (vdb1) growth: 14286785 (6975MB) size: 7999MB
>23:54:06,116 DEBUG storage: disk /dev/vdb growth: 14286785 (6975MB)
>23:54:06,117 DEBUG storage: calculating growth for disk /dev/vda
>23:54:06,117 DEBUG storage: adding request 11 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:06,118 DEBUG storage: adding request 12 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:06,119 DEBUG storage: Chunk.growRequests: 16383937 on /dev/vda start = 63  end = 16383999
>sectorSize = 512
>
>23:54:06,119 DEBUG storage: req: PartitionRequest instance --
>id = 11  name = vda1  growable = False
>base = 1024000  growth = 0  max_grow = 0
>done = True
>23:54:06,120 DEBUG storage: req: PartitionRequest instance --
>id = 12  name = vda2  growable = False
>base = 4128768  growth = 0  max_grow = 0
>done = True
>23:54:06,120 DEBUG storage: request 11 (vda1) growth: 0 (0MB) size: 500MB
>23:54:06,121 DEBUG storage: request 12 (vda2) growth: 0 (0MB) size: 2016MB
>23:54:06,121 DEBUG storage: disk /dev/vda growth: 0 (0MB)
>23:54:06,123 DEBUG storage:             PartitionDevice._setPartedPartition: vdb1 ;
>23:54:06,123 DEBUG storage: device vdb1 new partedPartition None
>23:54:06,125 DEBUG storage:             PartitionDevice._setDisk: req0 ; new: None ; old: vdb ;
>23:54:06,126 DEBUG storage:              DiskDevice.removeChild: kids: 1 ; name: vdb ;
>23:54:06,127 DEBUG storage: total growth: 14286785 sectors
>23:54:06,128 DEBUG storage: updating use_disk to vdb, type: 0
>23:54:06,128 DEBUG storage: new free: 63-16383999 / 7999MB
>23:54:06,129 DEBUG storage: new free allows for 14286785 sectors of growth
>23:54:06,129 DEBUG storage: adjusted start sector from 63 to 2048
>23:54:06,131 DEBUG storage: created partition vdb1 of 1024MB and added it to /dev/vdb
>23:54:06,132 DEBUG storage:             PartitionDevice._setPartedPartition: req0 ;
>23:54:06,133 DEBUG storage: device req0 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22e552d0>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b22e914d0>  PedPartition: <_ped.Partition object at 0x7f7b244c76b0>
>23:54:06,135 DEBUG storage:             PartitionDevice._setDisk: vdb1 ; new: vdb ; old: None ;
>23:54:06,136 DEBUG storage:              DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:06,138 DEBUG storage:             PartitionDevice._setPartedPartition: vdb1 ;
>23:54:06,139 DEBUG storage: device vdb1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22e552d0>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b242c8410>  PedPartition: <_ped.Partition object at 0x7f7b244c7650>
>23:54:06,140 DEBUG storage: growPartitions: disks=['vda', 'vdb'], partitions=['vdb1(id 10)', 'vda1(id 11)', 'vda2(id 12)']
>23:54:06,140 DEBUG storage: growable partitions are ['vdb1']
>23:54:06,141 DEBUG storage: adding request 11 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:06,141 DEBUG storage: adding request 12 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:06,142 DEBUG storage: disk vda has 1 chunks
>23:54:06,143 DEBUG storage: adding request 10 to chunk 16383937 (63-16383999) on /dev/vdb
>23:54:06,143 DEBUG storage: disk vdb has 1 chunks
>23:54:06,144 DEBUG storage: Chunk.growRequests: 16383937 on /dev/vdb start = 63  end = 16383999
>sectorSize = 512
>
>23:54:06,144 DEBUG storage: req: PartitionRequest instance --
>id = 10  name = vdb1  growable = True
>base = 2097152  growth = 0  max_grow = 102760448
>done = False
>23:54:06,145 DEBUG storage: 1 requests and 14286785 (6975MB) left in chunk
>23:54:06,145 DEBUG storage: adding 14286785 (6975MB) to 10 (vdb1)
>23:54:06,146 DEBUG storage: new grow amount for request 10 (vdb1) is 14286785 units, or 6975MB
>23:54:06,147 DEBUG storage: growing partitions on vda
>23:54:06,147 DEBUG storage: growing partitions on vdb
>23:54:06,148 DEBUG storage: partition vdb1 (10): 0
>23:54:06,148 DEBUG storage: new geometry for vdb1: parted.Geometry instance --
>  start: 2048  end: 16383999  length: 16381952
>  device: <parted.device.Device object at 0x7f7b22e553d0>  PedGeometry: <_ped.Geometry object at 0x7f7b22e91690>
>23:54:06,149 DEBUG storage: removing all non-preexisting partitions ['vdb1(id 10)'] from disk(s) ['vdb']
>23:54:06,151 DEBUG storage:              PartitionDevice._setPartedPartition: vdb1 ;
>23:54:06,151 DEBUG storage: device vdb1 new partedPartition None
>23:54:06,153 DEBUG storage:              PartitionDevice._setDisk: req0 ; new: None ; old: vdb ;
>23:54:06,154 DEBUG storage:               DiskDevice.removeChild: kids: 1 ; name: vdb ;
>23:54:06,154 DEBUG storage: back from removeNewPartitions
>23:54:06,155 DEBUG storage: extended: None
>23:54:06,155 DEBUG storage: setting req0 new geometry: parted.Geometry instance --
>  start: 2048  end: 16383999  length: 16381952
>  device: <parted.device.Device object at 0x7f7b22e553d0>  PedGeometry: <_ped.Geometry object at 0x7f7b22e91690>
>23:54:06,157 DEBUG storage:             PartitionDevice._setPartedPartition: req0 ;
>23:54:06,157 DEBUG storage: device req0 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22e552d0>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b22d5a910>  PedPartition: <_ped.Partition object at 0x7f7b244c74d0>
>23:54:06,159 DEBUG storage:             PartitionDevice._setDisk: vdb1 ; new: vdb ; old: None ;
>23:54:06,160 DEBUG storage:              DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:06,162 DEBUG storage:             PartitionDevice._setPartedPartition: vdb1 ;
>23:54:06,162 DEBUG storage: device vdb1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22e552d0>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b22d5ae90>  PedPartition: <_ped.Partition object at 0x7f7b244c7410>
>23:54:06,163 DEBUG storage: fixing size of non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot at 500.00
>23:54:06,164 DEBUG storage: fixing size of non-existent 2016MB partition vda2 (12) with non-existent swap at 2016.00
>23:54:06,164 DEBUG storage: fixing size of non-existent 7999MB partition vdb1 (10) with non-existent ext4 filesystem mounted at / at 7999.00
>23:54:06,167 DEBUG storage:            DeviceTree.getDeviceByName: name: vda ;
>23:54:06,168 DEBUG storage:            DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,169 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,170 DEBUG storage: _is_valid_disklabel(vda) returning True
>23:54:06,170 DEBUG storage: _is_valid_size(vda) returning True
>23:54:06,170 DEBUG storage: _is_valid_location(vda) returning True
>23:54:06,171 DEBUG storage: _is_valid_format(vda) returning True
>23:54:06,171 DEBUG storage: is_valid_stage1_device(vda) returning True
>23:54:06,173 DEBUG storage: _is_valid_disklabel(vda) returning True
>23:54:06,174 DEBUG storage: _is_valid_size(vda) returning True
>23:54:06,174 DEBUG storage: _is_valid_location(vda) returning True
>23:54:06,174 DEBUG storage: _is_valid_format(vda) returning True
>23:54:06,174 DEBUG storage: is_valid_stage1_device(vda) returning True
>23:54:06,175 DEBUG storage: _is_valid_disklabel(vda1) returning True
>23:54:06,175 DEBUG storage: _is_valid_size(vda1) returning True
>23:54:06,175 DEBUG storage: _is_valid_location(vda1) returning True
>23:54:06,176 DEBUG storage: _is_valid_partition(vda1) returning True
>23:54:06,176 DEBUG storage: _is_valid_format(vda1) returning True
>23:54:06,176 DEBUG storage: is_valid_stage2_device(vda1) returning True
>23:54:06,178 DEBUG storage:          DeviceTree.getDeviceByName: name: vda ;
>23:54:06,179 DEBUG storage:          DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:06,179 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:06,180 DEBUG storage: _is_valid_disklabel(vda) returning True
>23:54:06,181 DEBUG storage: _is_valid_size(vda) returning True
>23:54:06,181 DEBUG storage: _is_valid_location(vda) returning True
>23:54:06,181 DEBUG storage: _is_valid_format(vda) returning True
>23:54:06,181 DEBUG storage: is_valid_stage1_device(vda) returning True
>23:54:06,185 DEBUG storage: _is_valid_disklabel(vda) returning True
>23:54:06,186 DEBUG storage: _is_valid_size(vda) returning True
>23:54:06,186 DEBUG storage: _is_valid_location(vda) returning True
>23:54:06,186 DEBUG storage: _is_valid_format(vda) returning True
>23:54:06,186 DEBUG storage: is_valid_stage1_device(vda) returning True
>23:54:06,187 DEBUG storage: _is_valid_disklabel(vda1) returning True
>23:54:06,187 DEBUG storage: _is_valid_size(vda1) returning True
>23:54:06,187 DEBUG storage: _is_valid_location(vda1) returning True
>23:54:06,188 DEBUG storage: _is_valid_partition(vda1) returning True
>23:54:06,188 DEBUG storage: _is_valid_format(vda1) returning True
>23:54:06,188 DEBUG storage: is_valid_stage2_device(vda1) returning True
>23:54:13,143 DEBUG storage: clearpart: looking at vda2
>23:54:13,143 DEBUG storage: clearpart: looking at vda1
>23:54:13,143 DEBUG storage: clearpart: looking at vdb1
>23:54:13,144 DEBUG storage: checking whether disk vda has an empty extended
>23:54:13,145 DEBUG storage: extended is None ; logicals is []
>23:54:13,145 DEBUG storage: checking whether disk vdb has an empty extended
>23:54:13,145 DEBUG storage: extended is None ; logicals is []
>23:54:13,146 DEBUG storage: new disk order: []
>23:54:13,273 DEBUG storage:         DeviceTree.getDeviceByName: name: vda ;
>23:54:13,274 DEBUG storage:         DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:13,276 DEBUG storage:          DeviceTree.getDeviceByName: name: vda ;
>23:54:13,277 DEBUG storage:          DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:13,277 DEBUG storage: resolved 'vda' to 'vda' (disk)
>23:54:13,279 DEBUG storage: _is_valid_disklabel(vda) returning True
>23:54:13,279 DEBUG storage: _is_valid_size(vda) returning True
>23:54:13,279 DEBUG storage: _is_valid_location(vda) returning True
>23:54:13,279 DEBUG storage: _is_valid_format(vda) returning True
>23:54:13,280 DEBUG storage: is_valid_stage1_device(vda) returning True
>23:54:13,316 DEBUG storage:            PartitionDevice._setPartedPartition: vda1 ;
>23:54:13,317 DEBUG storage: device vda1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 1  path: /dev/vda1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b22b2ff90>  PedPartition: <_ped.Partition object at 0x7f7b244c7290>
>23:54:13,318 DEBUG storage:            PartitionDevice._setPartedPartition: vda2 ;
>23:54:13,319 DEBUG storage: device vda2 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 2  path: /dev/vda2  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b22c3c610>  PedPartition: <_ped.Partition object at 0x7f7b244c7770>
>23:54:13,321 DEBUG storage:            PartitionDevice._setPartedPartition: vdb1 ;
>23:54:13,321 DEBUG storage: device vdb1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22d5a390>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b22fd2dd0>  PedPartition: <_ped.Partition object at 0x7f7b244c74d0>
>23:54:13,342 DEBUG storage:                  Ext4FS.supported: supported: True ;
>23:54:13,342 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:15,788 DEBUG storage:              Ext4FS.supported: supported: True ;
>23:54:15,788 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:15,789 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.PartitionFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 500.0, ['vda'], None
>23:54:17,458 DEBUG storage:              Ext4FS.supported: supported: True ;
>23:54:17,459 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:17,460 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.PartitionFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 7999.0, ['vdb'], None
>23:54:17,466 DEBUG storage:               SwapSpace.__init__:
>23:54:17,467 DEBUG storage: getFormat('swap') returning SwapSpace instance
>23:54:18,732 DEBUG storage:             SwapSpace.__init__:
>23:54:18,733 DEBUG storage: getFormat('swap') returning SwapSpace instance
>23:54:18,733 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.PartitionFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 2016.0, ['vda'], None
>23:54:18,739 DEBUG storage:                Ext4FS.supported: supported: True ;
>23:54:18,740 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:19,181 DEBUG storage:              Ext4FS.supported: supported: True ;
>23:54:19,182 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:19,182 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.PartitionFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 7999.0, ['vdb'], None
>23:54:21,440 DEBUG storage:              Ext4FS.supported: supported: True ;
>23:54:21,440 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:21,441 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.PartitionFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 500.0, ['vda'], None
>23:54:25,684 DEBUG storage:             BTRFS.supported: supported: True ;
>23:54:25,685 DEBUG storage: getFormat('btrfs') returning BTRFS instance
>23:54:25,686 DEBUG storage:          BTRFS.supported: supported: True ;
>23:54:25,688 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.BTRFSFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 7999.0, ['vda', 'vdb'], single
>23:54:25,693 DEBUG storage:               BTRFS.supported: supported: True ;
>23:54:25,694 DEBUG storage: getFormat('btrfs') returning BTRFS instance
>23:54:29,547 DEBUG storage:               BTRFS.supported: supported: True ;
>23:54:29,548 DEBUG storage: getFormat('btrfs') returning BTRFS instance
>23:54:29,549 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.BTRFSFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 7999.0, ['vdb'], single
>23:54:29,550 INFO storage.ui: removed partition vdb1 (id 10) from device tree
>23:54:29,552 DEBUG storage.ui:                DiskDevice.removeChild: kids: 1 ; name: vdb ;
>23:54:29,552 INFO storage.ui: registered action: [10] Destroy Device partition vdb1 (id 10)
>23:54:29,555 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.BTRFSFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 7999.0, ['vda', 'vdb'], single
>23:54:29,555 DEBUG storage.ui: device requires 7999
>23:54:29,557 DEBUG storage.ui:                   BTRFS.supported: supported: True ;
>23:54:29,558 DEBUG storage.ui: getFormat('btrfs') returning BTRFS instance
>23:54:29,560 DEBUG storage.ui:                   DiskDevice.addChild: kids: 2 ; name: vda ;
>23:54:29,562 DEBUG storage.ui:                   PartitionDevice._setFormat: req3 ;
>23:54:29,564 DEBUG storage.ui:                    PartitionDevice._setFormat: req3 ; current: None ; type: btrfs ;
>23:54:29,566 DEBUG storage.ui:                 DiskDevice.removeChild: kids: 3 ; name: vda ;
>23:54:29,566 INFO storage.ui: added partition req3 (id 13) to device tree
>23:54:29,567 INFO storage.ui: registered action: [11] Create Device partition req3 (id 13)
>23:54:29,568 DEBUG storage.ui: getFormat('None') returning DeviceFormat instance
>23:54:29,568 INFO storage.ui: registered action: [12] Create Format btrfs filesystem on partition req3 (id 13)
>23:54:29,570 DEBUG storage.ui:                   BTRFS.supported: supported: True ;
>23:54:29,571 DEBUG storage.ui: getFormat('btrfs') returning BTRFS instance
>23:54:29,573 DEBUG storage.ui:                   DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:29,575 DEBUG storage.ui:                   PartitionDevice._setFormat: req4 ;
>23:54:29,577 DEBUG storage.ui:                    PartitionDevice._setFormat: req4 ; current: None ; type: btrfs ;
>23:54:29,579 DEBUG storage.ui:                 DiskDevice.removeChild: kids: 1 ; name: vdb ;
>23:54:29,579 INFO storage.ui: added partition req4 (id 14) to device tree
>23:54:29,580 INFO storage.ui: registered action: [13] Create Device partition req4 (id 14)
>23:54:29,580 DEBUG storage.ui: getFormat('None') returning DeviceFormat instance
>23:54:29,581 INFO storage.ui: registered action: [14] Create Format btrfs filesystem on partition req4 (id 14)
>23:54:29,581 DEBUG storage.ui: adding a TotalSizeSet with size 7999
>23:54:29,582 DEBUG storage.ui: set.allocated = 2
>23:54:29,584 DEBUG storage.ui:                 DiskDevice.setup: vda ; status: True ; controllable: True ; orig: False ;
>23:54:29,586 DEBUG storage.ui:                 DiskDevice.setup: vdb ; status: True ; controllable: True ; orig: False ;
>23:54:29,587 DEBUG storage.ui: removing all non-preexisting partitions ['req3(id 13)', 'req4(id 14)', 'vda1(id 11)', 'vda2(id 12)'] from disk(s) ['vda', 'vdb']
>23:54:29,589 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda1 ;
>23:54:29,590 DEBUG storage.ui: device vda1 new partedPartition None
>23:54:29,592 DEBUG storage.ui:                   PartitionDevice._setDisk: req1 ; new: None ; old: vda ;
>23:54:29,594 DEBUG storage.ui:                    DiskDevice.removeChild: kids: 2 ; name: vda ;
>23:54:29,596 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda2 ;
>23:54:29,596 DEBUG storage.ui: device vda2 new partedPartition None
>23:54:29,598 DEBUG storage.ui:                   PartitionDevice._setDisk: req2 ; new: None ; old: vda ;
>23:54:29,600 DEBUG storage.ui:                    DiskDevice.removeChild: kids: 1 ; name: vda ;
>23:54:29,601 DEBUG storage.ui: allocatePartitions: disks=['vda', 'vdb'] ; partitions=['req3(id 13)', 'req4(id 14)', 'req1(id 11)', 'req2(id 12)']
>23:54:29,602 DEBUG storage.ui: removing all non-preexisting partitions ['req1(id 11)', 'req3(id 13)', 'req4(id 14)', 'req2(id 12)'] from disk(s) ['vda', 'vdb']
>23:54:29,604 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,606 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,607 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,609 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,611 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,611 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,613 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,615 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,616 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,618 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,620 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,621 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,621 DEBUG storage.ui: allocating partition: req1 ; id: 11 ; disks: ['vda', 'vdb'] ;
>boot: True ; primary: False ; size: 500MB ; grow: False ; max_size: 0
>23:54:29,622 DEBUG storage.ui: checking freespace on vda
>23:54:29,623 DEBUG storage.ui: getBestFreeSpaceRegion: disk=/dev/vda part_type=0 req_size=500MB boot=True best=None grow=False
>23:54:29,623 DEBUG storage.ui: current free range is 63-16383999 (7999MB)
>23:54:29,624 DEBUG storage.ui: updating use_disk to vda, type: 0
>23:54:29,625 DEBUG storage.ui: new free: 63-16383999 / 7999MB
>23:54:29,625 DEBUG storage.ui: new free allows for 0 sectors of growth
>23:54:29,626 DEBUG storage.ui: found free space for bootable request
>23:54:29,626 DEBUG storage.ui: adjusted start sector from 63 to 2048
>23:54:29,627 DEBUG storage.ui: created partition vda1 of 500MB and added it to /dev/vda
>23:54:29,629 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req1 ;
>23:54:29,630 DEBUG storage.ui: device req1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 1  path: /dev/vda1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c3090>  PedPartition: <_ped.Partition object at 0x7f7b244c7290>
>23:54:29,632 DEBUG storage.ui:                   PartitionDevice._setDisk: vda1 ; new: vda ; old: None ;
>23:54:29,634 DEBUG storage.ui:                    DiskDevice.addChild: kids: 0 ; name: vda ;
>23:54:29,637 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda1 ;
>23:54:29,638 DEBUG storage.ui: device vda1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 1  path: /dev/vda1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b230cb7d0>  PedPartition: <_ped.Partition object at 0x7f7b244c7770>
>23:54:29,640 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,642 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,643 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,645 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,647 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,647 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,648 DEBUG storage.ui: allocating partition: req3 ; id: 13 ; disks: ['vda'] ;
>boot: False ; primary: False ; size: 1MB ; grow: True ; max_size: 7999.0
>23:54:29,649 DEBUG storage.ui: checking freespace on vda
>23:54:29,649 DEBUG storage.ui: getBestFreeSpaceRegion: disk=/dev/vda part_type=0 req_size=1MB boot=False best=None grow=True
>23:54:29,650 DEBUG storage.ui: current free range is 63-2047 (0MB)
>23:54:29,651 DEBUG storage.ui: current free range is 1026048-16383999 (7499MB)
>23:54:29,651 DEBUG storage.ui: evaluating growth potential for new layout
>23:54:29,652 DEBUG storage.ui: calculating growth for disk /dev/vdb
>23:54:29,653 DEBUG storage.ui: Chunk.growRequests: 16383937 on /dev/vdb start = 63  end = 16383999
>sectorSize = 512
>
>23:54:29,653 DEBUG storage.ui: disk /dev/vdb growth: 0 (0MB)
>23:54:29,654 DEBUG storage.ui: calculating growth for disk /dev/vda
>23:54:29,656 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req3 ;
>23:54:29,657 DEBUG storage.ui: device req3 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 2  path: /dev/vda2  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c34d0>  PedPartition: <_ped.Partition object at 0x7f7b244ba170>
>23:54:29,659 DEBUG storage.ui:                   PartitionDevice._setDisk: vda2 ; new: vda ; old: None ;
>23:54:29,661 DEBUG storage.ui:                    DiskDevice.addChild: kids: 1 ; name: vda ;
>23:54:29,662 DEBUG storage.ui: adding request 11 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,663 DEBUG storage.ui: adding request 13 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,664 DEBUG storage.ui: Chunk.growRequests: 16383937 on /dev/vda start = 63  end = 16383999
>sectorSize = 512
>
>23:54:29,664 DEBUG storage.ui: req: PartitionRequest instance --
>id = 11  name = vda1  growable = False
>base = 1024000  growth = 0  max_grow = 0
>done = True
>23:54:29,665 DEBUG storage.ui: req: PartitionRequest instance --
>id = 13  name = vda2  growable = True
>base = 2048  growth = 0  max_grow = 16379904
>done = False
>23:54:29,666 DEBUG storage.ui: 1 requests and 15357889 (7498MB) left in chunk
>23:54:29,666 DEBUG storage.ui: adding 15357889 (7498MB) to 13 (vda2)
>23:54:29,667 DEBUG storage.ui: new grow amount for request 13 (vda2) is 15357889 units, or 7498MB
>23:54:29,667 DEBUG storage.ui: request 11 (vda1) growth: 0 (0MB) size: 500MB
>23:54:29,668 DEBUG storage.ui: request 13 (vda2) growth: 15357889 (7498MB) size: 7499MB
>23:54:29,668 DEBUG storage.ui: disk /dev/vda growth: 15357889 (7498MB)
>23:54:29,670 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda2 ;
>23:54:29,671 DEBUG storage.ui: device vda2 new partedPartition None
>23:54:29,673 DEBUG storage.ui:                   PartitionDevice._setDisk: req3 ; new: None ; old: vda ;
>23:54:29,675 DEBUG storage.ui:                    DiskDevice.removeChild: kids: 2 ; name: vda ;
>23:54:29,675 DEBUG storage.ui: total growth: 15357889 sectors
>23:54:29,676 DEBUG storage.ui: updating use_disk to vda, type: 0
>23:54:29,677 DEBUG storage.ui: new free: 1026048-16383999 / 7499MB
>23:54:29,677 DEBUG storage.ui: new free allows for 15357889 sectors of growth
>23:54:29,678 DEBUG storage.ui: created partition vda2 of 1MB and added it to /dev/vda
>23:54:29,680 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req3 ;
>23:54:29,681 DEBUG storage.ui: device req3 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 2  path: /dev/vda2  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c36d0>  PedPartition: <_ped.Partition object at 0x7f7b244ba230>
>23:54:29,683 DEBUG storage.ui:                   PartitionDevice._setDisk: vda2 ; new: vda ; old: None ;
>23:54:29,685 DEBUG storage.ui:                    DiskDevice.addChild: kids: 1 ; name: vda ;
>23:54:29,687 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda2 ;
>23:54:29,688 DEBUG storage.ui: device vda2 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 2  path: /dev/vda2  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c3650>  PedPartition: <_ped.Partition object at 0x7f7b244ba290>
>23:54:29,690 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,692 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,693 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,696 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,698 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,698 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,699 DEBUG storage.ui: allocating partition: req4 ; id: 14 ; disks: ['vdb'] ;
>boot: False ; primary: False ; size: 1MB ; grow: True ; max_size: 7999.0
>23:54:29,699 DEBUG storage.ui: checking freespace on vdb
>23:54:29,700 DEBUG storage.ui: getBestFreeSpaceRegion: disk=/dev/vdb part_type=0 req_size=1MB boot=False best=None grow=True
>23:54:29,701 DEBUG storage.ui: current free range is 63-16383999 (7999MB)
>23:54:29,702 DEBUG storage.ui: evaluating growth potential for new layout
>23:54:29,702 DEBUG storage.ui: calculating growth for disk /dev/vdb
>23:54:29,703 DEBUG storage.ui: adjusted start sector from 63 to 2048
>23:54:29,705 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req4 ;
>23:54:29,706 DEBUG storage.ui: device req4 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22d5a390>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c3a90>  PedPartition: <_ped.Partition object at 0x7f7b244ba1d0>
>23:54:29,708 DEBUG storage.ui:                   PartitionDevice._setDisk: vdb1 ; new: vdb ; old: None ;
>23:54:29,710 DEBUG storage.ui:                    DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:29,711 DEBUG storage.ui: adding request 14 to chunk 16383937 (63-16383999) on /dev/vdb
>23:54:29,711 DEBUG storage.ui: Chunk.growRequests: 16383937 on /dev/vdb start = 63  end = 16383999
>sectorSize = 512
>
>23:54:29,712 DEBUG storage.ui: req: PartitionRequest instance --
>id = 14  name = vdb1  growable = True
>base = 2048  growth = 0  max_grow = 16379904
>done = False
>23:54:29,713 DEBUG storage.ui: 1 requests and 16381889 (7998MB) left in chunk
>23:54:29,713 DEBUG storage.ui: adding 16381889 (7998MB) to 14 (vdb1)
>23:54:29,714 DEBUG storage.ui: taking back 1985 (0MB) from 14 (vdb1)
>23:54:29,714 DEBUG storage.ui: new grow amount for request 14 (vdb1) is 16379904 units, or 7998MB
>23:54:29,715 DEBUG storage.ui: request 14 (vdb1) growth: 16379904 (7998MB) size: 7999MB
>23:54:29,715 DEBUG storage.ui: disk /dev/vdb growth: 16379904 (7998MB)
>23:54:29,716 DEBUG storage.ui: calculating growth for disk /dev/vda
>23:54:29,716 DEBUG storage.ui: adding request 11 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,717 DEBUG storage.ui: adding request 13 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,717 DEBUG storage.ui: Chunk.growRequests: 16383937 on /dev/vda start = 63  end = 16383999
>sectorSize = 512
>
>23:54:29,718 DEBUG storage.ui: req: PartitionRequest instance --
>id = 11  name = vda1  growable = False
>base = 1024000  growth = 0  max_grow = 0
>done = True
>23:54:29,719 DEBUG storage.ui: req: PartitionRequest instance --
>id = 13  name = vda2  growable = True
>base = 2048  growth = 0  max_grow = 16379904
>done = False
>23:54:29,719 DEBUG storage.ui: 1 requests and 15357889 (7498MB) left in chunk
>23:54:29,720 DEBUG storage.ui: adding 15357889 (7498MB) to 13 (vda2)
>23:54:29,720 DEBUG storage.ui: new grow amount for request 13 (vda2) is 15357889 units, or 7498MB
>23:54:29,721 DEBUG storage.ui: request 11 (vda1) growth: 0 (0MB) size: 500MB
>23:54:29,722 DEBUG storage.ui: request 13 (vda2) growth: 15357889 (7498MB) size: 7499MB
>23:54:29,722 DEBUG storage.ui: disk /dev/vda growth: 15357889 (7498MB)
>23:54:29,724 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vdb1 ;
>23:54:29,725 DEBUG storage.ui: device vdb1 new partedPartition None
>23:54:29,727 DEBUG storage.ui:                   PartitionDevice._setDisk: req4 ; new: None ; old: vdb ;
>23:54:29,729 DEBUG storage.ui:                    DiskDevice.removeChild: kids: 1 ; name: vdb ;
>23:54:29,729 DEBUG storage.ui: total growth: 31737793 sectors
>23:54:29,730 DEBUG storage.ui: updating use_disk to vdb, type: 0
>23:54:29,731 DEBUG storage.ui: new free: 63-16383999 / 7999MB
>23:54:29,731 DEBUG storage.ui: new free allows for 31737793 sectors of growth
>23:54:29,732 DEBUG storage.ui: adjusted start sector from 63 to 2048
>23:54:29,733 DEBUG storage.ui: created partition vdb1 of 1MB and added it to /dev/vdb
>23:54:29,735 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req4 ;
>23:54:29,736 DEBUG storage.ui: device req4 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22d5a390>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c3c10>  PedPartition: <_ped.Partition object at 0x7f7b244ba2f0>
>23:54:29,738 DEBUG storage.ui:                   PartitionDevice._setDisk: vdb1 ; new: vdb ; old: None ;
>23:54:29,740 DEBUG storage.ui:                    DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:29,743 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vdb1 ;
>23:54:29,743 DEBUG storage.ui: device vdb1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22d5a390>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b22c3c050>  PedPartition: <_ped.Partition object at 0x7f7b244ba230>
>23:54:29,746 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,748 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,749 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,751 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,753 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,753 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,755 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,757 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,758 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,760 DEBUG storage.ui:                    DeviceTree.getDeviceByName: name: vda ;
>23:54:29,762 DEBUG storage.ui:                    DeviceTree.getDeviceByName returned existing 8000MB disk vda (1) with non-existent msdos disklabel
>23:54:29,763 DEBUG storage.ui: resolved 'vda' to 'vda' (disk)
>23:54:29,763 DEBUG storage.ui: allocating partition: req2 ; id: 12 ; disks: ['vda', 'vdb'] ;
>boot: False ; primary: False ; size: 2016MB ; grow: False ; max_size: 0
>23:54:29,764 DEBUG storage.ui: checking freespace on vda
>23:54:29,765 DEBUG storage.ui: getBestFreeSpaceRegion: disk=/dev/vda part_type=0 req_size=2016MB boot=False best=None grow=False
>23:54:29,766 DEBUG storage.ui: current free range is 63-2047 (0MB)
>23:54:29,767 DEBUG storage.ui: current free range is 1028096-16383999 (7498MB)
>23:54:29,767 DEBUG storage.ui: evaluating growth potential for new layout
>23:54:29,768 DEBUG storage.ui: calculating growth for disk /dev/vdb
>23:54:29,768 DEBUG storage.ui: adding request 14 to chunk 16383937 (63-16383999) on /dev/vdb
>23:54:29,769 DEBUG storage.ui: Chunk.growRequests: 16383937 on /dev/vdb start = 63  end = 16383999
>sectorSize = 512
>
>23:54:29,770 DEBUG storage.ui: req: PartitionRequest instance --
>id = 14  name = vdb1  growable = True
>base = 2048  growth = 0  max_grow = 16379904
>done = False
>23:54:29,770 DEBUG storage.ui: 1 requests and 16381889 (7998MB) left in chunk
>23:54:29,771 DEBUG storage.ui: adding 16381889 (7998MB) to 14 (vdb1)
>23:54:29,771 DEBUG storage.ui: taking back 1985 (0MB) from 14 (vdb1)
>23:54:29,772 DEBUG storage.ui: new grow amount for request 14 (vdb1) is 16379904 units, or 7998MB
>23:54:29,773 DEBUG storage.ui: request 14 (vdb1) growth: 16379904 (7998MB) size: 7999MB
>23:54:29,773 DEBUG storage.ui: disk /dev/vdb growth: 16379904 (7998MB)
>23:54:29,774 DEBUG storage.ui: calculating growth for disk /dev/vda
>23:54:29,776 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req2 ;
>23:54:29,777 DEBUG storage.ui: device req2 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 3  path: /dev/vda3  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c30d0>  PedPartition: <_ped.Partition object at 0x7f7b244ba350>
>23:54:29,779 DEBUG storage.ui:                   PartitionDevice._setDisk: vda3 ; new: vda ; old: None ;
>23:54:29,781 DEBUG storage.ui:                    DiskDevice.addChild: kids: 2 ; name: vda ;
>23:54:29,781 DEBUG storage.ui: adding request 11 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,782 DEBUG storage.ui: adding request 13 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,783 DEBUG storage.ui: adding request 12 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,783 DEBUG storage.ui: Chunk.growRequests: 16383937 on /dev/vda start = 63  end = 16383999
>sectorSize = 512
>
>23:54:29,784 DEBUG storage.ui: req: PartitionRequest instance --
>id = 11  name = vda1  growable = False
>base = 1024000  growth = 0  max_grow = 0
>done = True
>23:54:29,785 DEBUG storage.ui: req: PartitionRequest instance --
>id = 13  name = vda2  growable = True
>base = 2048  growth = 0  max_grow = 16379904
>done = False
>23:54:29,785 DEBUG storage.ui: req: PartitionRequest instance --
>id = 12  name = vda3  growable = False
>base = 4128768  growth = 0  max_grow = 0
>done = True
>23:54:29,786 DEBUG storage.ui: 1 requests and 11229121 (5482MB) left in chunk
>23:54:29,786 DEBUG storage.ui: adding 11229121 (5482MB) to 13 (vda2)
>23:54:29,787 DEBUG storage.ui: new grow amount for request 13 (vda2) is 11229121 units, or 5482MB
>23:54:29,787 DEBUG storage.ui: request 11 (vda1) growth: 0 (0MB) size: 500MB
>23:54:29,788 DEBUG storage.ui: request 13 (vda2) growth: 11229121 (5482MB) size: 5483MB
>23:54:29,788 DEBUG storage.ui: request 12 (vda3) growth: 0 (0MB) size: 2016MB
>23:54:29,789 DEBUG storage.ui: disk /dev/vda growth: 11229121 (5482MB)
>23:54:29,791 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda3 ;
>23:54:29,792 DEBUG storage.ui: device vda3 new partedPartition None
>23:54:29,793 DEBUG storage.ui:                   PartitionDevice._setDisk: req2 ; new: None ; old: vda ;
>23:54:29,795 DEBUG storage.ui:                    DiskDevice.removeChild: kids: 3 ; name: vda ;
>23:54:29,796 DEBUG storage.ui: total growth: 27609025 sectors
>23:54:29,796 DEBUG storage.ui: updating use_disk to vda, type: 0
>23:54:29,797 DEBUG storage.ui: new free: 1028096-16383999 / 7498MB
>23:54:29,797 DEBUG storage.ui: new free allows for 27609025 sectors of growth
>23:54:29,798 DEBUG storage.ui: checking freespace on vdb
>23:54:29,799 DEBUG storage.ui: getBestFreeSpaceRegion: disk=/dev/vdb part_type=0 req_size=2016MB boot=False best=parted.Geometry instance --
>  start: 1028096  end: 16383999  length: 15355904
>  device: <parted.device.Device object at 0x7f7b244c3510>  PedGeometry: <_ped.Geometry object at 0x7f7b244c38d0> grow=False
>23:54:29,800 DEBUG storage.ui: current free range is 63-2047 (0MB)
>23:54:29,800 DEBUG storage.ui: current free range is 4096-16383999 (7998MB)
>23:54:29,801 DEBUG storage.ui: not enough free space for primary -- trying logical
>23:54:29,802 DEBUG storage.ui: created partition vda3 of 2016MB and added it to /dev/vda
>23:54:29,804 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req2 ;
>23:54:29,805 DEBUG storage.ui: device req2 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 3  path: /dev/vda3  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c3590>  PedPartition: <_ped.Partition object at 0x7f7b244ba170>
>23:54:29,807 DEBUG storage.ui:                   PartitionDevice._setDisk: vda3 ; new: vda ; old: None ;
>23:54:29,809 DEBUG storage.ui:                    DiskDevice.addChild: kids: 2 ; name: vda ;
>23:54:29,811 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda3 ;
>23:54:29,812 DEBUG storage.ui: device vda3 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 3  path: /dev/vda3  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c3990>  PedPartition: <_ped.Partition object at 0x7f7b244ba1d0>
>23:54:29,813 DEBUG storage.ui: growPartitions: disks=['vda', 'vdb'], partitions=['vda2(id 13)', 'vdb1(id 14)', 'vda1(id 11)', 'vda3(id 12)']
>23:54:29,814 DEBUG storage.ui: growable partitions are ['vda2', 'vdb1']
>23:54:29,814 DEBUG storage.ui: adding request 13 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,815 DEBUG storage.ui: adding request 11 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,816 DEBUG storage.ui: adding request 12 to chunk 16383937 (63-16383999) on /dev/vda
>23:54:29,816 DEBUG storage.ui: disk vda has 1 chunks
>23:54:29,817 DEBUG storage.ui: adding request 14 to chunk 16383937 (63-16383999) on /dev/vdb
>23:54:29,818 DEBUG storage.ui: disk vdb has 1 chunks
>23:54:29,818 DEBUG storage.ui: Chunk.growRequests: 16383937 on /dev/vda start = 63  end = 16383999
>sectorSize = 512
>
>23:54:29,819 DEBUG storage.ui: req: PartitionRequest instance --
>id = 11  name = vda1  growable = False
>base = 1024000  growth = 0  max_grow = 0
>done = True
>23:54:29,820 DEBUG storage.ui: req: PartitionRequest instance --
>id = 13  name = vda2  growable = True
>base = 2048  growth = 0  max_grow = 16379904
>done = False
>23:54:29,820 DEBUG storage.ui: req: PartitionRequest instance --
>id = 12  name = vda3  growable = False
>base = 4128768  growth = 0  max_grow = 0
>done = True
>23:54:29,820 DEBUG storage.ui: 1 requests and 11229121 (5482MB) left in chunk
>23:54:29,821 DEBUG storage.ui: adding 11229121 (5482MB) to 13 (vda2)
>23:54:29,821 DEBUG storage.ui: new grow amount for request 13 (vda2) is 11229121 units, or 5482MB
>23:54:29,821 DEBUG storage.ui: Chunk.growRequests: 16383937 on /dev/vdb start = 63  end = 16383999
>sectorSize = 512
>
>23:54:29,822 DEBUG storage.ui: req: PartitionRequest instance --
>id = 14  name = vdb1  growable = True
>base = 2048  growth = 0  max_grow = 16379904
>done = False
>23:54:29,822 DEBUG storage.ui: 1 requests and 16381889 (7998MB) left in chunk
>23:54:29,822 DEBUG storage.ui: adding 16381889 (7998MB) to 14 (vdb1)
>23:54:29,823 DEBUG storage.ui: taking back 1985 (0MB) from 14 (vdb1)
>23:54:29,823 DEBUG storage.ui: new grow amount for request 14 (vdb1) is 16379904 units, or 7998MB
>23:54:29,823 DEBUG storage.ui: set: ['vda2', 'vdb1'] 2/7999
>23:54:29,823 DEBUG storage.ui: allocating 5482 to TotalSizeSet with 2/7999 (7997 needed)
>23:54:29,824 DEBUG storage.ui: PartitionRequest instance --
>id = 14  name = vdb1  growable = True
>base = 2048  growth = 16379904  max_grow = 16379904
>done = True
>23:54:29,824 DEBUG storage.ui: needed: 2514
>23:54:29,824 DEBUG storage.ui: PartitionRequest instance --
>id = 13  name = vda2  growable = True
>base = 2048  growth = 11229121  max_grow = 16379904
>done = False
>23:54:29,825 DEBUG storage.ui: needed: 2514
>23:54:29,825 DEBUG storage.ui: set: ['vda2', 'vdb1'] 5484/7999
>23:54:29,825 DEBUG storage.ui: allocating 0 to TotalSizeSet with 5484/7999 (2514 needed)
>23:54:29,825 DEBUG storage.ui: PartitionRequest instance --
>id = 14  name = vdb1  growable = True
>base = 2048  growth = 16379904  max_grow = 16379904
>done = True
>23:54:29,826 DEBUG storage.ui: needed: 2514
>23:54:29,826 DEBUG storage.ui: PartitionRequest instance --
>id = 13  name = vda2  growable = True
>base = 2048  growth = 11229121  max_grow = 16379904
>done = False
>23:54:29,826 DEBUG storage.ui: needed: 2514
>23:54:29,827 DEBUG storage.ui: growing partitions on vda
>23:54:29,827 DEBUG storage.ui: partition vda1 (11): 0
>23:54:29,828 DEBUG storage.ui: new geometry for vda1: parted.Geometry instance --
>  start: 2048  end: 1026047  length: 1024000
>  device: <parted.device.Device object at 0x7f7b243ec390>  PedGeometry: <_ped.Geometry object at 0x7f7b244c3cd0>
>23:54:29,828 DEBUG storage.ui: partition vda2 (13): 0
>23:54:29,828 DEBUG storage.ui: new geometry for vda2: parted.Geometry instance --
>  start: 1026048  end: 12255231  length: 11229184
>  device: <parted.device.Device object at 0x7f7b243ec390>  PedGeometry: <_ped.Geometry object at 0x7f7b244c3ed0>
>23:54:29,829 DEBUG storage.ui: partition vda3 (12): 0
>23:54:29,829 DEBUG storage.ui: new geometry for vda3: parted.Geometry instance --
>  start: 12255232  end: 16383999  length: 4128768
>  device: <parted.device.Device object at 0x7f7b243ec390>  PedGeometry: <_ped.Geometry object at 0x7f7b244ce090>
>23:54:29,830 DEBUG storage.ui: removing all non-preexisting partitions ['vda1(id 11)', 'vda2(id 13)', 'vda3(id 12)'] from disk(s) ['vda']
>23:54:29,831 DEBUG storage.ui:                    PartitionDevice._setPartedPartition: vda1 ;
>23:54:29,832 DEBUG storage.ui: device vda1 new partedPartition None
>23:54:29,833 DEBUG storage.ui:                    PartitionDevice._setDisk: req1 ; new: None ; old: vda ;
>23:54:29,835 DEBUG storage.ui:                     DiskDevice.removeChild: kids: 3 ; name: vda ;
>23:54:29,837 DEBUG storage.ui:                    PartitionDevice._setPartedPartition: vda2 ;
>23:54:29,838 DEBUG storage.ui: device vda2 new partedPartition None
>23:54:29,839 DEBUG storage.ui:                    PartitionDevice._setDisk: req3 ; new: None ; old: vda ;
>23:54:29,841 DEBUG storage.ui:                     DiskDevice.removeChild: kids: 2 ; name: vda ;
>23:54:29,843 DEBUG storage.ui:                    PartitionDevice._setPartedPartition: vda3 ;
>23:54:29,843 DEBUG storage.ui: device vda3 new partedPartition None
>23:54:29,845 DEBUG storage.ui:                    PartitionDevice._setDisk: req2 ; new: None ; old: vda ;
>23:54:29,847 DEBUG storage.ui:                     DiskDevice.removeChild: kids: 1 ; name: vda ;
>23:54:29,848 DEBUG storage.ui: back from removeNewPartitions
>23:54:29,848 DEBUG storage.ui: extended: None
>23:54:29,848 DEBUG storage.ui: setting req1 new geometry: parted.Geometry instance --
>  start: 2048  end: 1026047  length: 1024000
>  device: <parted.device.Device object at 0x7f7b243ec390>  PedGeometry: <_ped.Geometry object at 0x7f7b244c3cd0>
>23:54:29,850 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req1 ;
>23:54:29,851 DEBUG storage.ui: device req1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 1  path: /dev/vda1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c3550>  PedPartition: <_ped.Partition object at 0x7f7b244c7290>
>23:54:29,852 DEBUG storage.ui:                   PartitionDevice._setDisk: vda1 ; new: vda ; old: None ;
>23:54:29,854 DEBUG storage.ui:                    DiskDevice.addChild: kids: 0 ; name: vda ;
>23:54:29,856 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda1 ;
>23:54:29,857 DEBUG storage.ui: device vda1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 1  path: /dev/vda1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244ce1d0>  PedPartition: <_ped.Partition object at 0x7f7b244ba290>
>23:54:29,857 DEBUG storage.ui: setting req3 new geometry: parted.Geometry instance --
>  start: 1026048  end: 12255231  length: 11229184
>  device: <parted.device.Device object at 0x7f7b243ec390>  PedGeometry: <_ped.Geometry object at 0x7f7b244c3ed0>
>23:54:29,859 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req3 ;
>23:54:29,859 DEBUG storage.ui: device req3 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 2  path: /dev/vda2  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c3390>  PedPartition: <_ped.Partition object at 0x7f7b244ba410>
>23:54:29,861 DEBUG storage.ui:                   PartitionDevice._setDisk: vda2 ; new: vda ; old: None ;
>23:54:29,863 DEBUG storage.ui:                    DiskDevice.addChild: kids: 1 ; name: vda ;
>23:54:29,865 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda2 ;
>23:54:29,866 DEBUG storage.ui: device vda2 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 2  path: /dev/vda2  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c36d0>  PedPartition: <_ped.Partition object at 0x7f7b244ba3b0>
>23:54:29,866 DEBUG storage.ui: setting req2 new geometry: parted.Geometry instance --
>  start: 12255232  end: 16383999  length: 4128768
>  device: <parted.device.Device object at 0x7f7b243ec390>  PedGeometry: <_ped.Geometry object at 0x7f7b244ce090>
>23:54:29,868 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req2 ;
>23:54:29,869 DEBUG storage.ui: device req2 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 3  path: /dev/vda3  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b23080710>  PedPartition: <_ped.Partition object at 0x7f7b244ba470>
>23:54:29,870 DEBUG storage.ui:                   PartitionDevice._setDisk: vda3 ; new: vda ; old: None ;
>23:54:29,872 DEBUG storage.ui:                    DiskDevice.addChild: kids: 2 ; name: vda ;
>23:54:29,874 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vda3 ;
>23:54:29,875 DEBUG storage.ui: device vda3 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 3  path: /dev/vda3  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244ce710>  PedPartition: <_ped.Partition object at 0x7f7b244ba530>
>23:54:29,875 DEBUG storage.ui: growing partitions on vdb
>23:54:29,876 DEBUG storage.ui: partition vdb1 (14): 0
>23:54:29,876 DEBUG storage.ui: new geometry for vdb1: parted.Geometry instance --
>  start: 2048  end: 16383999  length: 16381952
>  device: <parted.device.Device object at 0x7f7b22d5a0d0>  PedGeometry: <_ped.Geometry object at 0x7f7b244c3b50>
>23:54:29,877 DEBUG storage.ui: removing all non-preexisting partitions ['vdb1(id 14)'] from disk(s) ['vdb']
>23:54:29,878 DEBUG storage.ui:                    PartitionDevice._setPartedPartition: vdb1 ;
>23:54:29,879 DEBUG storage.ui: device vdb1 new partedPartition None
>23:54:29,880 DEBUG storage.ui:                    PartitionDevice._setDisk: req4 ; new: None ; old: vdb ;
>23:54:29,882 DEBUG storage.ui:                     DiskDevice.removeChild: kids: 1 ; name: vdb ;
>23:54:29,882 DEBUG storage.ui: back from removeNewPartitions
>23:54:29,883 DEBUG storage.ui: extended: None
>23:54:29,883 DEBUG storage.ui: setting req4 new geometry: parted.Geometry instance --
>  start: 2048  end: 16383999  length: 16381952
>  device: <parted.device.Device object at 0x7f7b22d5a0d0>  PedGeometry: <_ped.Geometry object at 0x7f7b244c3b50>
>23:54:29,885 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: req4 ;
>23:54:29,885 DEBUG storage.ui: device req4 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22d5a390>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244ce290>  PedPartition: <_ped.Partition object at 0x7f7b244c7770>
>23:54:29,887 DEBUG storage.ui:                   PartitionDevice._setDisk: vdb1 ; new: vdb ; old: None ;
>23:54:29,889 DEBUG storage.ui:                    DiskDevice.addChild: kids: 0 ; name: vdb ;
>23:54:29,891 DEBUG storage.ui:                   PartitionDevice._setPartedPartition: vdb1 ;
>23:54:29,891 DEBUG storage.ui: device vdb1 new partedPartition parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22d5a390>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244ce2d0>  PedPartition: <_ped.Partition object at 0x7f7b244ba1d0>
>23:54:29,892 DEBUG storage.ui: fixing size of non-existent 500MB partition vda1 (11) with non-existent ext4 filesystem mounted at /boot at 500.00
>23:54:29,893 DEBUG storage.ui: fixing size of non-existent 5483MB partition vda2 (13) with non-existent btrfs filesystem at 5483.00
>23:54:29,893 DEBUG storage.ui: fixing size of non-existent 2016MB partition vda3 (12) with non-existent swap at 2016.00
>23:54:29,894 DEBUG storage.ui: fixing size of non-existent 7999MB partition vdb1 (14) with non-existent btrfs filesystem at 7999.00
>23:54:29,895 DEBUG storage.ui: creating new container
>23:54:29,898 DEBUG storage.ui: newBTRFS: args = () ; kwargs = {'parents': [PartitionDevice instance (0x7f7b23189f50) --
>  name = vda2  status = False  kids = 0 id = 13
>  parents = ['existing 8000MB disk vda (1) with non-existent msdos disklabel']
>  uuid = None  size = 5483.0
>  format = non-existent btrfs filesystem
>  major = 0  minor = 0  exists = False  protected = False
>  sysfs path =   partedDevice = None
>  target size = 1  path = /dev/vda2
>  format args = []  originalFormat = btrfs  grow = False  max size = 7999.0  bootable = False
>  part type = 0  primary = False
>  partedPartition = parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b243ec210>  fileSystem: None
>  number: 2  path: /dev/vda2  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244c36d0>  PedPartition: <_ped.Partition object at 0x7f7b244ba3b0>
>  disk = existing 8000MB disk vda (1) with non-existent msdos disklabel
>  start = 1026048  end = 12255231  length = 11229184
>  flags = , PartitionDevice instance (0x7f7b2309d790) --
>  name = vdb1  status = False  kids = 0 id = 14
>  parents = ['existing 8000MB disk vdb (2) with non-existent msdos disklabel']
>  uuid = None  size = 7999.0
>  format = non-existent btrfs filesystem
>  major = 0  minor = 0  exists = False  protected = False
>  sysfs path =   partedDevice = None
>  target size = 1  path = /dev/vdb1
>  format args = []  originalFormat = btrfs  grow = False  max size = 7999.0  bootable = False
>  part type = 0  primary = False
>  partedPartition = parted.Partition instance --
>  disk: <parted.disk.Disk object at 0x7f7b22d5a390>  fileSystem: None
>  number: 1  path: /dev/vdb1  type: 0
>  name: None  active: True  busy: False
>  geometry: <parted.geometry.Geometry object at 0x7f7b244ce2d0>  PedPartition: <_ped.Partition object at 0x7f7b244ba1d0>
>  disk = existing 8000MB disk vdb (2) with non-existent msdos disklabel
>  start = 2048  end = 16383999  length = 16381952
>  flags = ]}
>23:54:29,900 DEBUG storage.ui:                    PartitionDevice.addChild: kids: 0 ; name: vda2 ;
>23:54:29,902 DEBUG storage.ui:                    PartitionDevice.addChild: kids: 0 ; name: vdb1 ;
>23:54:29,902 DEBUG storage.ui: getFormat('None') returning DeviceFormat instance
>23:54:29,904 DEBUG storage.ui:                     BTRFSVolumeDevice._setFormat: fedora ; current: None ; type: None ;
>23:54:29,906 DEBUG storage.ui:                    BTRFS.supported: supported: True ;
>23:54:29,906 DEBUG storage.ui: getFormat('btrfs') returning BTRFS instance
>23:54:29,908 DEBUG storage.ui:                   BTRFSVolumeDevice._setFormat: btrfs.15 ; current: None ; type: btrfs ;
>23:54:29,909 DEBUG storage.ui:                   BTRFS.supported: supported: True ;
>23:54:29,910 DEBUG storage.ui: getFormat('btrfs') returning BTRFS instance
>23:54:29,911 DEBUG storage.ui:                  BTRFSVolumeDevice._setFormat: btrfs.15 ; current: btrfs ; type: btrfs ;
>23:54:29,912 INFO storage.ui: added btrfs volume fedora (id 15) to device tree
>23:54:29,912 INFO storage.ui: registered action: [15] Create Device btrfs volume fedora (id 15)
>23:54:29,913 DEBUG storage.ui: getFormat('None') returning DeviceFormat instance
>23:54:29,913 INFO storage.ui: registered action: [16] Create Format btrfs filesystem on btrfs volume fedora (id 15)
>23:54:29,914 DEBUG storage.ui: BTRFSVolumeDevice instance (0x7f7b22e32910) --
>  name = fedora  status = False  kids = 0 id = 15
>  parents = ['non-existent 5483MB partition vda2 (13) with non-existent btrfs filesystem',
> 'non-existent 7999MB partition vdb1 (14) with non-existent btrfs filesystem']
>  uuid = None  size = 13482.0
>  format = non-existent btrfs filesystem
>  major = 0  minor = 0  exists = False  protected = False
>  sysfs path =   partedDevice = None
>  target size = 0  path = /dev/vda2
>  format args = []  originalFormat = btrfs
>23:54:29,915 DEBUG storage.ui: creating new device
>23:54:29,916 DEBUG storage.ui: newBTRFS: args = () ; kwargs = {'metaDataLevel': 'single', 'subvol': True, 'fmt_type': 'btrfs', 'fmt_args': {}, 'dataLevel': 'single', 'parents': [BTRFSVolumeDevice instance (0x7f7b22e32910) --
>  name = fedora  status = False  kids = 0 id = 15
>  parents = ['non-existent 5483MB partition vda2 (13) with non-existent btrfs filesystem',
> 'non-existent 7999MB partition vdb1 (14) with non-existent btrfs filesystem']
>  uuid = None  size = 13482.0
>  format = non-existent btrfs filesystem
>  major = 0  minor = 0  exists = False  protected = False
>  sysfs path =   partedDevice = None
>  target size = 0  path = /dev/vda2
>  format args = []  originalFormat = btrfs], 'mountpoint': '/', 'size': 7999.0}
>23:54:29,919 DEBUG storage.ui:                      BTRFSVolumeDevice.addChild: kids: 0 ; name: fedora ;
>23:54:29,919 DEBUG storage.ui: getFormat('None') returning DeviceFormat instance
>23:54:29,921 DEBUG storage.ui:                      BTRFSSubVolumeDevice._setFormat: root ; current: None ; type: None ;
>23:54:29,923 DEBUG storage.ui:                     BTRFS.supported: supported: True ;
>23:54:29,923 DEBUG storage.ui: getFormat('btrfs') returning BTRFS instance
>23:54:29,925 DEBUG storage.ui:                   BTRFSSubVolumeDevice._setFormat: root ; current: None ; type: btrfs ;
>23:54:29,925 INFO storage.ui: added btrfs subvolume root (id 16) to device tree
>23:54:29,926 INFO storage.ui: registered action: [17] Create Device btrfs subvolume root (id 16)
>23:54:29,926 DEBUG storage.ui: getFormat('None') returning DeviceFormat instance
>23:54:29,927 INFO storage.ui: registered action: [18] Create Format btrfs filesystem mounted at / on btrfs subvolume root (id 16)
>23:54:29,940 DEBUG storage:                Ext4FS.supported: supported: True ;
>23:54:29,940 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:35,174 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.MDFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 500.0, ['vda', 'vdb'], raid0
>23:54:39,156 DEBUG storage:             Ext4FS.supported: supported: True ;
>23:54:39,157 DEBUG storage: getFormat('ext4') returning Ext4FS instance
>23:54:39,157 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.MDFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 500.0, ['vda'], raid1
>23:54:39,158 INFO storage.ui: removed partition vda1 (id 11) from device tree
>23:54:39,160 DEBUG storage.ui:               DiskDevice.removeChild: kids: 3 ; name: vda ;
>23:54:39,160 INFO storage.ui: registered action: [19] Destroy Device partition vda1 (id 11)
>23:54:39,162 DEBUG storage.ui: instantiating <class 'pyanaconda.storage.MDFactory'>: <pyanaconda.storage.Storage object at 0x7f7b22e5dc90>, 500.0, ['vda'], raid1
>
>
>/tmp/ifcfg.log:
>03:52:18,087 DEBUG ifcfg: NetworkDevice eth0: setDefaultConfig()
>03:52:18,089 DEBUG ifcfg: NetworkDevice eth0 set: DEVICE=eth0 BOOTPROTO=dhcp ONBOOT=no
>03:52:18,089 DEBUG ifcfg: NetworkDevice eth0 set: HWADDR=52:54:00:DD:5F:B3
>03:52:18,091 DEBUG ifcfg: NetworkDevice eth0 set: UUID=d5859d14-2424-485e-aefa-63ec5066b210
>03:52:18,091 DEBUG ifcfg: /etc/sysconfig/network-scripts/ifcfg-eth0:
>
>03:52:18,091 DEBUG ifcfg: NetworkDevice eth0:
>DEVICE="eth0"
>HWADDR="52:54:00:DD:5F:B3"
>BOOTPROTO="dhcp"
>ONBOOT="no"
>UUID="d5859d14-2424-485e-aefa-63ec5066b210"
>
>03:52:18,092 DEBUG ifcfg: writeIfcfgFile eth0 to /etc/sysconfig/network-scripts/ifcfg-eth0
>
>
>/proc/cmdline:
>initrd=initrd.img inst.stage2=hd:LABEL=Fedora\x2018-Beta-TC4\x20x86_64 quiet BOOT_IMAGE=vmlinuz 
>
>
>/tmp/syslog:
>03:52:09,305 INFO kernel:imklog 5.8.11, log source = /proc/kmsg started.
>03:52:09,305 INFO rsyslogd: [origin software="rsyslogd" swVersion="5.8.11" x-pid="522" x-info="http://www.rsyslog.com"] start
>03:52:09,305 INFO kernel:[    0.000000] Initializing cgroup subsys cpuset
>03:52:09,305 INFO kernel:[    0.000000] Initializing cgroup subsys cpu
>03:52:09,305 NOTICE kernel:[    0.000000] Linux version 3.6.1-1.fc18.x86_64 (mockbuild@) (gcc version 4.7.2 20120921 (Red Hat 4.7.2-2) (GCC) ) #1 SMP Mon Oct 8 17:19:09 UTC 2012
>03:52:09,305 INFO kernel:[    0.000000] Command line: initrd=initrd.img inst.stage2=hd:LABEL=Fedora\x2018-Beta-TC4\x20x86_64 quiet BOOT_IMAGE=vmlinuz 
>03:52:09,305 INFO kernel:[    0.000000] e820: BIOS-provided physical RAM map:
>03:52:09,305 INFO kernel:[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009bbff] usable
>03:52:09,305 INFO kernel:[    0.000000] BIOS-e820: [mem 0x000000000009bc00-0x000000000009ffff] reserved
>03:52:09,305 INFO kernel:[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
>03:52:09,305 INFO kernel:[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003fffdfff] usable
>03:52:09,305 INFO kernel:[    0.000000] BIOS-e820: [mem 0x000000003fffe000-0x000000003fffffff] reserved
>03:52:09,305 INFO kernel:[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
>03:52:09,305 INFO kernel:[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
>03:52:09,305 INFO kernel:[    0.000000] NX (Execute Disable) protection: active
>03:52:09,305 INFO kernel:[    0.000000] DMI 2.4 present.
>03:52:09,305 DEBUG kernel:[    0.000000] DMI: Bochs Bochs, BIOS Bochs 01/01/2007
>03:52:09,305 INFO kernel:[    0.000000] Hypervisor detected: KVM
>03:52:09,305 DEBUG kernel:[    0.000000] e820: update [mem 0x00000000-0x0000ffff] usable ==> reserved
>03:52:09,305 DEBUG kernel:[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
>03:52:09,305 INFO kernel:[    0.000000] No AGP bridge found
>03:52:09,305 INFO kernel:[    0.000000] e820: last_pfn = 0x3fffe max_arch_pfn = 0x400000000
>03:52:09,305 DEBUG kernel:[    0.000000] MTRR default type: write-back
>03:52:09,305 DEBUG kernel:[    0.000000] MTRR fixed ranges enabled:
>03:52:09,305 DEBUG kernel:[    0.000000]   00000-9FFFF write-back
>03:52:09,305 DEBUG kernel:[    0.000000]   A0000-BFFFF uncachable
>03:52:09,305 DEBUG kernel:[    0.000000]   C0000-FFFFF write-protect
>03:52:09,305 DEBUG kernel:[    0.000000] MTRR variable ranges enabled:
>03:52:09,305 DEBUG kernel:[    0.000000]   0 base 00E0000000 mask FFE0000000 uncachable
>03:52:09,305 DEBUG kernel:[    0.000000]   1 disabled
>03:52:09,305 DEBUG kernel:[    0.000000]   2 disabled
>03:52:09,305 DEBUG kernel:[    0.000000]   3 disabled
>03:52:09,305 DEBUG kernel:[    0.000000]   4 disabled
>03:52:09,305 DEBUG kernel:[    0.000000]   5 disabled
>03:52:09,305 DEBUG kernel:[    0.000000]   6 disabled
>03:52:09,305 DEBUG kernel:[    0.000000]   7 disabled
>03:52:09,305 INFO kernel:[    0.000000] x86 PAT enabled: cpu 0, old 0x70406, new 0x7010600070106
>03:52:09,305 INFO kernel:[    0.000000] found SMP MP-table at [mem 0x000fdad0-0x000fdadf] mapped at [ffff8800000fdad0]
>03:52:09,305 DEBUG kernel:[    0.000000] initial memory mapped: [mem 0x00000000-0x1fffffff]
>03:52:09,305 DEBUG kernel:[    0.000000] Base memory trampoline at [ffff880000095000] 95000 size 24576
>03:52:09,305 INFO kernel:[    0.000000] init_memory_mapping: [mem 0x00000000-0x3fffdfff]
>03:52:09,305 DEBUG kernel:[    0.000000]  [mem 0x00000000-0x3fdfffff] page 2M
>03:52:09,305 DEBUG kernel:[    0.000000]  [mem 0x3fe00000-0x3fffdfff] page 4k
>03:52:09,305 DEBUG kernel:[    0.000000] kernel direct mapping tables up to 0x3fffdfff @ [mem 0x1fdfe000-0x1fffffff]
>03:52:09,305 INFO kernel:[    0.000000] RAMDISK: [mem 0x3e5db000-0x3ffdcfff]
>03:52:09,305 WARNING kernel:[    0.000000] ACPI: RSDP 00000000000fd970 00014 (v00 BOCHS )
>03:52:09,305 WARNING kernel:[    0.000000] ACPI: RSDT 000000003fffe5d0 00038 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
>03:52:09,305 WARNING kernel:[    0.000000] ACPI: FACP 000000003fffff80 00074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
>03:52:09,305 WARNING kernel:[    0.000000] ACPI: DSDT 000000003fffe610 01109 (v01   BXPC   BXDSDT 00000001 INTL 20100528)
>03:52:09,305 WARNING kernel:[    0.000000] ACPI: FACS 000000003fffff40 00040
>03:52:09,305 WARNING kernel:[    0.000000] ACPI: SSDT 000000003ffffea0 0009E (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
>03:52:09,305 WARNING kernel:[    0.000000] ACPI: APIC 000000003ffffdb0 00078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
>03:52:09,305 WARNING kernel:[    0.000000] ACPI: HPET 000000003ffffd70 00038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
>03:52:09,305 WARNING kernel:[    0.000000] ACPI: SSDT 000000003ffff720 00644 (v01   BXPC BXSSDTPC 00000001 INTL 20100528)
>03:52:09,305 DEBUG kernel:[    0.000000] ACPI: Local APIC address 0xfee00000
>03:52:09,305 INFO kernel:[    0.000000] No NUMA configuration found
>03:52:09,305 INFO kernel:[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000003fffdfff]
>03:52:09,305 INFO kernel:[    0.000000] Initmem setup node 0 [mem 0x00000000-0x3fffdfff]
>03:52:09,305 INFO kernel:[    0.000000]   NODE_DATA [mem 0x3ffea000-0x3fffdfff]
>03:52:09,305 INFO kernel:[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
>03:52:09,305 INFO kernel:[    0.000000] kvm-clock: cpu 0, msr 0:1cfac01, boot clock
>03:52:09,305 DEBUG kernel:[    0.000000]  [ffffea0000000000-ffffea0000ffffff] PMD -> [ffff88003cc00000-ffff88003dbfffff] on node 0
>03:52:09,305 WARNING kernel:[    0.000000] Zone ranges:
>03:52:09,305 WARNING kernel:[    0.000000]   DMA      [mem 0x00010000-0x00ffffff]
>03:52:09,305 WARNING kernel:[    0.000000]   DMA32    [mem 0x01000000-0xffffffff]
>03:52:09,305 WARNING kernel:[    0.000000]   Normal   empty
>03:52:09,305 WARNING kernel:[    0.000000] Movable zone start for each node
>03:52:09,305 WARNING kernel:[    0.000000] Early memory node ranges
>03:52:09,305 WARNING kernel:[    0.000000]   node   0: [mem 0x00010000-0x0009afff]
>03:52:09,305 WARNING kernel:[    0.000000]   node   0: [mem 0x00100000-0x3fffdfff]
>03:52:09,305 DEBUG kernel:[    0.000000] On node 0 totalpages: 262025
>03:52:09,305 DEBUG kernel:[    0.000000]   DMA zone: 64 pages used for memmap
>03:52:09,305 DEBUG kernel:[    0.000000]   DMA zone: 6 pages reserved
>03:52:09,305 DEBUG kernel:[    0.000000]   DMA zone: 3909 pages, LIFO batch:0
>03:52:09,305 DEBUG kernel:[    0.000000]   DMA32 zone: 4032 pages used for memmap
>03:52:09,305 DEBUG kernel:[    0.000000]   DMA32 zone: 254014 pages, LIFO batch:31
>03:52:09,305 INFO kernel:[    0.000000] ACPI: PM-Timer IO Port: 0xb008
>03:52:09,305 DEBUG kernel:[    0.000000] ACPI: Local APIC address 0xfee00000
>03:52:09,305 INFO kernel:[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
>03:52:09,305 INFO kernel:[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
>03:52:09,305 INFO kernel:[    0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
>03:52:09,305 INFO kernel:[    0.000000] IOAPIC[0]: apic_id 1, version 17, address 0xfec00000, GSI 0-23
>03:52:09,305 INFO kernel:[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
>03:52:09,305 INFO kernel:[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
>03:52:09,305 INFO kernel:[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
>03:52:09,305 INFO kernel:[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
>03:52:09,305 INFO kernel:[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
>03:52:09,305 DEBUG kernel:[    0.000000] ACPI: IRQ0 used by override.
>03:52:09,305 DEBUG kernel:[    0.000000] ACPI: IRQ2 used by override.
>03:52:09,305 DEBUG kernel:[    0.000000] ACPI: IRQ5 used by override.
>03:52:09,305 DEBUG kernel:[    0.000000] ACPI: IRQ9 used by override.
>03:52:09,305 DEBUG kernel:[    0.000000] ACPI: IRQ10 used by override.
>03:52:09,305 DEBUG kernel:[    0.000000] ACPI: IRQ11 used by override.
>03:52:09,305 INFO kernel:[    0.000000] Using ACPI (MADT) for SMP configuration information
>03:52:09,305 INFO kernel:[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
>03:52:09,305 INFO kernel:[    0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
>03:52:09,305 DEBUG kernel:[    0.000000] nr_irqs_gsi: 40
>03:52:09,305 INFO kernel:[    0.000000] PM: Registered nosave memory: 000000000009b000 - 000000000009c000
>03:52:09,305 INFO kernel:[    0.000000] PM: Registered nosave memory: 000000000009c000 - 00000000000a0000
>03:52:09,305 INFO kernel:[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000
>03:52:09,305 INFO kernel:[    0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000
>03:52:09,305 INFO kernel:[    0.000000] e820: [mem 0x40000000-0xfeffbfff] available for PCI devices
>03:52:09,305 INFO kernel:[    0.000000] Booting paravirtualized kernel on KVM
>03:52:09,305 INFO kernel:[    0.000000] setup_percpu: NR_CPUS:128 nr_cpumask_bits:128 nr_cpu_ids:1 nr_node_ids:1
>03:52:09,305 INFO kernel:[    0.000000] PERCPU: Embedded 28 pages/cpu @ffff88003e200000 s84288 r8192 d22208 u2097152
>03:52:09,305 DEBUG kernel:[    0.000000] pcpu-alloc: s84288 r8192 d22208 u2097152 alloc=1*2097152
>03:52:09,305 DEBUG kernel:[    0.000000] pcpu-alloc: [0] 0 
>03:52:09,305 INFO kernel:[    0.000000] kvm-clock: cpu 0, msr 0:3e213c01, primary cpu clock
>03:52:09,305 INFO kernel:[    0.000000] KVM setup async PF for cpu 0
>03:52:09,305 INFO kernel:[    0.000000] kvm-stealtime: cpu 0, msr 3e20dec0
>03:52:09,305 WARNING kernel:[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 257923
>03:52:09,305 WARNING kernel:[    0.000000] Policy zone: DMA32
>03:52:09,305 NOTICE kernel:[    0.000000] Kernel command line: initrd=initrd.img inst.stage2=hd:LABEL=Fedora\x2018-Beta-TC4\x20x86_64 quiet BOOT_IMAGE=vmlinuz 
>03:52:09,305 INFO kernel:[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
>03:52:09,305 NOTICE kernel:[    0.000000] __ex_table already sorted, skipping sort
>03:52:09,305 INFO kernel:[    0.000000] Checking aperture...
>03:52:09,305 INFO kernel:[    0.000000] No AGP bridge found
>03:52:09,306 INFO kernel:[    0.000000] Memory: 988760k/1048568k available (6322k kernel code, 468k absent, 59340k reserved, 6882k data, 1036k init)
>03:52:09,306 INFO kernel:[    0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
>03:52:09,306 INFO kernel:[    0.000000] Hierarchical RCU implementation.
>03:52:09,306 INFO kernel:[    0.000000] 	RCU restricting CPUs from NR_CPUS=128 to nr_cpu_ids=1.
>03:52:09,306 INFO kernel:[    0.000000] NR_IRQS:8448 nr_irqs:256 16
>03:52:09,306 INFO kernel:[    0.000000] Console: colour VGA+ 80x25
>03:52:09,306 INFO kernel:[    0.000000] console [tty0] enabled
>03:52:09,306 INFO kernel:[    0.000000] allocated 4194304 bytes of page_cgroup
>03:52:09,306 INFO kernel:[    0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
>03:52:09,306 DEBUG kernel:[    0.000000] hpet clockevent registered
>03:52:09,306 INFO kernel:[    0.000000] tsc: Detected 3311.134 MHz processor
>03:52:09,306 INFO kernel:[    0.001999] Calibrating delay loop (skipped) preset value.. 6622.26 BogoMIPS (lpj=3311134)
>03:52:09,306 INFO kernel:[    0.001999] pid_max: default: 32768 minimum: 301
>03:52:09,306 INFO kernel:[    0.001999] Security Framework initialized
>03:52:09,306 INFO kernel:[    0.001999] SELinux:  Initializing.
>03:52:09,306 DEBUG kernel:[    0.001999] SELinux:  Starting in permissive mode
>03:52:09,306 INFO kernel:[    0.001999] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
>03:52:09,306 INFO kernel:[    0.001999] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
>03:52:09,306 INFO kernel:[    0.001999] Mount-cache hash table entries: 256
>03:52:09,306 INFO kernel:[    0.001999] Initializing cgroup subsys cpuacct
>03:52:09,306 INFO kernel:[    0.001999] Initializing cgroup subsys memory
>03:52:09,306 INFO kernel:[    0.001999] Initializing cgroup subsys devices
>03:52:09,306 INFO kernel:[    0.001999] Initializing cgroup subsys freezer
>03:52:09,306 INFO kernel:[    0.001999] Initializing cgroup subsys net_cls
>03:52:09,306 INFO kernel:[    0.001999] Initializing cgroup subsys blkio
>03:52:09,306 INFO kernel:[    0.001999] Initializing cgroup subsys perf_event
>03:52:09,306 INFO kernel:[    0.001999] mce: CPU supports 10 MCE banks
>03:52:09,306 INFO kernel:[    0.001999] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
>03:52:09,306 INFO kernel:[    0.001999] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0
>03:52:09,306 INFO kernel:[    0.001999] tlb_flushall_shift is 0xffffffff
>03:52:09,306 INFO kernel:[    0.001999] SMP alternatives: switching to UP code
>03:52:09,306 INFO kernel:[    0.015137] Freeing SMP alternatives: 24k freed
>03:52:09,306 INFO kernel:[    0.015154] ACPI: Core revision 20120711
>03:52:09,306 INFO kernel:[    0.015790] ftrace: allocating 23458 entries in 92 pages
>03:52:09,306 INFO kernel:[    0.024153] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
>03:52:09,306 INFO kernel:[    0.024156] smpboot: CPU0: AMD QEMU Virtual CPU version 1.0.1 stepping 03
>03:52:09,306 INFO kernel:[    0.024996] Performance Events: Broken PMU hardware detected, using software events only.
>03:52:09,306 ERR kernel:[    0.024996] Failed to access perfctr msr (MSR c0010001 is ffffffffffffffff)
>03:52:09,306 WARNING kernel:[    0.024996] NMI watchdog: disabled (cpu0): hardware events not enabled
>03:52:09,306 INFO kernel:[    0.024996] Brought up 1 CPUs
>03:52:09,306 INFO kernel:[    0.024996] smpboot: Total of 1 processors activated (6622.26 BogoMIPS)
>03:52:09,306 INFO kernel:[    0.025287] devtmpfs: initialized
>03:52:09,306 INFO kernel:[    0.026160] atomic64 test passed for x86-64 platform with CX8 and with SSE
>03:52:09,306 INFO kernel:[    0.026242] RTC time:  3:52:02, date: 10/14/12
>03:52:09,306 INFO kernel:[    0.026280] NET: Registered protocol family 16
>03:52:09,306 INFO kernel:[    0.026458] ACPI: bus type pci registered
>03:52:09,306 INFO kernel:[    0.026571] PCI: Using configuration type 1 for base access
>03:52:09,306 INFO kernel:[    0.027287] bio: create slab <bio-0> at 0
>03:52:09,306 INFO kernel:[    0.027347] ACPI: Added _OSI(Module Device)
>03:52:09,306 INFO kernel:[    0.027348] ACPI: Added _OSI(Processor Device)
>03:52:09,306 INFO kernel:[    0.027349] ACPI: Added _OSI(3.0 _SCP Extensions)
>03:52:09,306 INFO kernel:[    0.027350] ACPI: Added _OSI(Processor Aggregator Device)
>03:52:09,306 DEBUG kernel:[    0.027909] ACPI: EC: Look up EC in DSDT
>03:52:09,306 INFO kernel:[    0.029544] ACPI: Interpreter enabled
>03:52:09,306 INFO kernel:[    0.029547] ACPI: (supports S0 S5)
>03:52:09,306 INFO kernel:[    0.029554] ACPI: Using IOAPIC for interrupt routing
>03:52:09,306 INFO kernel:[    0.032288] ACPI: No dock devices found.
>03:52:09,306 INFO kernel:[    0.032291] PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug
>03:52:09,306 INFO kernel:[    0.032310] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
>03:52:09,306 DEBUG kernel:[    0.032324] pci_root PNP0A03:00: host bridge window [io  0x0000-0x0cf7] (ignored)
>03:52:09,306 DEBUG kernel:[    0.032327] pci_root PNP0A03:00: host bridge window [io  0x0d00-0xffff] (ignored)
>03:52:09,306 DEBUG kernel:[    0.032328] pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff] (ignored)
>03:52:09,306 DEBUG kernel:[    0.032330] pci_root PNP0A03:00: host bridge window [mem 0xe0000000-0xfebfffff] (ignored)
>03:52:09,306 DEBUG kernel:[    0.032332] PCI: root bus 00: using default resources
>03:52:09,306 WARNING kernel:[    0.032334] pci_root PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
>03:52:09,306 INFO kernel:[    0.032357] PCI host bridge to bus 0000:00
>03:52:09,306 DEBUG kernel:[    0.032360] pci_bus 0000:00: busn_res: [bus 00-ff] is inserted under domain [bus 00-ff]
>03:52:09,306 INFO kernel:[    0.032362] pci_bus 0000:00: root bus resource [bus 00-ff]
>03:52:09,306 INFO kernel:[    0.032364] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
>03:52:09,306 INFO kernel:[    0.032365] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff]
>03:52:09,306 DEBUG kernel:[    0.032414] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
>03:52:09,306 DEBUG kernel:[    0.032814] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
>03:52:09,306 DEBUG kernel:[    0.033414] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
>03:52:09,306 DEBUG kernel:[    0.033998] pci 0000:00:01.1: reg 20: [io  0xc120-0xc12f]
>03:52:09,306 DEBUG kernel:[    0.034314] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300
>03:52:09,306 DEBUG kernel:[    0.036723] pci 0000:00:01.2: reg 20: [io  0xc080-0xc09f]
>03:52:09,306 DEBUG kernel:[    0.037073] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
>03:52:09,306 INFO kernel:[    0.037566] pci 0000:00:01.3: quirk: [io  0xb000-0xb03f] claimed by PIIX4 ACPI
>03:52:09,306 INFO kernel:[    0.037578] pci 0000:00:01.3: quirk: [io  0xb100-0xb10f] claimed by PIIX4 SMB
>03:52:09,306 DEBUG kernel:[    0.037755] pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000
>03:52:09,306 DEBUG kernel:[    0.039001] pci 0000:00:02.0: reg 10: [mem 0xf4000000-0xf7ffffff]
>03:52:09,306 DEBUG kernel:[    0.040003] pci 0000:00:02.0: reg 14: [mem 0xf8000000-0xfbffffff]
>03:52:09,306 DEBUG kernel:[    0.041744] pci 0000:00:02.0: reg 18: [mem 0xfc024000-0xfc025fff]
>03:52:09,306 DEBUG kernel:[    0.042999] pci 0000:00:02.0: reg 1c: [io  0xc0a0-0xc0bf]
>03:52:09,306 DEBUG kernel:[    0.046734] pci 0000:00:02.0: reg 30: [mem 0xfc000000-0xfc00ffff pref]
>03:52:09,306 DEBUG kernel:[    0.047100] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000
>03:52:09,306 DEBUG kernel:[    0.047320] pci 0000:00:03.0: reg 10: [io  0xc0c0-0xc0df]
>03:52:09,306 DEBUG kernel:[    0.047473] pci 0000:00:03.0: reg 14: [mem 0xfc026000-0xfc026fff]
>03:52:09,306 DEBUG kernel:[    0.048376] pci 0000:00:03.0: reg 30: [mem 0xfc010000-0xfc01ffff pref]
>03:52:09,306 DEBUG kernel:[    0.048693] pci 0000:00:04.0: [8086:2668] type 00 class 0x040300
>03:52:09,306 DEBUG kernel:[    0.048854] pci 0000:00:04.0: reg 10: [mem 0xfc020000-0xfc023fff]
>03:52:09,306 DEBUG kernel:[    0.049795] pci 0000:00:05.0: [1af4:1003] type 00 class 0x078000
>03:52:09,306 DEBUG kernel:[    0.049995] pci 0000:00:05.0: reg 10: [io  0xc0e0-0xc0ff]
>03:52:09,306 DEBUG kernel:[    0.050138] pci 0000:00:05.0: reg 14: [mem 0xfc027000-0xfc027fff]
>03:52:09,306 DEBUG kernel:[    0.051185] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
>03:52:09,306 DEBUG kernel:[    0.051419] pci 0000:00:06.0: reg 10: [io  0xc000-0xc03f]
>03:52:09,306 DEBUG kernel:[    0.051590] pci 0000:00:06.0: reg 14: [mem 0xfc028000-0xfc028fff]
>03:52:09,306 DEBUG kernel:[    0.052750] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000
>03:52:09,306 DEBUG kernel:[    0.052974] pci 0000:00:07.0: reg 10: [io  0xc040-0xc07f]
>03:52:09,306 DEBUG kernel:[    0.053148] pci 0000:00:07.0: reg 14: [mem 0xfc029000-0xfc029fff]
>03:52:09,306 DEBUG kernel:[    0.054219] pci 0000:00:08.0: [1af4:1002] type 00 class 0x050000
>03:52:09,306 DEBUG kernel:[    0.054348] pci 0000:00:08.0: reg 10: [io  0xc100-0xc11f]
>03:52:09,306 DEBUG kernel:[    0.055244] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
>03:52:09,306 INFO kernel:[    0.055641]  pci0000:00: ACPI _OSC support notification failed, disabling PCIe ASPM
>03:52:09,306 INFO kernel:[    0.055643]  pci0000:00: Unable to request _OSC control (_OSC support mask: 0x08)
>03:52:09,306 INFO kernel:[    0.059107] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
>03:52:09,306 INFO kernel:[    0.059204] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
>03:52:09,306 INFO kernel:[    0.059292] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
>03:52:09,306 INFO kernel:[    0.059380] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
>03:52:09,306 INFO kernel:[    0.059467] ACPI: PCI Interrupt Link [LNKS] (IRQs 9) *0
>03:52:09,306 INFO kernel:[    0.059588] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
>03:52:09,306 INFO kernel:[    0.059591] vgaarb: loaded
>03:52:09,306 INFO kernel:[    0.059592] vgaarb: bridge control possible 0000:00:02.0
>03:52:09,306 NOTICE kernel:[    0.059658] SCSI subsystem initialized
>03:52:09,306 INFO kernel:[    0.059659] ACPI: bus type scsi registered
>03:52:09,306 DEBUG kernel:[    0.059699] libata version 3.00 loaded.
>03:52:09,306 INFO kernel:[    0.059722] ACPI: bus type usb registered
>03:52:09,306 INFO kernel:[    0.059736] usbcore: registered new interface driver usbfs
>03:52:09,306 INFO kernel:[    0.059747] usbcore: registered new interface driver hub
>03:52:09,306 INFO kernel:[    0.059762] usbcore: registered new device driver usb
>03:52:09,306 INFO kernel:[    0.059803] PCI: Using ACPI for IRQ routing
>03:52:09,306 DEBUG kernel:[    0.059805] PCI: pci_cache_line_size set to 64 bytes
>03:52:09,306 DEBUG kernel:[    0.060052] e820: reserve RAM buffer [mem 0x0009bc00-0x0009ffff]
>03:52:09,306 DEBUG kernel:[    0.060054] e820: reserve RAM buffer [mem 0x3fffe000-0x3fffffff]
>03:52:09,306 INFO kernel:[    0.060132] NetLabel: Initializing
>03:52:09,306 INFO kernel:[    0.060134] NetLabel:  domain hash size = 128
>03:52:09,306 INFO kernel:[    0.060134] NetLabel:  protocols = UNLABELED CIPSOv4
>03:52:09,306 INFO kernel:[    0.060145] NetLabel:  unlabeled traffic allowed by default
>03:52:09,306 INFO kernel:[    0.060184] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
>03:52:09,306 INFO kernel:[    0.060203] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
>03:52:09,306 INFO kernel:[    0.060206] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
>03:52:09,306 INFO kernel:[    0.065020] Switching to clocksource kvm-clock
>03:52:09,306 INFO kernel:[    0.071814] pnp: PnP ACPI init
>03:52:09,306 INFO kernel:[    0.071828] ACPI: bus type pnp registered
>03:52:09,306 DEBUG kernel:[    0.071851] pnp 00:00: [bus 00-ff]
>03:52:09,306 DEBUG kernel:[    0.071853] pnp 00:00: [io  0x0cf8-0x0cff]
>03:52:09,306 DEBUG kernel:[    0.071855] pnp 00:00: [io  0x0000-0x0cf7 window]
>03:52:09,306 DEBUG kernel:[    0.071856] pnp 00:00: [io  0x0d00-0xffff window]
>03:52:09,306 DEBUG kernel:[    0.071858] pnp 00:00: [mem 0x000a0000-0x000bffff window]
>03:52:09,306 DEBUG kernel:[    0.071860] pnp 00:00: [mem 0xe0000000-0xfebfffff window]
>03:52:09,306 DEBUG kernel:[    0.071886] pnp 00:00: Plug and Play ACPI device, IDs PNP0a03 (active)
>03:52:09,306 DEBUG kernel:[    0.071897] pnp 00:01: [io  0x0070-0x0071]
>03:52:09,306 DEBUG kernel:[    0.071922] pnp 00:01: [irq 8]
>03:52:09,306 DEBUG kernel:[    0.071924] pnp 00:01: [io  0x0072-0x0077]
>03:52:09,306 DEBUG kernel:[    0.071939] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active)
>03:52:09,306 DEBUG kernel:[    0.071971] pnp 00:02: [io  0x0060]
>03:52:09,306 DEBUG kernel:[    0.071973] pnp 00:02: [io  0x0064]
>03:52:09,306 DEBUG kernel:[    0.071988] pnp 00:02: [irq 1]
>03:52:09,306 DEBUG kernel:[    0.072002] pnp 00:02: Plug and Play ACPI device, IDs PNP0303 (active)
>03:52:09,306 DEBUG kernel:[    0.072049] pnp 00:03: [irq 12]
>03:52:09,306 DEBUG kernel:[    0.072065] pnp 00:03: Plug and Play ACPI device, IDs PNP0f13 (active)
>03:52:09,306 DEBUG kernel:[    0.072087] pnp 00:04: [io  0x03f2-0x03f5]
>03:52:09,306 DEBUG kernel:[    0.072088] pnp 00:04: [io  0x03f7]
>03:52:09,306 DEBUG kernel:[    0.072103] pnp 00:04: [irq 6]
>03:52:09,306 DEBUG kernel:[    0.072105] pnp 00:04: [dma 2]
>03:52:09,306 DEBUG kernel:[    0.072119] pnp 00:04: Plug and Play ACPI device, IDs PNP0700 (active)
>03:52:09,306 DEBUG kernel:[    0.072204] pnp 00:05: [io  0x03f8-0x03ff]
>03:52:09,306 DEBUG kernel:[    0.072219] pnp 00:05: [irq 4]
>03:52:09,306 DEBUG kernel:[    0.072236] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
>03:52:09,306 DEBUG kernel:[    0.072359] pnp 00:06: [mem 0xfed00000-0xfed003ff]
>03:52:09,306 DEBUG kernel:[    0.072379] pnp 00:06: Plug and Play ACPI device, IDs PNP0103 (active)
>03:52:09,306 INFO kernel:[    0.072520] pnp: PnP ACPI: found 7 devices
>03:52:09,306 INFO kernel:[    0.072521] ACPI: ACPI bus type pnp unregistered
>03:52:09,306 DEBUG kernel:[    0.078711] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
>03:52:09,306 DEBUG kernel:[    0.078714] pci_bus 0000:00: resource 5 [mem 0x00000000-0xffffffffff]
>03:52:09,306 INFO kernel:[    0.078746] NET: Registered protocol family 2
>03:52:09,306 INFO kernel:[    0.079364] TCP established hash table entries: 131072 (order: 9, 2097152 bytes)
>03:52:09,306 INFO kernel:[    0.079816] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
>03:52:09,306 INFO kernel:[    0.079962] TCP: Hash tables configured (established 131072 bind 65536)
>03:52:09,306 INFO kernel:[    0.079983] TCP: reno registered
>03:52:09,306 INFO kernel:[    0.079987] UDP hash table entries: 512 (order: 2, 16384 bytes)
>03:52:09,306 INFO kernel:[    0.079993] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
>03:52:09,306 INFO kernel:[    0.080065] NET: Registered protocol family 1
>03:52:09,306 INFO kernel:[    0.080076] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
>03:52:09,306 INFO kernel:[    0.080099] pci 0000:00:01.0: PIIX3: Enabling Passive Release
>03:52:09,306 INFO kernel:[    0.080125] pci 0000:00:01.0: Activating ISA DMA hang workarounds
>03:52:09,306 WARNING kernel:[    0.080297] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
>03:52:09,306 DEBUG kernel:[    0.080456] pci 0000:00:02.0: Boot video device
>03:52:09,306 DEBUG kernel:[    0.080534] PCI: CLS 0 bytes, default 64
>03:52:09,306 INFO kernel:[    0.080576] Unpacking initramfs...
>03:52:09,306 INFO kernel:[    2.943716] Freeing initrd memory: 26632k freed
>03:52:09,306 NOTICE kernel:[    2.950451] Initialise module verification
>03:52:09,306 INFO kernel:[    2.950490] audit: initializing netlink socket (disabled)
>03:52:09,306 NOTICE kernel:[    2.950500] type=2000 audit(1350186726.949:1): initialized
>03:52:09,306 INFO kernel:[    2.966628] HugeTLB registered 2 MB page size, pre-allocated 0 pages
>03:52:09,306 NOTICE kernel:[    2.967995] VFS: Disk quotas dquot_6.5.2
>03:52:09,306 WARNING kernel:[    2.968050] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
>03:52:09,306 INFO kernel:[    2.968426] msgmni has been set to 1983
>03:52:09,306 DEBUG kernel:[    2.968478] SELinux:  Registering netfilter hooks
>03:52:09,306 INFO kernel:[    2.968866] alg: No test for stdrng (krng)
>03:52:09,306 INFO kernel:[    2.968870] NET: Registered protocol family 38
>03:52:09,306 NOTICE kernel:[    2.968872] Key type asymmetric registered
>03:52:09,306 NOTICE kernel:[    2.968873] Asymmetric key parser 'x509' registered
>03:52:09,306 INFO kernel:[    2.968903] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
>03:52:09,306 INFO kernel:[    2.968921] io scheduler noop registered
>03:52:09,306 INFO kernel:[    2.968922] io scheduler deadline registered
>03:52:09,306 INFO kernel:[    2.968951] io scheduler cfq registered (default)
>03:52:09,306 INFO kernel:[    2.969046] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
>03:52:09,306 INFO kernel:[    2.969063] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
>03:52:09,306 INFO kernel:[    2.969064] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
>03:52:09,306 INFO kernel:[    2.969133] acpiphp: Slot [3] registered
>03:52:09,306 INFO kernel:[    2.969148] acpiphp: Slot [4] registered
>03:52:09,306 INFO kernel:[    2.969161] acpiphp: Slot [5] registered
>03:52:09,306 INFO kernel:[    2.969176] acpiphp: Slot [6] registered
>03:52:09,306 INFO kernel:[    2.969189] acpiphp: Slot [7] registered
>03:52:09,306 INFO kernel:[    2.969205] acpiphp: Slot [8] registered
>03:52:09,306 INFO kernel:[    2.969219] acpiphp: Slot [9] registered
>03:52:09,306 INFO kernel:[    2.969234] acpiphp: Slot [10] registered
>03:52:09,306 INFO kernel:[    2.969250] acpiphp: Slot [11] registered
>03:52:09,306 INFO kernel:[    2.969264] acpiphp: Slot [12] registered
>03:52:09,306 INFO kernel:[    2.969277] acpiphp: Slot [13] registered
>03:52:09,306 INFO kernel:[    2.969292] acpiphp: Slot [14] registered
>03:52:09,306 INFO kernel:[    2.969305] acpiphp: Slot [15] registered
>03:52:09,306 INFO kernel:[    2.969318] acpiphp: Slot [16] registered
>03:52:09,306 INFO kernel:[    2.969333] acpiphp: Slot [17] registered
>03:52:09,306 INFO kernel:[    2.969348] acpiphp: Slot [18] registered
>03:52:09,306 INFO kernel:[    2.969361] acpiphp: Slot [19] registered
>03:52:09,306 INFO kernel:[    2.969374] acpiphp: Slot [20] registered
>03:52:09,306 INFO kernel:[    2.969388] acpiphp: Slot [21] registered
>03:52:09,306 INFO kernel:[    2.969402] acpiphp: Slot [22] registered
>03:52:09,306 INFO kernel:[    2.969428] acpiphp: Slot [23] registered
>03:52:09,306 INFO kernel:[    2.969447] acpiphp: Slot [24] registered
>03:52:09,306 INFO kernel:[    2.969461] acpiphp: Slot [25] registered
>03:52:09,306 INFO kernel:[    2.969476] acpiphp: Slot [26] registered
>03:52:09,306 INFO kernel:[    2.969490] acpiphp: Slot [27] registered
>03:52:09,306 INFO kernel:[    2.969503] acpiphp: Slot [28] registered
>03:52:09,306 INFO kernel:[    2.969516] acpiphp: Slot [29] registered
>03:52:09,306 INFO kernel:[    2.969531] acpiphp: Slot [30] registered
>03:52:09,306 INFO kernel:[    2.969544] acpiphp: Slot [31] registered
>03:52:09,306 INFO kernel:[    2.969716] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
>03:52:09,306 INFO kernel:[    2.969719] ACPI: Power Button [PWRF]
>03:52:09,306 INFO kernel:[    2.971616] GHES: HEST is not enabled!
>03:52:09,306 WARNING kernel:[    2.971824] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
>03:52:09,306 DEBUG kernel:[    2.971876] virtio-pci 0000:00:03.0: setting latency timer to 64
>03:52:09,306 WARNING kernel:[    2.972143] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
>03:52:09,306 DEBUG kernel:[    2.972175] virtio-pci 0000:00:05.0: setting latency timer to 64
>03:52:09,306 WARNING kernel:[    2.972406] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
>03:52:09,306 DEBUG kernel:[    2.972450] virtio-pci 0000:00:06.0: setting latency timer to 64
>03:52:09,306 DEBUG kernel:[    2.972695] virtio-pci 0000:00:07.0: setting latency timer to 64
>03:52:09,306 DEBUG kernel:[    2.972836] virtio-pci 0000:00:08.0: setting latency timer to 64
>03:52:09,306 INFO kernel:[    2.972923] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
>03:52:09,306 INFO kernel:[    2.995463] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
>03:52:09,306 INFO kernel:[    3.018387] 00:05: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
>03:52:09,306 DEBUG kernel:[    3.018760] virtio-pci 0000:00:05.0: irq 40 for MSI/MSI-X
>03:52:09,306 DEBUG kernel:[    3.018782] virtio-pci 0000:00:05.0: irq 41 for MSI/MSI-X
>03:52:09,306 INFO kernel:[    3.056981] Non-volatile memory driver v1.3
>03:52:09,306 INFO kernel:[    3.056983] Linux agpgart interface v0.103
>03:52:09,306 INFO kernel:[    3.057659] loop: module loaded
>03:52:09,306 DEBUG kernel:[    3.057717] ata_piix 0000:00:01.1: version 2.13
>03:52:09,306 DEBUG kernel:[    3.057810] ata_piix 0000:00:01.1: setting latency timer to 64
>03:52:09,306 INFO kernel:[    3.058147] scsi0 : ata_piix
>03:52:09,306 INFO kernel:[    3.058197] scsi1 : ata_piix
>03:52:09,306 INFO kernel:[    3.058225] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14
>03:52:09,306 INFO kernel:[    3.058227] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15
>03:52:09,306 INFO kernel:[    3.058282] libphy: Fixed MDIO Bus: probed
>03:52:09,306 INFO kernel:[    3.058313] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
>03:52:09,306 INFO kernel:[    3.058324] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
>03:52:09,306 INFO kernel:[    3.058333] uhci_hcd: USB Universal Host Controller Interface driver
>03:52:09,306 DEBUG kernel:[    3.058390] uhci_hcd 0000:00:01.2: setting latency timer to 64
>03:52:09,306 INFO kernel:[    3.058401] uhci_hcd 0000:00:01.2: UHCI Host Controller
>03:52:09,306 INFO kernel:[    3.058439] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
>03:52:09,306 INFO kernel:[    3.058557] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c080
>03:52:09,306 INFO kernel:[    3.058642] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
>03:52:09,306 INFO kernel:[    3.058644] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
>03:52:09,306 INFO kernel:[    3.058645] usb usb1: Product: UHCI Host Controller
>03:52:09,306 INFO kernel:[    3.058647] usb usb1: Manufacturer: Linux 3.6.1-1.fc18.x86_64 uhci_hcd
>03:52:09,306 INFO kernel:[    3.058648] usb usb1: SerialNumber: 0000:00:01.2
>03:52:09,306 INFO kernel:[    3.058724] hub 1-0:1.0: USB hub found
>03:52:09,306 INFO kernel:[    3.058728] hub 1-0:1.0: 2 ports detected
>03:52:09,306 INFO kernel:[    3.058838] usbcore: registered new interface driver usbserial
>03:52:09,306 INFO kernel:[    3.058845] usbcore: registered new interface driver usbserial_generic
>03:52:09,306 INFO kernel:[    3.058851] USB Serial support registered for generic
>03:52:09,306 INFO kernel:[    3.058852] usbserial: USB Serial Driver core
>03:52:09,306 INFO kernel:[    3.058885] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
>03:52:09,306 INFO kernel:[    3.059814] serio: i8042 KBD port at 0x60,0x64 irq 1
>03:52:09,306 INFO kernel:[    3.059819] serio: i8042 AUX port at 0x60,0x64 irq 12
>03:52:09,306 INFO kernel:[    3.059888] mousedev: PS/2 mouse device common for all mice
>03:52:09,306 INFO kernel:[    3.060229] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
>03:52:09,306 INFO kernel:[    3.065590] rtc_cmos 00:01: RTC can wake from S4
>03:52:09,306 INFO kernel:[    3.066478] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0
>03:52:09,306 INFO kernel:[    3.066785] rtc0: alarms up to one day, 114 bytes nvram, hpet irqs
>03:52:09,306 INFO kernel:[    3.066933] device-mapper: uevent: version 1.0.3
>03:52:09,306 INFO kernel:[    3.067107] device-mapper: ioctl: 4.23.0-ioctl (2012-07-25) initialised: dm-devel@redhat.com
>03:52:09,306 INFO kernel:[    3.067231] cpuidle: using governor ladder
>03:52:09,306 INFO kernel:[    3.067234] cpuidle: using governor menu
>03:52:09,306 INFO kernel:[    3.067341] EFI Variables Facility v0.08 2004-May-17
>03:52:09,306 INFO kernel:[    3.067557] usbcore: registered new interface driver usbhid
>03:52:09,306 INFO kernel:[    3.067560] usbhid: USB HID core driver
>03:52:09,306 INFO kernel:[    3.067590] drop_monitor: Initializing network drop monitor service
>03:52:09,306 INFO kernel:[    3.067722] ip_tables: (C) 2000-2006 Netfilter Core Team
>03:52:09,306 INFO kernel:[    3.069056] TCP: cubic registered
>03:52:09,306 INFO kernel:[    3.069060] Initializing XFRM netlink socket
>03:52:09,306 INFO kernel:[    3.069272] NET: Registered protocol family 10
>03:52:09,306 INFO kernel:[    3.069603] mip6: Mobile IPv6
>03:52:09,306 INFO kernel:[    3.069607] NET: Registered protocol family 17
>03:52:09,306 DEBUG kernel:[    3.069933] PM: Hibernation image not present or could not be loaded.
>03:52:09,306 NOTICE kernel:[    3.069936] Loading module verification certificates
>03:52:09,306 WARNING kernel:[    3.069968] Cert Valid From: 2012-10-08 16:53:34
>03:52:09,306 WARNING kernel:[    3.069971] Cert Valid To: 2112-09-14 16:53:34
>03:52:09,306 WARNING kernel:[    3.069975] Now: 2012-10-14 03:52:07
>03:52:09,306 NOTICE kernel:[    3.072267] MODSIGN: Loaded cert 'Fedora kernel signing key: c814c8ca34b3e7ee0fe44d0c19fe2dea1a58507b'
>03:52:09,306 INFO kernel:[    3.072289] registered taskstats version 1
>03:52:09,306 INFO kernel:[    3.073461]   Magic number: 0:730:866
>03:52:09,306 INFO kernel:[    3.073751] rtc_cmos 00:01: setting system clock to 2012-10-14 03:52:05 UTC (1350186725)
>03:52:09,306 DEBUG kernel:[    3.219902] ata2.01: NODEV after polling detection
>03:52:09,306 INFO kernel:[    3.221296] ata2.00: ATAPI: QEMU DVD-ROM, 1.0.1, max UDMA/100
>03:52:09,306 INFO kernel:[    3.222557] ata2.00: configured for MWDMA2
>03:52:09,306 NOTICE kernel:[    3.223946] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     1.0. PQ: 0 ANSI: 5
>03:52:09,306 WARNING kernel:[    3.225218] sr0: scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
>03:52:09,306 INFO kernel:[    3.225223] cdrom: Uniform CD-ROM driver Revision: 3.20
>03:52:09,306 DEBUG kernel:[    3.225401] sr 1:0:0:0: Attached scsi CD-ROM sr0
>03:52:09,306 NOTICE kernel:[    3.225512] sr 1:0:0:0: Attached scsi generic sg0 type 5
>03:52:09,306 INFO kernel:[    3.231835] Freeing unused kernel memory: 1036k freed
>03:52:09,306 INFO kernel:[    3.232294] Write protecting the kernel read-only data: 12288k
>03:52:09,306 INFO kernel:[    3.242440] Freeing unused kernel memory: 1860k freed
>03:52:09,306 INFO kernel:[    3.249613] Freeing unused kernel memory: 1416k freed
>03:52:09,378 INFO systemd: systemd 194 running in system mode. (+PAM +LIBWRAP +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ; fedora)
>03:52:09,378 INFO systemd: Detected virtualization 'kvm'.
>03:52:09,378 INFO systemd: Initializing machine ID from KVM UUID.
>03:52:09,378 WARNING systemd: Cannot add dependency job for unit udev-settle.service, ignoring: Unit udev-settle.service failed to load: No such file or directory. See system logs and 'systemctl status udev-settle.service' for details.
>03:52:09,378 INFO systemd: Started Remount Root and Kernel File Systems.
>03:52:09,378 INFO systemd: Starting Local File Systems (Pre).
>03:52:09,378 INFO systemd: Reached target Local File Systems (Pre).
>03:52:09,378 INFO systemd: Mounting Temporary Directory...
>03:52:09,378 INFO systemd: Starting Load Random Seed...
>03:52:09,378 INFO systemd: Started Load Random Seed.
>03:52:09,378 INFO systemd: Mounted POSIX Message Queue File System.
>03:52:09,443 INFO kernel:[    3.329265] BIOS EDD facility v0.16 2004-Jun-25, 2 devices found
>03:52:09,443 INFO kernel:[    3.360033] usb 1-1: new full-speed USB device number 2 using uhci_hcd
>03:52:09,443 INFO kernel:[    3.411218] squashfs: version 4.0 (2009/01/31) Phillip Lougher
>03:52:09,443 INFO kernel:[    3.414695] Loading iSCSI transport class v2.0-870.
>03:52:09,443 NOTICE kernel:[    3.419311] iscsi: registered transport (tcp)
>03:52:09,443 INFO kernel:[    3.421888] alua: device handler registered
>03:52:09,443 INFO kernel:[    3.424244] emc: device handler registered
>03:52:09,548 INFO NetworkManager: <info> NetworkManager (version 0.9.7.0-4.git20121004.fc18) is starting...
>03:52:09,601 INFO kernel:[    3.426544] hp_sw: device handler registered
>03:52:09,601 INFO kernel:[    3.428948] rdac: device handler registered
>03:52:09,601 INFO kernel:[    3.443595] FDC 0 is a S82078B
>03:52:09,601 INFO kernel:[    3.455954] No iBFT detected.
>03:52:09,601 INFO kernel:[    3.462876] md: raid0 personality registered for level 0
>03:52:09,601 INFO kernel:[    3.467170] md: raid1 personality registered for level 1
>03:52:09,601 INFO kernel:[    3.469753] async_tx: api initialized (async)
>03:52:09,601 INFO kernel:[    3.470909] xor: automatically using best checksumming function:
>03:52:09,601 INFO kernel:[    3.480014]    generic_sse:  8816.000 MB/sec
>03:52:09,601 WARNING kernel:[    3.500017] raid6: sse2x1    4207 MB/s
>03:52:09,601 WARNING kernel:[    3.517016] raid6: sse2x2    8121 MB/s
>03:52:09,601 WARNING kernel:[    3.534136] raid6: sse2x4   10054 MB/s
>03:52:09,601 WARNING kernel:[    3.534138] raid6: using algorithm sse2x4 (10054 MB/s)
>03:52:09,601 WARNING kernel:[    3.534139] raid6: using intx1 recovery algorithm
>03:52:09,601 INFO kernel:[    3.541778] md: raid6 personality registered for level 6
>03:52:09,601 INFO kernel:[    3.541780] md: raid5 personality registered for level 5
>03:52:09,601 INFO kernel:[    3.541781] md: raid4 personality registered for level 4
>03:52:09,601 INFO kernel:[    3.547829] md: raid10 personality registered for level 10
>03:52:09,601 INFO kernel:[    3.550410] md: linear personality registered for level -1
>03:52:09,601 INFO kernel:[    3.558096] device-mapper: multipath: version 1.5.0 loaded
>03:52:09,601 INFO kernel:[    3.560655] device-mapper: multipath round-robin: version 1.0.0 loaded
>03:52:09,601 INFO kernel:[    3.570761] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2
>03:52:09,601 INFO kernel:[    3.609097] RPC: Registered named UNIX socket transport module.
>03:52:09,601 INFO kernel:[    3.609099] RPC: Registered udp transport module.
>03:52:09,601 INFO kernel:[    3.609100] RPC: Registered tcp transport module.
>03:52:09,601 INFO kernel:[    3.609101] RPC: Registered tcp NFSv4.1 backchannel transport module.
>03:52:09,601 INFO kernel:[    3.759785] usb 1-1: New USB device found, idVendor=0627, idProduct=0001
>03:52:09,601 INFO kernel:[    3.759789] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=5
>03:52:09,601 INFO kernel:[    3.759791] usb 1-1: Product: QEMU USB Tablet
>03:52:09,601 INFO kernel:[    3.759793] usb 1-1: Manufacturer: QEMU 1.0.1
>03:52:09,601 INFO kernel:[    3.759795] usb 1-1: SerialNumber: 42
>03:52:09,601 INFO kernel:[    3.794892] input: QEMU 1.0.1 QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/input/input3
>03:52:09,601 INFO kernel:[    3.794968] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Pointer [QEMU 1.0.1 QEMU USB Tablet] on usb-0000:00:01.2-1/input0
>03:52:09,601 DEBUG kernel:[    3.810217] virtio-pci 0000:00:03.0: irq 42 for MSI/MSI-X
>03:52:09,601 DEBUG kernel:[    3.810241] virtio-pci 0000:00:03.0: irq 43 for MSI/MSI-X
>03:52:09,601 DEBUG kernel:[    3.810264] virtio-pci 0000:00:03.0: irq 44 for MSI/MSI-X
>03:52:09,601 DEBUG kernel:[    3.839903] virtio-pci 0000:00:06.0: irq 45 for MSI/MSI-X
>03:52:09,601 DEBUG kernel:[    3.839968] virtio-pci 0000:00:06.0: irq 46 for MSI/MSI-X
>03:52:09,602 INFO kernel:[    3.952067] tsc: Refined TSC clocksource calibration: 3311.132 MHz
>03:52:09,602 INFO kernel:[    3.964853]  vda: unknown partition table
>03:52:09,602 DEBUG kernel:[    3.965308] virtio-pci 0000:00:07.0: irq 47 for MSI/MSI-X
>03:52:09,602 DEBUG kernel:[    3.965332] virtio-pci 0000:00:07.0: irq 48 for MSI/MSI-X
>03:52:09,602 INFO kernel:[    3.995809]  vdb: unknown partition table
>03:52:09,602 DEBUG kernel:[    4.098377] ISO 9660 Extensions: Microsoft Joliet Level 3
>03:52:09,602 DEBUG kernel:[    4.101893] ISO 9660 Extensions: RRIP_1991A
>03:52:09,602 INFO kernel:[    4.254817] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
>03:52:09,602 DEBUG kernel:[    5.057096] SELinux: 2048 avtab hash slots, 92171 rules.
>03:52:09,602 DEBUG kernel:[    5.074368] SELinux: 2048 avtab hash slots, 92171 rules.
>03:52:09,602 DEBUG kernel:[    5.183335] SELinux:  9 users, 15 roles, 4276 types, 230 bools, 1 sens, 1024 cats
>03:52:09,602 DEBUG kernel:[    5.183340] SELinux:  83 classes, 92171 rules
>03:52:09,602 INFO kernel:[    5.187768] SELinux:  Permission compromise_kernel in class capability2 not defined in policy.
>03:52:09,602 INFO kernel:[    5.187773] SELinux: the above unknown classes and permissions will be allowed
>03:52:09,612 INFO NetworkManager: <info> Read config file /etc/NetworkManager/NetworkManager.conf
>03:52:09,612 INFO NetworkManager: <info> WEXT support is enabled
>03:52:09,612 INFO systemd: Starting D-Bus System Message Bus...
>03:52:09,612 INFO systemd: Started D-Bus System Message Bus.
>03:52:09,612 DEBUG kernel:[    5.187779] SELinux:  Completing initialization.
>03:52:09,612 DEBUG kernel:[    5.187780] SELinux:  Setting up existing superblocks.
>03:52:09,612 DEBUG kernel:[    5.187785] SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.187790] SELinux: initialized (dev rootfs, type rootfs), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.187798] SELinux: initialized (dev bdev, type bdev), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.187802] SELinux: initialized (dev proc, type proc), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.187808] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.187818] SELinux: initialized (dev devtmpfs, type devtmpfs), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.188522] SELinux: initialized (dev sockfs, type sockfs), uses task SIDs
>03:52:09,612 DEBUG kernel:[    5.188525] SELinux: initialized (dev debugfs, type debugfs), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189099] SELinux: initialized (dev pipefs, type pipefs), uses task SIDs
>03:52:09,612 DEBUG kernel:[    5.189103] SELinux: initialized (dev anon_inodefs, type anon_inodefs), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189106] SELinux: initialized (dev devpts, type devpts), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.189119] SELinux: initialized (dev hugetlbfs, type hugetlbfs), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.189125] SELinux: initialized (dev mqueue, type mqueue), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.189130] SELinux: initialized (dev selinuxfs, type selinuxfs), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189139] SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189329] SELinux: initialized (dev securityfs, type securityfs), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189331] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.189336] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.189494] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.189528] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189531] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189535] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189547] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189552] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189555] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189557] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189560] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189564] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189569] SELinux: initialized (dev rpc_pipefs, type rpc_pipefs), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189573] SELinux: initialized (dev sr0, type iso9660), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.189581] SELinux: initialized (dev loop0, type squashfs), uses xattr
>03:52:09,612 DEBUG kernel:[    5.189596] SELinux: initialized (dev dm-0, type ext4), uses xattr
>03:52:09,612 NOTICE kernel:[    5.191395] type=1403 audit(1350186727.616:2): policy loaded auid=4294967295 ses=4294967295
>03:52:09,612 DEBUG kernel:[    5.742721] SELinux: initialized (dev autofs, type autofs), uses genfs_contexts
>03:52:09,612 DEBUG kernel:[    5.881283] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.896768] SELinux: initialized (dev hugetlbfs, type hugetlbfs), uses transition SIDs
>03:52:09,612 DEBUG kernel:[    5.995557] SELinux: initialized (dev configfs, type configfs), uses genfs_contexts
>03:52:09,612 NOTICE kernel:[    6.429374] type=1400 audit(1350186728.854:3): avc:  denied  { create } for  pid=481 comm="systemd-tmpfile" name="tmp" scontext=system_u:system_r:systemd_tmpfiles_t:s0 tcontext=system_u:object_r:var_t:s0 tclass=dir
>03:52:09,612 NOTICE kernel:[    6.429411] type=1400 audit(1350186728.854:4): avc:  denied  { setattr } for  pid=481 comm="systemd-tmpfile" name="tmp" dev="dm-0" ino=1672 scontext=system_u:system_r:systemd_tmpfiles_t:s0 tcontext=system_u:object_r:var_t:s0 tclass=dir
>03:52:09,612 NOTICE kernel:[    6.429444] type=1400 audit(1350186728.854:5): avc:  denied  { relabelfrom } for  pid=481 comm="systemd-tmpfile" name="tmp" dev="dm-0" ino=1672 scontext=system_u:system_r:systemd_tmpfiles_t:s0 tcontext=system_u:object_r:var_t:s0 tclass=dir
>03:52:09,612 NOTICE kernel:[    7.067311] type=1400 audit(1350186729.492:6): avc:  denied  { execute } for  pid=539 comm="bash" name="hostname" dev="dm-0" ino=59588 scontext=system_u:system_r:getty_t:s0 tcontext=unconfined_u:object_r:hostname_exec_t:s0 tclass=file
>03:52:09,612 NOTICE kernel:[    7.067331] type=1400 audit(1350186729.492:7): avc:  denied  { read open } for  pid=539 comm="bash" path="/usr/bin/hostname" dev="dm-0" ino=59588 scontext=system_u:system_r:getty_t:s0 tcontext=unconfined_u:object_r:hostname_exec_t:s0 tclass=file
>03:52:09,612 NOTICE kernel:[    7.067351] type=1400 audit(1350186729.492:8): avc:  denied  { execute_no_trans } for  pid=539 comm="bash" path="/usr/bin/hostname" dev="dm-0" ino=59588 scontext=system_u:system_r:getty_t:s0 tcontext=unconfined_u:object_r:hostname_exec_t:s0 tclass=file
>03:52:09,612 NOTICE kernel:[    7.186048] type=1400 audit(1350186729.611:9): avc:  denied  { read } for  pid=526 comm="bash" name=".profile" dev="dm-0" ino=59989 scontext=system_u:system_r:getty_t:s0 tcontext=unconfined_u:object_r:admin_home_t:s0 tclass=file
>03:52:09,612 NOTICE kernel:[    7.186059] type=1400 audit(1350186729.611:10): avc:  denied  { open } for  pid=526 comm="bash" path="/root/.profile" dev="dm-0" ino=59989 scontext=system_u:system_r:getty_t:s0 tcontext=unconfined_u:object_r:admin_home_t:s0 tclass=file
>03:52:09,749 INFO dbus-daemon: dbus[541]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
>03:52:09,750 NOTICE dbus: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
>03:52:09,765 INFO systemd: Starting Authorization Manager...
>03:52:09,919 INFO polkitd: Started polkitd version 0.107
>03:52:10,090 INFO dbus-daemon: dbus[541]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
>03:52:10,090 NOTICE dbus: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
>03:52:10,090 INFO systemd: Started Authorization Manager.
>03:52:10,131 WARNING NetworkManager:    ifcfg-rh: Could not get hostname: failed to read /etc/sysconfig/network
>03:52:10,133 NOTICE NetworkManager:    ifcfg-rh: Acquired D-Bus service com.redhat.ifcfgrh1
>03:52:10,133 INFO NetworkManager: <info> Loaded plugin ifcfg-rh: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
>03:52:10,133 INFO NetworkManager: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
>03:52:10,134 NOTICE NetworkManager:    ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-lo ...
>03:52:10,137 INFO NetworkManager: <info> monitoring kernel firmware directory '/lib/firmware'.
>03:52:10,157 INFO systemd: Started Network Manager.
>03:52:10,164 INFO systemd: Starting Network Manager Wait Online...
>03:52:10,165 INFO systemd: Starting Anaconda System Services.
>03:52:10,190 INFO systemd: Reached target Anaconda System Services.
>03:52:10,190 INFO systemd: Starting Anaconda...
>03:52:10,197 INFO NetworkManager: <info> WiFi enabled by radio killswitch; enabled by state file
>03:52:10,197 INFO NetworkManager: <info> WWAN enabled by radio killswitch; enabled by state file
>03:52:10,200 INFO NetworkManager: <info> WiMAX enabled by radio killswitch; enabled by state file
>03:52:10,201 INFO NetworkManager: <info> Networking is enabled by state file
>03:52:10,202 WARNING NetworkManager: <warn> failed to allocate link cache: (-10) Operation not supported
>03:52:10,202 INFO NetworkManager: <info> (eth0): carrier is OFF
>03:52:10,203 ERR NetworkManager: <error> [1350186730.199524] [nm-device-ethernet.c:454] update_permanent_hw_address(): (eth0): unable to read permanent MAC address (error 0)
>03:52:10,204 INFO NetworkManager: <info> (eth0): new Ethernet device (driver: 'virtio_net' ifindex: 2)
>03:52:10,204 INFO NetworkManager: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
>03:52:10,205 INFO NetworkManager: <info> (eth0): now managed
>03:52:10,207 INFO NetworkManager: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>03:52:10,207 INFO NetworkManager: <info> (eth0): bringing up device.
>03:52:10,208 INFO NetworkManager: <info> (eth0): carrier now ON (device state 20)
>03:52:10,208 INFO NetworkManager: <info> (eth0): preparing device.
>03:52:10,210 INFO NetworkManager: <info> (eth0): deactivating device (reason 'managed') [2]
>03:52:10,211 INFO NetworkManager: <info> Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:03.0/virtio0/net/eth0
>03:52:10,212 WARNING NetworkManager: <warn> /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring...
>03:52:10,212 WARNING NetworkManager: <warn> /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring...
>03:52:10,228 INFO NetworkManager: <info> (eth0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
>03:52:10,247 INFO NetworkManager: <info> Auto-activating connection 'Wired connection 1'.
>03:52:10,247 INFO NetworkManager: <info> Activation (eth0) starting connection 'Wired connection 1'
>03:52:10,247 INFO NetworkManager: <info> (eth0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>03:52:10,250 INFO NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
>03:52:10,252 INFO NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
>03:52:10,252 INFO NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
>03:52:10,253 INFO NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
>03:52:10,272 INFO NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
>03:52:10,273 INFO NetworkManager: <info> (eth0): device state change: prepare -> config (reason 'none') [40 50 0]
>03:52:10,280 INFO NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
>03:52:10,282 INFO NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
>03:52:10,283 INFO NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
>03:52:10,288 INFO NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
>03:52:10,289 INFO NetworkManager: <info> (eth0): device state change: config -> ip-config (reason 'none') [50 70 0]
>03:52:10,289 INFO NetworkManager: <info> Activation (eth0) Beginning DHCPv4 transaction (timeout in 45 seconds)
>03:52:10,289 INFO NetworkManager: <info> dhclient started with pid 580
>03:52:10,290 INFO NetworkManager: <info> Activation (eth0) Beginning IP6 addrconf.
>03:52:10,292 INFO NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
>03:52:10,310 INFO systemd: Started Anaconda.
>03:52:10,317 INFO systemd: Starting Anaconda Text Console...
>03:52:10,459 INFO systemd: Started Anaconda Text Console.
>03:52:10,735 INFO dhclient: Internet Systems Consortium DHCP Client 4.2.4-P2
>03:52:10,735 INFO dhclient: Copyright 2004-2012 Internet Systems Consortium.
>03:52:10,736 INFO dhclient: All rights reserved.
>03:52:10,736 INFO dhclient: For info, please visit https://www.isc.org/software/dhcp/
>03:52:10,736 INFO dhclient:
>03:52:10,753 INFO NetworkManager: <info> (eth0): DHCPv4 state changed nbi -> preinit
>03:52:10,754 INFO dhclient: Listening on LPF/eth0/52:54:00:dd:5f:b3
>03:52:10,755 INFO dhclient: Sending on   LPF/eth0/52:54:00:dd:5f:b3
>03:52:10,766 INFO dhclient: Sending on   Socket/fallback
>03:52:10,768 INFO dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 6 (xid=0x687a1c20)
>03:52:10,772 INFO dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67 (xid=0x687a1c20)
>03:52:10,772 INFO dhclient: DHCPOFFER from 192.168.0.1
>03:52:10,780 INFO dhclient: DHCPACK from 192.168.0.1 (xid=0x687a1c20)
>03:52:10,783 INFO NetworkManager: <info> (eth0): DHCPv4 state changed preinit -> bound
>03:52:10,783 INFO NetworkManager: <info>   address 192.168.0.118
>03:52:10,783 INFO NetworkManager: <info>   prefix 24 (255.255.255.0)
>03:52:10,784 INFO NetworkManager: <info>   gateway 192.168.0.1
>03:52:10,784 INFO NetworkManager: <info>   nameserver '192.168.0.1'
>03:52:10,784 INFO NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
>03:52:10,785 INFO NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) started...
>03:52:10,788 INFO dhclient: bound to 192.168.0.118 -- renewal in 35839 seconds.
>03:52:10,844 NOTICE kernel:[    8.370331] type=1400 audit(1350186730.795:11): avc:  denied  { read write } for  pid=598 comm="hostname" path="/dev/pts/1" dev="devpts" ino=4 scontext=system_u:system_r:hostname_t:s0 tcontext=system_u:object_r:devpts_t:s0 tclass=chr_file
>03:52:10,922 ERR systemd-udevd: ctx=0x7f2555caf9c0 path=/lib/modules/3.6.1-1.fc18.x86_64/kernel/sound/pci/hda/snd-hda-codec.ko error=No such file or directory
>03:52:10,922 ERR systemd-udevd: ctx=0x7f2555caf9c0 path=/lib/modules/3.6.1-1.fc18.x86_64/kernel/sound/pci/hda/snd-hda-codec.ko error=No such file or directory
>03:52:10,928 INFO systemd: Starting Agent daemon for Spice guests.
>03:52:10,928 INFO systemd: Reached target Agent daemon for Spice guests.
>03:52:10,959 INFO kernel:[    8.533196] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0
>03:52:10,988 ERR systemd-udevd: ctx=0x7f2555caf9c0 path=/lib/modules/3.6.1-1.fc18.x86_64/kernel/sound/core/snd-pcm.ko error=No such file or directory
>03:52:11,051 WARNING kernel:[    8.624045] microcode: AMD CPU family 0x6 not supported
>03:52:11,101 ERR systemd-udevd: ctx=0x7f2555caf9c0 path=/lib/modules/3.6.1-1.fc18.x86_64/kernel/arch/x86/kvm/kvm.ko error=No such file or directory
>03:52:11,790 INFO NetworkManager: <info> (eth0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
>03:52:11,790 INFO NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) complete.
>03:52:11,803 WARNING NetworkManager: <warn> /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring...
>03:52:11,803 INFO NetworkManager: <info> (eth0): device state change: secondaries -> activated (reason 'none') [90 100 0]
>03:52:11,804 INFO systemd: Started Network Manager Wait Online.
>03:52:11,805 INFO systemd: Starting Network.
>03:52:11,806 INFO systemd: Reached target Network.
>03:52:11,836 INFO systemd: Startup finished in 3s 251ms 660us (kernel) + 2s 61ms 320us (initrd) + 4s 63ms 844us (userspace) = 9s 376ms 824us.
>03:52:11,837 INFO NetworkManager: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
>03:52:11,840 INFO NetworkManager: <info> Activation (eth0) successful, device activated.
>03:52:11,840 NOTICE dbus: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
>03:52:11,841 INFO dbus-daemon: dbus[541]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
>03:52:11,851 INFO dbus-daemon: dbus[541]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>03:52:11,851 NOTICE dbus: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>03:52:12,016 INFO dbus-daemon: /etc/NetworkManager/dispatcher.d/04-iscsi: line 14: /sbin/chkconfig: No such file or directory
>03:52:13,453 NOTICE kernel:[   11.027828] type=1400 audit(1350186733.452:12): avc:  denied  { read write } for  pid=634 comm="auditd" path="/dev/mapper/control" dev="devtmpfs" ino=7334 scontext=system_u:system_r:auditd_t:s0 tcontext=system_u:object_r:lvm_control_t:s0 tclass=chr_file
>03:52:13,463 WARNING kernel:[   11.036129] auditd (635): /proc/635/oom_adj is deprecated, please use /proc/635/oom_score_adj instead.
>03:52:18,192 INFO systemd: Starting NTP client/server...
>03:52:18,254 INFO chrony-helper: tr: write error: Broken pipe
>03:52:18,255 INFO chrony-helper: tr: write error
>03:52:18,313 INFO chronyd: chronyd version 1.27-git1ca844 starting
>03:52:18,315 INFO chronyd: Linux kernel major=3 minor=6 patch=1
>03:52:18,315 INFO chronyd: hz=100 shift_hz=7 freq_scale=1.00000000 nominal_tick=10000 slew_delta_tick=833 max_tick_bias=1000 shift_pll=2
>03:52:18,326 INFO systemd: Started NTP client/server.
>03:52:19,131 NOTICE NetworkManager:    ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-eth0 ...
>03:52:19,131 WARNING NetworkManager: <warn> failed to allocate link cache: (-10) Operation not supported
>03:52:19,131 NOTICE NetworkManager:    ifcfg-rh:     read connection 'System eth0'
>03:52:19,131 WARNING NetworkManager: <warn> failed to allocate link cache: (-10) Operation not supported
>03:52:30,431 INFO NetworkManager: <info> (eth0): IP6 addrconf timed out or failed.
>03:52:30,432 INFO NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
>03:52:30,432 INFO NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) started...
>03:52:30,432 INFO NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
>03:52:37,466 INFO kernel:[   35.565051] Btrfs loaded
>03:52:37,551 INFO kernel:[   35.650705] SGI XFS with ACLs, security attributes, large block/inode numbers, no debug enabled

Actions: View

Attachments on bug 866114: 626767 | 626768 | 626769 | 626770 | 626771 | 626772 | 626773 | 626774 | 626775 | 626776 | 626777 | 626778 | 626779 | 626780