Attachment 937479 Details for Bug 1141676 – program log

program log

program.log (text/plain), 54.39 KB, created by Pushpendra Chavan on 2014-09-15 08:26:16 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Pushpendra Chavan

Created: 2014-09-15 08:26:16 UTC

Size: 54.39 KB

patch

obsolete

>06:54:03,139 INFO program: Running... udevadm trigger --action=change --subsystem-match=block
>06:54:03,150 DEBUG program: Return code: 0
>06:54:03,150 INFO program: Running... udevadm settle --timeout=300
>06:54:03,220 DEBUG program: Return code: 0
>06:54:03,221 INFO program: Running... modprobe fcoe
>06:54:03,266 DEBUG program: Return code: 0
>06:54:03,266 INFO program: Running... /usr/libexec/fcoe/fcoe_edd.sh -i
>06:54:03,290 INFO program: No FCoE boot disk information is found in EDD!
>06:54:03,290 DEBUG program: Return code: 1
>06:54:03,292 INFO program: Running... udevadm settle --timeout=300
>06:54:03,302 DEBUG program: Return code: 0
>06:54:03,305 INFO program: Running... udevadm settle --timeout=300
>06:54:03,314 DEBUG program: Return code: 0
>06:54:03,319 INFO program: Running... udevadm settle --timeout=300
>06:54:03,328 DEBUG program: Return code: 0
>06:54:03,331 INFO program: Running... udevadm settle --timeout=300
>06:54:03,341 DEBUG program: Return code: 0
>06:54:03,351 INFO program: Running... loadkeys us
>06:54:03,384 INFO program: Loading /lib/kbd/keymaps/i386/qwerty/us.map.gz
>06:54:03,384 DEBUG program: Return code: 0
>06:54:04,594 INFO program: Running... metacity --display :1 --sm-disable
>06:54:05,472 INFO program: Running... modprobe xfs
>06:54:05,538 DEBUG program: Return code: 0
>06:54:06,063 INFO program: Running... udevadm settle --timeout=300
>06:54:06,108 DEBUG program: Return code: 0
>06:54:06,108 INFO program: Running... mpathconf --user_friendly_names y --with_multipathd y
>06:54:06,243 DEBUG program: Return code: 0
>06:54:06,244 INFO program: Running... hwclock --hctosys --utc
>06:54:07,005 DEBUG program: Return code: 0
>06:54:07,006 INFO program: Running... systemctl start chronyd.service
>06:54:07,080 DEBUG program: Return code: 0
>06:54:07,082 INFO program: Running... udevadm settle --timeout=300
>06:54:07,140 DEBUG program: Return code: 0
>06:54:07,173 INFO program: Running... udevadm settle --timeout=300
>06:54:07,224 DEBUG program: Return code: 0
>06:54:07,321 INFO program: Running... multipath -c /dev/vda
>06:54:07,333 DEBUG program: Return code: 1
>06:54:07,499 INFO program: Running... xfs_db -c "sb 0" -c "p dblocks" -c "p blocksize" /dev/vda1
>06:54:07,556 INFO program: command "sb 0" not found
>06:54:07,556 INFO program: command "p dblocks" not found
>06:54:07,556 INFO program: command "p blocksize" not found
>06:54:07,556 DEBUG program: Return code: 0
>06:54:07,644 INFO program: Running... lvm pvs --unit=k --nosuffix --nameprefixes --rows --unquoted --noheadings -opv_uuid,pe_start,vg_name,vg_uuid,vg_size,vg_free,vg_extent_size,vg_extent_count,vg_free_count,pv_count --config global {locking_type=4}  /dev/vda2
>06:54:07,714 INFO program:   LVM2_PV_UUID=0wR2m0-JGzr-j7jF-LTHr-hpL0-ByF1-18NZxL
>06:54:07,714 INFO program:   LVM2_PE_START=1024.00
>06:54:07,714 INFO program:   LVM2_VG_NAME=rhel_newrhel7
>06:54:07,715 INFO program:   LVM2_VG_UUID=rPBCfT-uAxo-QMY8-3uba-EjX6-Tn3U-k0g9Lp
>06:54:07,715 INFO program:   LVM2_VG_SIZE=20455424.00
>06:54:07,715 INFO program:   LVM2_VG_FREE=0
>06:54:07,715 INFO program:   LVM2_VG_EXTENT_SIZE=4096.00
>06:54:07,715 INFO program:   LVM2_VG_EXTENT_COUNT=4994
>06:54:07,715 INFO program:   LVM2_VG_FREE_COUNT=0
>06:54:07,715 INFO program:   LVM2_PV_COUNT=1
>06:54:07,716 DEBUG program: Return code: 0
>06:54:07,751 INFO program: Running... lvm lvs -a --unit k --nosuffix --nameprefixes --rows --unquoted --noheadings -olv_name,lv_uuid,lv_size,lv_attr,segtype --config global {locking_type=4}  rhel_newrhel7
>06:54:07,789 INFO program:   LVM2_LV_NAME=root LVM2_LV_NAME=swap
>06:54:07,789 INFO program:   LVM2_LV_UUID=0og4iX-wV3s-8DvG-pg1s-aqX0-euCe-7f06Hi LVM2_LV_UUID=RfMRxd-8Q91-lpoS-173U-9xCM-vGNn-0VvJEV
>06:54:07,789 INFO program:   LVM2_LV_SIZE=18358272.00 LVM2_LV_SIZE=2097152.00
>06:54:07,789 INFO program:   LVM2_LV_ATTR=-wi-a----- LVM2_LV_ATTR=-wi-a-----
>06:54:07,789 INFO program:   LVM2_SEGTYPE=linear LVM2_SEGTYPE=linear
>06:54:07,789 DEBUG program: Return code: 0
>06:54:08,111 INFO program: Running... xfs_db -c "sb 0" -c "p dblocks" -c "p blocksize" /dev/mapper/rhel_newrhel7-root
>06:54:08,125 INFO program: command "sb 0" not found
>06:54:08,126 INFO program: command "p dblocks" not found
>06:54:08,126 INFO program: command "p blocksize" not found
>06:54:08,126 DEBUG program: Return code: 0
>06:54:08,278 INFO program: Running... dumpe2fs -h /dev/loop1
>06:54:08,356 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>06:54:08,356 INFO program: Filesystem volume name:   Anaconda
>06:54:08,356 INFO program: Last mounted on:          /var/tmp/lorax.imgutils.5h5P_5
>06:54:08,357 INFO program: Filesystem UUID:          8bacf3e8-b3f2-47c2-86ec-0454ad081725
>06:54:08,357 INFO program: Filesystem magic number:  0xEF53
>06:54:08,357 INFO program: Filesystem revision #:    1 (dynamic)
>06:54:08,357 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype extent 64bit flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>06:54:08,357 INFO program: Filesystem flags:         signed_directory_hash 
>06:54:08,357 INFO program: Default mount options:    user_xattr acl
>06:54:08,357 INFO program: Filesystem state:         clean
>06:54:08,358 INFO program: Errors behavior:          Continue
>06:54:08,358 INFO program: Filesystem OS type:       Linux
>06:54:08,358 INFO program: Inode count:              131072
>06:54:08,359 INFO program: Block count:              2097152
>06:54:08,359 INFO program: Reserved block count:     0
>06:54:08,359 INFO program: Free blocks:              1053309
>06:54:08,359 INFO program: Free inodes:              101797
>06:54:08,359 INFO program: First block:              1
>06:54:08,359 INFO program: Block size:               1024
>06:54:08,360 INFO program: Fragment size:            1024
>06:54:08,360 INFO program: Group descriptor size:    64
>06:54:08,361 INFO program: Reserved GDT blocks:      256
>06:54:08,361 INFO program: Blocks per group:         8192
>06:54:08,361 INFO program: Fragments per group:      8192
>06:54:08,361 INFO program: Inodes per group:         512
>06:54:08,362 INFO program: Inode blocks per group:   128
>06:54:08,362 INFO program: Flex block group size:    16
>06:54:08,362 INFO program: Filesystem created:       Wed May  7 07:36:56 2014
>06:54:08,362 INFO program: Last mount time:          Wed May  7 07:37:14 2014
>06:54:08,363 INFO program: Last write time:          Wed May  7 07:37:20 2014
>06:54:08,363 INFO program: Mount count:              2
>06:54:08,363 INFO program: Maximum mount count:      -1
>06:54:08,363 INFO program: Last checked:             Wed May  7 07:36:56 2014
>06:54:08,363 INFO program: Check interval:           0 (<none>)
>06:54:08,363 INFO program: Lifetime writes:          32 MB
>06:54:08,364 INFO program: Reserved blocks uid:      0 (user root)
>06:54:08,364 INFO program: Reserved blocks gid:      0 (group root)
>06:54:08,364 INFO program: First inode:              11
>06:54:08,364 INFO program: Inode size:	          256
>06:54:08,364 INFO program: Required extra isize:     28
>06:54:08,364 INFO program: Desired extra isize:      28
>06:54:08,365 INFO program: Journal inode:            8
>06:54:08,365 INFO program: Default directory hash:   half_md4
>06:54:08,365 INFO program: Directory Hash Seed:      164636ca-ef00-45fb-b431-a639a7074ae1
>06:54:08,365 INFO program: Journal backup:           inode blocks
>06:54:08,365 INFO program: Journal features:         (none)
>06:54:08,365 INFO program: Journal size:             32M
>06:54:08,366 INFO program: Journal length:           32768
>06:54:08,366 INFO program: Journal sequence:         0x0000001f
>06:54:08,366 INFO program: Journal start:            0
>06:54:08,366 INFO program: 
>06:54:08,366 DEBUG program: Return code: 0
>06:54:08,366 INFO program: Running... resize2fs -P /dev/loop1
>06:54:08,387 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>06:54:08,388 INFO program: resize2fs: Device or resource busy while trying to open /dev/loop1
>06:54:08,388 INFO program: Couldn't find valid filesystem superblock.
>06:54:08,388 DEBUG program: Return code: 1
>06:54:08,455 INFO program: Running... dumpe2fs -h /dev/mapper/live-rw
>06:54:08,468 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>06:54:08,468 INFO program: Filesystem volume name:   Anaconda
>06:54:08,468 INFO program: Last mounted on:          /
>06:54:08,468 INFO program: Filesystem UUID:          8bacf3e8-b3f2-47c2-86ec-0454ad081725
>06:54:08,468 INFO program: Filesystem magic number:  0xEF53
>06:54:08,468 INFO program: Filesystem revision #:    1 (dynamic)
>06:54:08,469 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype needs_recovery extent 64bit flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>06:54:08,469 INFO program: Filesystem flags:         signed_directory_hash 
>06:54:08,469 INFO program: Default mount options:    user_xattr acl
>06:54:08,469 INFO program: Filesystem state:         clean
>06:54:08,469 INFO program: Errors behavior:          Continue
>06:54:08,469 INFO program: Filesystem OS type:       Linux
>06:54:08,469 INFO program: Inode count:              131072
>06:54:08,470 INFO program: Block count:              2097152
>06:54:08,470 INFO program: Reserved block count:     0
>06:54:08,470 INFO program: Free blocks:              1053309
>06:54:08,470 INFO program: Free inodes:              101797
>06:54:08,470 INFO program: First block:              1
>06:54:08,470 INFO program: Block size:               1024
>06:54:08,471 INFO program: Fragment size:            1024
>06:54:08,471 INFO program: Group descriptor size:    64
>06:54:08,471 INFO program: Reserved GDT blocks:      256
>06:54:08,471 INFO program: Blocks per group:         8192
>06:54:08,471 INFO program: Fragments per group:      8192
>06:54:08,471 INFO program: Inodes per group:         512
>06:54:08,471 INFO program: Inode blocks per group:   128
>06:54:08,472 INFO program: Flex block group size:    16
>06:54:08,472 INFO program: Filesystem created:       Wed May  7 07:36:56 2014
>06:54:08,472 INFO program: Last mount time:          Mon Sep 15 06:53:55 2014
>06:54:08,472 INFO program: Last write time:          Mon Sep 15 06:53:55 2014
>06:54:08,472 INFO program: Mount count:              3
>06:54:08,472 INFO program: Maximum mount count:      -1
>06:54:08,473 INFO program: Last checked:             Wed May  7 07:36:56 2014
>06:54:08,473 INFO program: Check interval:           0 (<none>)
>06:54:08,473 INFO program: Lifetime writes:          32 MB
>06:54:08,473 INFO program: Reserved blocks uid:      0 (user root)
>06:54:08,473 INFO program: Reserved blocks gid:      0 (group root)
>06:54:08,473 INFO program: First inode:              11
>06:54:08,473 INFO program: Inode size:	          256
>06:54:08,474 INFO program: Required extra isize:     28
>06:54:08,474 INFO program: Desired extra isize:      28
>06:54:08,474 INFO program: Journal inode:            8
>06:54:08,475 INFO program: Default directory hash:   half_md4
>06:54:08,475 INFO program: Directory Hash Seed:      164636ca-ef00-45fb-b431-a639a7074ae1
>06:54:08,475 INFO program: Journal backup:           inode blocks
>06:54:08,475 INFO program: Journal features:         journal_64bit
>06:54:08,477 INFO program: Journal size:             32M
>06:54:08,477 INFO program: Journal length:           32768
>06:54:08,477 INFO program: Journal sequence:         0x00000020
>06:54:08,477 INFO program: Journal start:            1
>06:54:08,477 INFO program: 
>06:54:08,477 DEBUG program: Return code: 0
>06:54:08,478 INFO program: Running... resize2fs -P /dev/mapper/live-rw
>06:54:08,491 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>06:54:08,492 INFO program: Estimated minimum size of the filesystem: 1029961
>06:54:08,492 DEBUG program: Return code: 0
>06:54:08,785 INFO program: Running... dumpe2fs -h /dev/mapper/live-base
>06:54:08,794 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>06:54:08,795 INFO program: Filesystem volume name:   Anaconda
>06:54:08,795 INFO program: Last mounted on:          /var/tmp/lorax.imgutils.5h5P_5
>06:54:08,795 INFO program: Filesystem UUID:          8bacf3e8-b3f2-47c2-86ec-0454ad081725
>06:54:08,795 INFO program: Filesystem magic number:  0xEF53
>06:54:08,795 INFO program: Filesystem revision #:    1 (dynamic)
>06:54:08,795 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype extent 64bit flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>06:54:08,795 INFO program: Filesystem flags:         signed_directory_hash 
>06:54:08,795 INFO program: Default mount options:    user_xattr acl
>06:54:08,795 INFO program: Filesystem state:         clean
>06:54:08,795 INFO program: Errors behavior:          Continue
>06:54:08,796 INFO program: Filesystem OS type:       Linux
>06:54:08,796 INFO program: Inode count:              131072
>06:54:08,796 INFO program: Block count:              2097152
>06:54:08,796 INFO program: Reserved block count:     0
>06:54:08,796 INFO program: Free blocks:              1053309
>06:54:08,797 INFO program: Free inodes:              101797
>06:54:08,797 INFO program: First block:              1
>06:54:08,797 INFO program: Block size:               1024
>06:54:08,797 INFO program: Fragment size:            1024
>06:54:08,797 INFO program: Group descriptor size:    64
>06:54:08,797 INFO program: Reserved GDT blocks:      256
>06:54:08,798 INFO program: Blocks per group:         8192
>06:54:08,798 INFO program: Fragments per group:      8192
>06:54:08,798 INFO program: Inodes per group:         512
>06:54:08,798 INFO program: Inode blocks per group:   128
>06:54:08,798 INFO program: Flex block group size:    16
>06:54:08,798 INFO program: Filesystem created:       Wed May  7 07:36:56 2014
>06:54:08,799 INFO program: Last mount time:          Wed May  7 07:37:14 2014
>06:54:08,799 INFO program: Last write time:          Wed May  7 07:37:20 2014
>06:54:08,799 INFO program: Mount count:              2
>06:54:08,799 INFO program: Maximum mount count:      -1
>06:54:08,799 INFO program: Last checked:             Wed May  7 07:36:56 2014
>06:54:08,800 INFO program: Check interval:           0 (<none>)
>06:54:08,800 INFO program: Lifetime writes:          32 MB
>06:54:08,801 INFO program: Reserved blocks uid:      0 (user root)
>06:54:08,801 INFO program: Reserved blocks gid:      0 (group root)
>06:54:08,801 INFO program: First inode:              11
>06:54:08,801 INFO program: Inode size:	          256
>06:54:08,801 INFO program: Required extra isize:     28
>06:54:08,801 INFO program: Desired extra isize:      28
>06:54:08,803 INFO program: Journal inode:            8
>06:54:08,803 INFO program: Default directory hash:   half_md4
>06:54:08,804 INFO program: Directory Hash Seed:      164636ca-ef00-45fb-b431-a639a7074ae1
>06:54:08,804 INFO program: Journal backup:           inode blocks
>06:54:08,804 INFO program: Journal features:         (none)
>06:54:08,804 INFO program: Journal size:             32M
>06:54:08,804 INFO program: Journal length:           32768
>06:54:08,804 INFO program: Journal sequence:         0x0000001f
>06:54:08,805 INFO program: Journal start:            0
>06:54:08,805 INFO program: 
>06:54:08,805 DEBUG program: Return code: 0
>06:54:08,805 INFO program: Running... resize2fs -P /dev/mapper/live-base
>06:54:08,819 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>06:54:08,819 INFO program: resize2fs: Operation not permitted while trying to open /dev/mapper/live-base
>06:54:08,819 INFO program: Couldn't find valid filesystem superblock.
>06:54:08,819 DEBUG program: Return code: 1
>06:54:08,878 INFO program: Running... udevadm settle --timeout=300
>06:54:08,903 DEBUG program: Return code: 0
>06:54:08,907 INFO program: Running... udevadm settle --timeout=300
>06:54:08,928 DEBUG program: Return code: 0
>06:54:08,932 INFO program: Running... udevadm settle --timeout=300
>06:54:08,954 DEBUG program: Return code: 0
>06:54:08,956 INFO program: Running... udevadm settle --timeout=300
>06:54:08,976 DEBUG program: Return code: 0
>06:54:08,978 INFO program: Running... lvm lvchange -a n rhel_newrhel7/root
>06:54:09,034 DEBUG program: Return code: 0
>06:54:09,042 INFO program: Running... udevadm settle --timeout=300
>06:54:09,064 DEBUG program: Return code: 0
>06:54:09,065 INFO program: Running... lvm vgchange -a n rhel_newrhel7
>06:54:09,116 INFO program:   0 logical volume(s) in volume group "rhel_newrhel7" now active
>06:54:09,117 DEBUG program: Return code: 0
>06:54:09,122 INFO program: Running... udevadm settle --timeout=300
>06:54:09,146 DEBUG program: Return code: 0
>06:54:09,152 INFO program: Running... udevadm settle --timeout=300
>06:54:09,173 DEBUG program: Return code: 0
>06:54:09,181 INFO program: Running... mount -t xfs -o defaults,ro /dev/vda1 /mnt/sysimage
>06:54:09,215 DEBUG program: Return code: 0
>06:54:09,219 INFO program: Running... udevadm settle --timeout=300
>06:54:09,241 DEBUG program: Return code: 0
>06:54:09,241 INFO program: Running... umount /mnt/sysimage
>06:54:09,316 DEBUG program: Return code: 0
>06:54:09,317 INFO program: Running... udevadm settle --timeout=300
>06:54:09,337 DEBUG program: Return code: 0
>06:54:09,341 INFO program: Running... udevadm settle --timeout=300
>06:54:09,362 DEBUG program: Return code: 0
>06:54:09,366 INFO program: Running... lvm lvchange -a y rhel_newrhel7/root
>06:54:09,439 DEBUG program: Return code: 0
>06:54:09,440 INFO program: Running... udevadm settle --timeout=300
>06:54:09,463 DEBUG program: Return code: 0
>06:54:09,464 INFO program: Running... mount -t xfs -o defaults,ro /dev/mapper/rhel_newrhel7-root /mnt/sysimage
>06:54:09,564 DEBUG program: Return code: 0
>06:54:09,564 INFO program: Running... arch
>06:54:09,578 ERR program: Error running arch: No such file or directory
>06:54:09,591 INFO program: Running... udevadm settle --timeout=300
>06:54:09,614 DEBUG program: Return code: 0
>06:54:09,614 INFO program: Running... umount /mnt/sysimage
>06:54:09,740 DEBUG program: Return code: 0
>06:54:09,740 INFO program: Running... udevadm settle --timeout=300
>06:54:09,760 DEBUG program: Return code: 0
>06:54:09,762 INFO program: Running... lvm lvchange -a n rhel_newrhel7/root
>06:54:09,810 DEBUG program: Return code: 0
>06:54:10,560 INFO program: Running... systemctl status chronyd.service
>06:54:10,575 INFO program: chronyd.service - NTP client/server
>06:54:10,576 INFO program: Loaded: loaded (/usr/lib/systemd/system/chronyd.service; enabled)
>06:54:10,576 INFO program: Active: active (running) since Mon 2014-09-15 06:54:07 UTC; 3s ago
>06:54:10,576 INFO program: Process: 1461 ExecStartPost=/usr/libexec/chrony-helper add-dhclient-servers (code=exited, status=0/SUCCESS)
>06:54:10,576 INFO program: Process: 1458 ExecStart=/usr/sbin/chronyd -u chrony $OPTIONS (code=exited, status=0/SUCCESS)
>06:54:10,576 INFO program: Main PID: 1460 (chronyd)
>06:54:10,576 INFO program: CGroup: /system.slice/chronyd.service
>06:54:10,576 INFO program: `-1460 /usr/sbin/chronyd -u chrony
>06:54:10,576 INFO program: 
>06:54:10,576 INFO program: Sep 15 06:54:07 newrhel7.Private_Network systemd[1]: Started NTP client/server.
>06:54:10,576 DEBUG program: Return code: 0
>06:54:11,421 INFO program: Running... udevadm settle --timeout=300
>06:54:11,461 DEBUG program: Return code: 0
>06:54:11,463 INFO program: Running... udevadm settle --timeout=300
>06:54:11,500 DEBUG program: Return code: 0
>06:54:11,530 INFO program: Running... udevadm settle --timeout=300
>06:54:11,573 DEBUG program: Return code: 0
>06:54:11,574 INFO program: Running... mpathconf --user_friendly_names y --with_multipathd y
>06:54:11,697 DEBUG program: Return code: 0
>06:54:11,698 INFO program: Running... udevadm settle --timeout=300
>06:54:11,727 DEBUG program: Return code: 0
>06:54:11,730 INFO program: Running... udevadm settle --timeout=300
>06:54:11,762 DEBUG program: Return code: 0
>06:54:11,811 INFO program: Running... multipath -c /dev/vda
>06:54:11,825 DEBUG program: Return code: 1
>06:54:11,896 INFO program: Running... xfs_db -c "sb 0" -c "p dblocks" -c "p blocksize" /dev/vda1
>06:54:11,916 INFO program: command "sb 0" not found
>06:54:11,916 INFO program: command "p dblocks" not found
>06:54:11,916 INFO program: command "p blocksize" not found
>06:54:11,916 DEBUG program: Return code: 0
>06:54:11,968 INFO program: Running... lvm pvs --unit=k --nosuffix --nameprefixes --rows --unquoted --noheadings -opv_uuid,pe_start,vg_name,vg_uuid,vg_size,vg_free,vg_extent_size,vg_extent_count,vg_free_count,pv_count --config global {locking_type=4}  /dev/vda2
>06:54:11,995 INFO program:   LVM2_PV_UUID=0wR2m0-JGzr-j7jF-LTHr-hpL0-ByF1-18NZxL
>06:54:11,996 INFO program:   LVM2_PE_START=1024.00
>06:54:11,996 INFO program:   LVM2_VG_NAME=rhel_newrhel7
>06:54:11,996 INFO program:   LVM2_VG_UUID=rPBCfT-uAxo-QMY8-3uba-EjX6-Tn3U-k0g9Lp
>06:54:11,996 INFO program:   LVM2_VG_SIZE=20455424.00
>06:54:11,996 INFO program:   LVM2_VG_FREE=0
>06:54:11,996 INFO program:   LVM2_VG_EXTENT_SIZE=4096.00
>06:54:11,996 INFO program:   LVM2_VG_EXTENT_COUNT=4994
>06:54:11,996 INFO program:   LVM2_VG_FREE_COUNT=0
>06:54:11,997 INFO program:   LVM2_PV_COUNT=1
>06:54:11,997 DEBUG program: Return code: 0
>06:54:12,013 INFO program: Running... lvm lvs -a --unit k --nosuffix --nameprefixes --rows --unquoted --noheadings -olv_name,lv_uuid,lv_size,lv_attr,segtype --config global {locking_type=4}  rhel_newrhel7
>06:54:12,033 INFO program:   LVM2_LV_NAME=root LVM2_LV_NAME=swap
>06:54:12,033 INFO program:   LVM2_LV_UUID=0og4iX-wV3s-8DvG-pg1s-aqX0-euCe-7f06Hi LVM2_LV_UUID=RfMRxd-8Q91-lpoS-173U-9xCM-vGNn-0VvJEV
>06:54:12,033 INFO program:   LVM2_LV_SIZE=18358272.00 LVM2_LV_SIZE=2097152.00
>06:54:12,033 INFO program:   LVM2_LV_ATTR=-wi------- LVM2_LV_ATTR=-wi-------
>06:54:12,033 INFO program:   LVM2_SEGTYPE=linear LVM2_SEGTYPE=linear
>06:54:12,033 DEBUG program: Return code: 0
>06:54:12,116 INFO program: Running... lvm lvchange -a y rhel_newrhel7/root
>06:54:12,236 DEBUG program: Return code: 0
>06:54:12,236 INFO program: Running... udevadm settle --timeout=300
>06:54:12,278 DEBUG program: Return code: 0
>06:54:12,320 INFO program: Running... xfs_db -c "sb 0" -c "p dblocks" -c "p blocksize" /dev/mapper/rhel_newrhel7-root
>06:54:12,349 INFO program: command "sb 0" not found
>06:54:12,349 INFO program: command "p dblocks" not found
>06:54:12,349 INFO program: command "p blocksize" not found
>06:54:12,349 DEBUG program: Return code: 0
>06:54:12,422 INFO program: Running... lvm lvchange -a y rhel_newrhel7/swap
>06:54:12,472 DEBUG program: Return code: 0
>06:54:12,472 INFO program: Running... udevadm settle --timeout=300
>06:54:12,485 DEBUG program: Return code: 0
>06:54:12,486 INFO program: Running... modprobe btrfs
>06:54:12,594 DEBUG program: Return code: 0
>06:54:12,623 INFO program: Running... dumpe2fs -h /dev/loop1
>06:54:12,637 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>06:54:12,637 INFO program: Filesystem volume name:   Anaconda
>06:54:12,637 INFO program: Last mounted on:          /var/tmp/lorax.imgutils.5h5P_5
>06:54:12,637 INFO program: Filesystem UUID:          8bacf3e8-b3f2-47c2-86ec-0454ad081725
>06:54:12,637 INFO program: Filesystem magic number:  0xEF53
>06:54:12,638 INFO program: Filesystem revision #:    1 (dynamic)
>06:54:12,638 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype extent 64bit flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>06:54:12,638 INFO program: Filesystem flags:         signed_directory_hash 
>06:54:12,638 INFO program: Default mount options:    user_xattr acl
>06:54:12,639 INFO program: Filesystem state:         clean
>06:54:12,639 INFO program: Errors behavior:          Continue
>06:54:12,639 INFO program: Filesystem OS type:       Linux
>06:54:12,639 INFO program: Inode count:              131072
>06:54:12,639 INFO program: Block count:              2097152
>06:54:12,640 INFO program: Reserved block count:     0
>06:54:12,640 INFO program: Free blocks:              1053309
>06:54:12,640 INFO program: Free inodes:              101797
>06:54:12,640 INFO program: First block:              1
>06:54:12,640 INFO program: Block size:               1024
>06:54:12,640 INFO program: Fragment size:            1024
>06:54:12,641 INFO program: Group descriptor size:    64
>06:54:12,641 INFO program: Reserved GDT blocks:      256
>06:54:12,641 INFO program: Blocks per group:         8192
>06:54:12,641 INFO program: Fragments per group:      8192
>06:54:12,641 INFO program: Inodes per group:         512
>06:54:12,641 INFO program: Inode blocks per group:   128
>06:54:12,642 INFO program: Flex block group size:    16
>06:54:12,642 INFO program: Filesystem created:       Wed May  7 07:36:56 2014
>06:54:12,642 INFO program: Last mount time:          Wed May  7 07:37:14 2014
>06:54:12,642 INFO program: Last write time:          Wed May  7 07:37:20 2014
>06:54:12,642 INFO program: Mount count:              2
>06:54:12,642 INFO program: Maximum mount count:      -1
>06:54:12,643 INFO program: Last checked:             Wed May  7 07:36:56 2014
>06:54:12,643 INFO program: Check interval:           0 (<none>)
>06:54:12,643 INFO program: Lifetime writes:          32 MB
>06:54:12,643 INFO program: Reserved blocks uid:      0 (user root)
>06:54:12,643 INFO program: Reserved blocks gid:      0 (group root)
>06:54:12,643 INFO program: First inode:              11
>06:54:12,643 INFO program: Inode size:	          256
>06:54:12,644 INFO program: Required extra isize:     28
>06:54:12,644 INFO program: Desired extra isize:      28
>06:54:12,644 INFO program: Journal inode:            8
>06:54:12,644 INFO program: Default directory hash:   half_md4
>06:54:12,644 INFO program: Directory Hash Seed:      164636ca-ef00-45fb-b431-a639a7074ae1
>06:54:12,644 INFO program: Journal backup:           inode blocks
>06:54:12,645 INFO program: Journal features:         (none)
>06:54:12,645 INFO program: Journal size:             32M
>06:54:12,645 INFO program: Journal length:           32768
>06:54:12,645 INFO program: Journal sequence:         0x0000001f
>06:54:12,645 INFO program: Journal start:            0
>06:54:12,645 INFO program: 
>06:54:12,646 DEBUG program: Return code: 0
>06:54:12,646 INFO program: Running... modprobe vfat
>06:54:12,663 DEBUG program: Return code: 0
>06:54:12,664 INFO program: Running... resize2fs -P /dev/loop1
>06:54:12,678 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>06:54:12,678 INFO program: resize2fs: Device or resource busy while trying to open /dev/loop1
>06:54:12,678 INFO program: Couldn't find valid filesystem superblock.
>06:54:12,678 DEBUG program: Return code: 1
>06:54:12,720 INFO program: Running... dumpe2fs -h /dev/mapper/live-rw
>06:54:12,728 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>06:54:12,728 INFO program: Filesystem volume name:   Anaconda
>06:54:12,728 INFO program: Last mounted on:          /
>06:54:12,728 INFO program: Filesystem UUID:          8bacf3e8-b3f2-47c2-86ec-0454ad081725
>06:54:12,728 INFO program: Filesystem magic number:  0xEF53
>06:54:12,728 INFO program: Filesystem revision #:    1 (dynamic)
>06:54:12,728 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype needs_recovery extent 64bit flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>06:54:12,728 INFO program: Filesystem flags:         signed_directory_hash 
>06:54:12,728 INFO program: Default mount options:    user_xattr acl
>06:54:12,729 INFO program: Filesystem state:         clean
>06:54:12,729 INFO program: Errors behavior:          Continue
>06:54:12,729 INFO program: Filesystem OS type:       Linux
>06:54:12,729 INFO program: Inode count:              131072
>06:54:12,729 INFO program: Block count:              2097152
>06:54:12,730 INFO program: Reserved block count:     0
>06:54:12,730 INFO program: Free blocks:              1053309
>06:54:12,730 INFO program: Free inodes:              101797
>06:54:12,730 INFO program: First block:              1
>06:54:12,730 INFO program: Block size:               1024
>06:54:12,730 INFO program: Fragment size:            1024
>06:54:12,731 INFO program: Group descriptor size:    64
>06:54:12,731 INFO program: Reserved GDT blocks:      256
>06:54:12,731 INFO program: Blocks per group:         8192
>06:54:12,731 INFO program: Fragments per group:      8192
>06:54:12,731 INFO program: Inodes per group:         512
>06:54:12,732 INFO program: Inode blocks per group:   128
>06:54:12,732 INFO program: Flex block group size:    16
>06:54:12,732 INFO program: Filesystem created:       Wed May  7 07:36:56 2014
>06:54:12,732 INFO program: Last mount time:          Mon Sep 15 06:53:55 2014
>06:54:12,733 INFO program: Last write time:          Mon Sep 15 06:53:55 2014
>06:54:12,733 INFO program: Mount count:              3
>06:54:12,733 INFO program: Maximum mount count:      -1
>06:54:12,733 INFO program: Last checked:             Wed May  7 07:36:56 2014
>06:54:12,733 INFO program: Check interval:           0 (<none>)
>06:54:12,733 INFO program: Lifetime writes:          32 MB
>06:54:12,734 INFO program: Reserved blocks uid:      0 (user root)
>06:54:12,734 INFO program: Reserved blocks gid:      0 (group root)
>06:54:12,734 INFO program: First inode:              11
>06:54:12,734 INFO program: Inode size:	          256
>06:54:12,734 INFO program: Required extra isize:     28
>06:54:12,734 INFO program: Desired extra isize:      28
>06:54:12,734 INFO program: Journal inode:            8
>06:54:12,735 INFO program: Default directory hash:   half_md4
>06:54:12,735 INFO program: Directory Hash Seed:      164636ca-ef00-45fb-b431-a639a7074ae1
>06:54:12,735 INFO program: Journal backup:           inode blocks
>06:54:12,735 INFO program: Journal features:         journal_64bit
>06:54:12,735 INFO program: Journal size:             32M
>06:54:12,735 INFO program: Journal length:           32768
>06:54:12,736 INFO program: Journal sequence:         0x00000020
>06:54:12,736 INFO program: Journal start:            1
>06:54:12,736 INFO program: 
>06:54:12,736 DEBUG program: Return code: 0
>06:54:12,736 INFO program: Running... resize2fs -P /dev/mapper/live-rw
>06:54:12,744 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>06:54:12,744 INFO program: Estimated minimum size of the filesystem: 1029961
>06:54:12,745 DEBUG program: Return code: 0
>06:54:12,774 INFO program: Running... dumpe2fs -h /dev/mapper/live-base
>06:54:12,781 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>06:54:12,781 INFO program: Filesystem volume name:   Anaconda
>06:54:12,781 INFO program: Last mounted on:          /var/tmp/lorax.imgutils.5h5P_5
>06:54:12,781 INFO program: Filesystem UUID:          8bacf3e8-b3f2-47c2-86ec-0454ad081725
>06:54:12,781 INFO program: Filesystem magic number:  0xEF53
>06:54:12,781 INFO program: Filesystem revision #:    1 (dynamic)
>06:54:12,781 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype extent 64bit flex_bg sparse_super huge_file uninit_bg dir_nlink extra_isize
>06:54:12,781 INFO program: Filesystem flags:         signed_directory_hash 
>06:54:12,782 INFO program: Default mount options:    user_xattr acl
>06:54:12,782 INFO program: Filesystem state:         clean
>06:54:12,782 INFO program: Errors behavior:          Continue
>06:54:12,782 INFO program: Filesystem OS type:       Linux
>06:54:12,782 INFO program: Inode count:              131072
>06:54:12,782 INFO program: Block count:              2097152
>06:54:12,783 INFO program: Reserved block count:     0
>06:54:12,783 INFO program: Free blocks:              1053309
>06:54:12,783 INFO program: Free inodes:              101797
>06:54:12,783 INFO program: First block:              1
>06:54:12,783 INFO program: Block size:               1024
>06:54:12,783 INFO program: Fragment size:            1024
>06:54:12,784 INFO program: Group descriptor size:    64
>06:54:12,784 INFO program: Reserved GDT blocks:      256
>06:54:12,784 INFO program: Blocks per group:         8192
>06:54:12,784 INFO program: Fragments per group:      8192
>06:54:12,784 INFO program: Inodes per group:         512
>06:54:12,784 INFO program: Inode blocks per group:   128
>06:54:12,785 INFO program: Flex block group size:    16
>06:54:12,785 INFO program: Filesystem created:       Wed May  7 07:36:56 2014
>06:54:12,785 INFO program: Last mount time:          Wed May  7 07:37:14 2014
>06:54:12,785 INFO program: Last write time:          Wed May  7 07:37:20 2014
>06:54:12,785 INFO program: Mount count:              2
>06:54:12,786 INFO program: Maximum mount count:      -1
>06:54:12,786 INFO program: Last checked:             Wed May  7 07:36:56 2014
>06:54:12,786 INFO program: Check interval:           0 (<none>)
>06:54:12,786 INFO program: Lifetime writes:          32 MB
>06:54:12,787 INFO program: Reserved blocks uid:      0 (user root)
>06:54:12,787 INFO program: Reserved blocks gid:      0 (group root)
>06:54:12,787 INFO program: First inode:              11
>06:54:12,787 INFO program: Inode size:	          256
>06:54:12,787 INFO program: Required extra isize:     28
>06:54:12,787 INFO program: Desired extra isize:      28
>06:54:12,788 INFO program: Journal inode:            8
>06:54:12,788 INFO program: Default directory hash:   half_md4
>06:54:12,788 INFO program: Directory Hash Seed:      164636ca-ef00-45fb-b431-a639a7074ae1
>06:54:12,788 INFO program: Journal backup:           inode blocks
>06:54:12,788 INFO program: Journal features:         (none)
>06:54:12,788 INFO program: Journal size:             32M
>06:54:12,789 INFO program: Journal length:           32768
>06:54:12,789 INFO program: Journal sequence:         0x0000001f
>06:54:12,789 INFO program: Journal start:            0
>06:54:12,789 INFO program: 
>06:54:12,789 DEBUG program: Return code: 0
>06:54:12,790 INFO program: Running... resize2fs -P /dev/mapper/live-base
>06:54:12,797 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>06:54:12,798 INFO program: resize2fs: Operation not permitted while trying to open /dev/mapper/live-base
>06:54:12,798 INFO program: Couldn't find valid filesystem superblock.
>06:54:12,798 DEBUG program: Return code: 1
>06:54:12,805 INFO program: Running... udevadm settle --timeout=300
>06:54:12,820 DEBUG program: Return code: 0
>06:54:12,853 INFO program: Running... udevadm settle --timeout=300
>06:54:12,865 DEBUG program: Return code: 0
>06:54:12,871 INFO program: Running... udevadm settle --timeout=300
>06:54:12,882 DEBUG program: Return code: 0
>06:54:12,887 INFO program: Running... udevadm settle --timeout=300
>06:54:12,898 DEBUG program: Return code: 0
>06:54:12,900 INFO program: Running... udevadm settle --timeout=300
>06:54:12,912 DEBUG program: Return code: 0
>06:54:12,914 INFO program: Running... lvm lvchange -a n rhel_newrhel7/root
>06:54:12,947 DEBUG program: Return code: 0
>06:54:12,951 INFO program: Running... udevadm settle --timeout=300
>06:54:12,962 DEBUG program: Return code: 0
>06:54:12,964 INFO program: Running... lvm vgchange -a n rhel_newrhel7
>06:54:12,997 INFO program:   0 logical volume(s) in volume group "rhel_newrhel7" now active
>06:54:12,997 DEBUG program: Return code: 0
>06:54:13,002 INFO program: Running... udevadm settle --timeout=300
>06:54:13,013 DEBUG program: Return code: 0
>06:54:13,018 INFO program: Running... udevadm settle --timeout=300
>06:54:13,029 DEBUG program: Return code: 0
>06:54:13,034 INFO program: Running... mount -t xfs -o defaults,ro /dev/vda1 /mnt/sysimage
>06:54:13,054 DEBUG program: Return code: 0
>06:54:13,057 INFO program: Running... udevadm settle --timeout=300
>06:54:13,070 DEBUG program: Return code: 0
>06:54:13,070 INFO program: Running... umount /mnt/sysimage
>06:54:13,137 DEBUG program: Return code: 0
>06:54:13,138 INFO program: Running... udevadm settle --timeout=300
>06:54:13,162 DEBUG program: Return code: 0
>06:54:13,169 INFO program: Running... udevadm settle --timeout=300
>06:54:13,189 DEBUG program: Return code: 0
>06:54:13,196 INFO program: Running... lvm lvchange -a y rhel_newrhel7/root
>06:54:13,253 DEBUG program: Return code: 0
>06:54:13,253 INFO program: Running... udevadm settle --timeout=300
>06:54:13,269 DEBUG program: Return code: 0
>06:54:13,270 INFO program: Running... mount -t xfs -o defaults,ro /dev/mapper/rhel_newrhel7-root /mnt/sysimage
>06:54:13,351 DEBUG program: Return code: 0
>06:54:13,352 INFO program: Running... arch
>06:54:13,358 ERR program: Error running arch: No such file or directory
>06:54:13,367 INFO program: Running... udevadm settle --timeout=300
>06:54:13,379 DEBUG program: Return code: 0
>06:54:13,379 INFO program: Running... umount /mnt/sysimage
>06:54:13,501 DEBUG program: Return code: 0
>06:54:13,502 INFO program: Running... udevadm settle --timeout=300
>06:54:13,528 DEBUG program: Return code: 0
>06:54:13,531 INFO program: Running... lvm lvchange -a n rhel_newrhel7/root
>06:54:13,577 DEBUG program: Return code: 0
>06:54:19,535 INFO program: Running... udevadm settle --timeout=300
>06:54:19,555 DEBUG program: Return code: 0
>06:54:19,567 INFO program: Running... udevadm settle --timeout=300
>06:54:19,584 DEBUG program: Return code: 0
>06:54:19,587 INFO program: Running... lvm vgchange -a n rhel_newrhel7
>06:54:19,613 INFO program:   0 logical volume(s) in volume group "rhel_newrhel7" now active
>06:54:19,614 DEBUG program: Return code: 0
>06:54:19,628 INFO program: Running... udevadm settle --timeout=300
>06:54:19,640 DEBUG program: Return code: 0
>06:54:19,927 INFO program: Running... udevadm settle --timeout=300
>06:54:19,939 DEBUG program: Return code: 0
>06:54:19,942 INFO program: Running... udevadm settle --timeout=300
>06:54:19,954 DEBUG program: Return code: 0
>06:54:19,967 INFO program: Running... udevadm settle --timeout=300
>06:54:19,979 DEBUG program: Return code: 0
>06:54:19,982 INFO program: Running... udevadm settle --timeout=300
>06:54:19,994 DEBUG program: Return code: 0
>06:54:21,416 INFO program: Running... hwclock --systohc --utc
>06:54:22,501 DEBUG program: Return code: 0
>06:54:22,534 INFO program: Running... wipefs -f -a /dev/vda1
>06:54:22,619 INFO program: /dev/vda1: 4 bytes were erased at offset 0x00000000 (xfs): 58 46 53 42
>06:54:22,619 DEBUG program: Return code: 0
>06:54:22,619 INFO program: Running... udevadm settle --timeout=300
>06:54:22,647 DEBUG program: Return code: 0
>06:54:22,652 INFO program: Running... udevadm settle --timeout=300
>06:54:22,666 DEBUG program: Return code: 0
>06:54:22,666 INFO program: Running... udevadm settle --timeout=300
>06:54:22,682 DEBUG program: Return code: 0
>06:54:22,690 INFO program: Running... lvm lvchange -a y rhel_newrhel7/root
>06:54:22,746 DEBUG program: Return code: 0
>06:54:22,747 INFO program: Running... udevadm settle --timeout=300
>06:54:22,761 DEBUG program: Return code: 0
>06:54:22,763 INFO program: Running... wipefs -f -a /dev/mapper/rhel_newrhel7-root
>06:54:22,787 INFO program: /dev/mapper/rhel_newrhel7-root: 4 bytes were erased at offset 0x00000000 (xfs): 58 46 53 42
>06:54:22,787 DEBUG program: Return code: 0
>06:54:22,788 INFO program: Running... udevadm settle --timeout=300
>06:54:22,809 DEBUG program: Return code: 0
>06:54:22,812 INFO program: Running... udevadm settle --timeout=300
>06:54:22,824 DEBUG program: Return code: 0
>06:54:22,827 INFO program: Running... lvm lvchange -a n rhel_newrhel7/root
>06:54:22,858 DEBUG program: Return code: 0
>06:54:22,859 INFO program: Running... udevadm settle --timeout=300
>06:54:22,871 DEBUG program: Return code: 0
>06:54:22,877 INFO program: Running... lvm lvremove rhel_newrhel7/root
>06:54:22,917 INFO program:   Logical volume "root" successfully removed
>06:54:22,918 DEBUG program: Return code: 0
>06:54:22,918 INFO program: Running... udevadm settle --timeout=300
>06:54:22,931 DEBUG program: Return code: 0
>06:54:22,936 INFO program: Running... lvm lvchange -a y rhel_newrhel7/swap
>06:54:22,980 DEBUG program: Return code: 0
>06:54:22,981 INFO program: Running... udevadm settle --timeout=300
>06:54:22,997 DEBUG program: Return code: 0
>06:54:22,999 INFO program: Running... wipefs -f -a /dev/mapper/rhel_newrhel7-swap
>06:54:23,032 INFO program: /dev/mapper/rhel_newrhel7-swap: 10 bytes were erased at offset 0x00000ff6 (swap): 53 57 41 50 53 50 41 43 45 32
>06:54:23,033 DEBUG program: Return code: 0
>06:54:23,033 INFO program: Running... udevadm settle --timeout=300
>06:54:23,054 DEBUG program: Return code: 0
>06:54:23,057 INFO program: Running... udevadm settle --timeout=300
>06:54:23,070 DEBUG program: Return code: 0
>06:54:23,072 INFO program: Running... lvm lvchange -a n rhel_newrhel7/swap
>06:54:23,102 DEBUG program: Return code: 0
>06:54:23,103 INFO program: Running... udevadm settle --timeout=300
>06:54:23,118 DEBUG program: Return code: 0
>06:54:23,129 INFO program: Running... lvm lvremove rhel_newrhel7/swap
>06:54:23,161 INFO program:   Logical volume "swap" successfully removed
>06:54:23,161 DEBUG program: Return code: 0
>06:54:23,162 INFO program: Running... udevadm settle --timeout=300
>06:54:23,174 DEBUG program: Return code: 0
>06:54:23,179 INFO program: Running... udevadm settle --timeout=300
>06:54:23,191 DEBUG program: Return code: 0
>06:54:23,193 INFO program: Running... lvm vgchange -a n rhel_newrhel7
>06:54:23,213 INFO program:   0 logical volume(s) in volume group "rhel_newrhel7" now active
>06:54:23,213 DEBUG program: Return code: 0
>06:54:23,217 INFO program: Running... lvm vgreduce --removemissing --force rhel_newrhel7
>06:54:23,238 INFO program:   Volume group "rhel_newrhel7" is already consistent
>06:54:23,238 DEBUG program: Return code: 0
>06:54:23,239 INFO program: Running... lvm vgchange -a n rhel_newrhel7
>06:54:23,260 INFO program:   0 logical volume(s) in volume group "rhel_newrhel7" now active
>06:54:23,260 DEBUG program: Return code: 0
>06:54:23,260 INFO program: Running... lvm vgremove --force rhel_newrhel7
>06:54:23,284 INFO program:   Volume group "rhel_newrhel7" successfully removed
>06:54:23,284 DEBUG program: Return code: 0
>06:54:23,284 INFO program: Running... udevadm settle --timeout=300
>06:54:23,298 DEBUG program: Return code: 0
>06:54:23,301 INFO program: Running... lvm pvremove --force --force --yes /dev/vda2
>06:54:23,323 INFO program:   Labels on physical volume "/dev/vda2" successfully wiped
>06:54:23,323 DEBUG program: Return code: 0
>06:54:23,324 INFO program: Running... lvm pvscan --cache /dev/vda2
>06:54:23,347 INFO program:   No PV label found on /dev/vda2.
>06:54:23,347 DEBUG program: Return code: 0
>06:54:23,349 INFO program: Running... udevadm settle --timeout=300
>06:54:23,365 DEBUG program: Return code: 0
>06:54:23,369 INFO program: Running... udevadm settle --timeout=300
>06:54:23,382 DEBUG program: Return code: 0
>06:54:23,382 INFO program: Running... udevadm settle --timeout=300
>06:54:23,393 DEBUG program: Return code: 0
>06:54:23,399 INFO program: Running... udevadm settle --timeout=300
>06:54:23,411 DEBUG program: Return code: 0
>06:54:23,564 INFO program: Running... udevadm settle --timeout=300
>06:54:23,618 DEBUG program: Return code: 0
>06:54:23,619 INFO program: Running... udevadm settle --timeout=300
>06:54:23,641 DEBUG program: Return code: 0
>06:54:23,645 INFO program: Running... udevadm settle --timeout=300
>06:54:23,657 DEBUG program: Return code: 0
>06:54:23,681 INFO program: Running... udevadm settle --timeout=300
>06:54:23,702 DEBUG program: Return code: 0
>06:54:23,703 INFO program: Running... udevadm settle --timeout=300
>06:54:23,716 DEBUG program: Return code: 0
>06:54:23,719 INFO program: Running... wipefs -f -a /dev/vda
>06:54:23,747 INFO program: /dev/vda: 2 bytes were erased at offset 0x000001fe (dos): 55 aa
>06:54:23,747 INFO program: /dev/vda: calling ioclt to re-read partition table: Success
>06:54:23,748 DEBUG program: Return code: 0
>06:54:23,748 INFO program: Running... udevadm settle --timeout=300
>06:54:23,769 DEBUG program: Return code: 0
>06:54:23,771 INFO program: Running... udevadm settle --timeout=300
>06:54:23,785 DEBUG program: Return code: 0
>06:54:23,785 INFO program: Running... udevadm settle --timeout=300
>06:54:23,798 DEBUG program: Return code: 0
>06:54:23,820 INFO program: Running... udevadm settle --timeout=300
>06:54:23,840 DEBUG program: Return code: 0
>06:54:23,841 INFO program: Running... udevadm settle --timeout=300
>06:54:23,853 DEBUG program: Return code: 0
>06:54:23,855 INFO program: Running... udevadm settle --timeout=300
>06:54:23,868 DEBUG program: Return code: 0
>06:54:23,875 INFO program: Running... dd if=/dev/zero of=/dev/vda bs=512 seek=2048 count=2048
>06:54:23,909 INFO program: 2048+0 records in
>06:54:23,909 INFO program: 2048+0 records out
>06:54:23,909 INFO program: 1048576 bytes (1.0 MB) copied, 0.0276221 s, 38.0 MB/s
>06:54:23,910 DEBUG program: Return code: 0
>06:54:23,910 INFO program: Running... udevadm settle --timeout=300
>06:54:23,930 DEBUG program: Return code: 0
>06:54:23,963 INFO program: Running... udevadm settle --timeout=300
>06:54:23,993 DEBUG program: Return code: 0
>06:54:23,996 INFO program: Running... wipefs -f -a /dev/vda1
>06:54:24,012 DEBUG program: Return code: 0
>06:54:24,021 INFO program: Running... udevadm settle --timeout=300
>06:54:24,034 DEBUG program: Return code: 0
>06:54:24,035 INFO program: Running... udevadm settle --timeout=300
>06:54:24,047 DEBUG program: Return code: 0
>06:54:24,078 INFO program: Running... mkfs.xfs -f /dev/vda1
>06:54:24,210 INFO program: meta-data=/dev/vda1              isize=256    agcount=4, agsize=32000 blks
>06:54:24,210 INFO program:          =                       sectsz=512   attr=2, projid32bit=1
>06:54:24,210 INFO program:          =                       crc=0
>06:54:24,210 INFO program: data     =                       bsize=4096   blocks=128000, imaxpct=25
>06:54:24,210 INFO program:          =                       sunit=0      swidth=0 blks
>06:54:24,211 INFO program: naming   =version 2              bsize=4096   ascii-ci=0 ftype=0
>06:54:24,211 INFO program: log      =internal log           bsize=4096   blocks=853, version=2
>06:54:24,211 INFO program:          =                       sectsz=512   sunit=0 blks, lazy-count=1
>06:54:24,211 INFO program: realtime =none                   extsz=4096   blocks=0, rtextents=0
>06:54:24,211 DEBUG program: Return code: 0
>06:54:24,214 INFO program: Running... udevadm settle --timeout=300
>06:54:24,237 DEBUG program: Return code: 0
>06:54:24,240 INFO program: Running... udevadm settle --timeout=300
>06:54:24,254 DEBUG program: Return code: 0
>06:54:24,261 INFO program: Running... dd if=/dev/zero of=/dev/vda bs=512 seek=1026048 count=2048
>06:54:24,291 INFO program: 2048+0 records in
>06:54:24,291 INFO program: 2048+0 records out
>06:54:24,291 INFO program: 1048576 bytes (1.0 MB) copied, 0.0236225 s, 44.4 MB/s
>06:54:24,292 DEBUG program: Return code: 0
>06:54:24,292 INFO program: Running... udevadm settle --timeout=300
>06:54:24,308 DEBUG program: Return code: 0
>06:54:24,348 INFO program: Running... udevadm settle --timeout=300
>06:54:24,372 DEBUG program: Return code: 0
>06:54:24,376 INFO program: Running... wipefs -f -a /dev/vda2
>06:54:24,391 DEBUG program: Return code: 0
>06:54:24,396 INFO program: Running... udevadm settle --timeout=300
>06:54:24,411 DEBUG program: Return code: 0
>06:54:24,411 INFO program: Running... udevadm settle --timeout=300
>06:54:24,423 DEBUG program: Return code: 0
>06:54:24,452 INFO program: Running... wipefs -f -a /dev/vda2
>06:54:24,470 DEBUG program: Return code: 0
>06:54:24,470 INFO program: Running... lvm pvscan --cache /dev/vda2
>06:54:24,494 INFO program:   No PV label found on /dev/vda2.
>06:54:24,494 DEBUG program: Return code: 0
>06:54:24,494 INFO program: Running... lvm pvcreate --dataalignment 1024k /dev/vda2
>06:54:24,524 INFO program:   Physical volume "/dev/vda2" successfully created
>06:54:24,524 DEBUG program: Return code: 0
>06:54:24,524 INFO program: Running... lvm pvscan --cache /dev/vda2
>06:54:24,546 DEBUG program: Return code: 0
>06:54:24,548 INFO program: Running... udevadm settle --timeout=300
>06:54:24,562 DEBUG program: Return code: 0
>06:54:24,565 INFO program: Running... udevadm settle --timeout=300
>06:54:24,578 DEBUG program: Return code: 0
>06:54:24,583 INFO program: Running... lvm vgcreate -s 4m rhel_newrhel7 /dev/vda2
>06:54:24,610 INFO program:   Volume group "rhel_newrhel7" successfully created
>06:54:24,610 DEBUG program: Return code: 0
>06:54:24,613 INFO program: Running... udevadm settle --timeout=300
>06:54:24,625 DEBUG program: Return code: 0
>06:54:24,625 INFO program: Running... udevadm settle --timeout=300
>06:54:24,637 DEBUG program: Return code: 0
>06:54:24,642 INFO program: Running... lvm vgs --noheadings --nosuffix --units m -o uuid,size,free,extent_size,extent_count,free_count,pv_count --config global {locking_type=4}  rhel_newrhel7
>06:54:24,652 INFO program:   CdyhGt-YhtS-r1Qg-oUcY-fEGe-MblR-NcSyqP 19976.00 19976.00 4.00 4994 4994   1
>06:54:24,652 DEBUG program: Return code: 0
>06:54:24,654 INFO program: Running... lvm lvcreate -L 2048m -n swap -y rhel_newrhel7
>06:54:24,710 INFO program:   Logical volume "swap" created
>06:54:24,711 DEBUG program: Return code: 0
>06:54:24,716 INFO program: Running... udevadm settle --timeout=300
>06:54:24,734 DEBUG program: Return code: 0
>06:54:24,734 INFO program: Running... udevadm settle --timeout=300
>06:54:24,746 DEBUG program: Return code: 0
>06:54:24,750 INFO program: Running... mkswap -f /dev/mapper/rhel_newrhel7-swap
>06:54:24,785 INFO program: Setting up swapspace version 1, size = 2097148 KiB
>06:54:24,785 INFO program: no label, UUID=81955252-24a8-456f-8f3c-dcbee2493bc5
>06:54:24,785 DEBUG program: Return code: 0
>06:54:24,787 INFO program: Running... udevadm settle --timeout=300
>06:54:24,818 DEBUG program: Return code: 0
>06:54:24,821 INFO program: Running... udevadm settle --timeout=300
>06:54:24,836 DEBUG program: Return code: 0
>06:54:24,841 INFO program: Running... lvm vgs --noheadings --nosuffix --units m -o uuid,size,free,extent_size,extent_count,free_count,pv_count --config global {locking_type=4}  rhel_newrhel7
>06:54:24,852 INFO program:   CdyhGt-YhtS-r1Qg-oUcY-fEGe-MblR-NcSyqP 19976.00 17928.00 4.00 4994 4482   1
>06:54:24,853 DEBUG program: Return code: 0
>06:54:24,854 INFO program: Running... lvm lvcreate -L 17928m -n root -y rhel_newrhel7
>06:54:24,908 INFO program:   Logical volume "root" created
>06:54:24,908 DEBUG program: Return code: 0
>06:54:24,914 INFO program: Running... udevadm settle --timeout=300
>06:54:24,933 DEBUG program: Return code: 0
>06:54:24,933 INFO program: Running... udevadm settle --timeout=300
>06:54:24,945 DEBUG program: Return code: 0
>06:54:24,950 INFO program: Running... mkfs.xfs -f /dev/mapper/rhel_newrhel7-root
>06:54:25,109 INFO program: meta-data=/dev/mapper/rhel_newrhel7-root isize=256    agcount=4, agsize=1147392 blks
>06:54:25,109 INFO program:          =                       sectsz=512   attr=2, projid32bit=1
>06:54:25,110 INFO program:          =                       crc=0
>06:54:25,110 INFO program: data     =                       bsize=4096   blocks=4589568, imaxpct=25
>06:54:25,111 INFO program:          =                       sunit=0      swidth=0 blks
>06:54:25,112 INFO program: naming   =version 2              bsize=4096   ascii-ci=0 ftype=0
>06:54:25,112 INFO program: log      =internal log           bsize=4096   blocks=2560, version=2
>06:54:25,113 INFO program:          =                       sectsz=512   sunit=0 blks, lazy-count=1
>06:54:25,113 INFO program: realtime =none                   extsz=4096   blocks=0, rtextents=0
>06:54:25,114 DEBUG program: Return code: 0
>06:54:25,122 INFO program: Running... udevadm settle --timeout=300
>06:54:25,168 DEBUG program: Return code: 0
>06:54:25,174 INFO program: Running... udevadm settle --timeout=300
>06:54:25,194 DEBUG program: Return code: 0
>06:54:25,261 INFO program: Running... swapon /dev/mapper/rhel_newrhel7-swap
>06:54:25,296 DEBUG program: Return code: 0
>06:54:25,322 INFO program: Running... mount -t xfs -o defaults /dev/mapper/rhel_newrhel7-root /mnt/sysimage
>06:54:25,337 DEBUG program: Return code: 0
>06:54:25,348 INFO program: Running... mount -t xfs -o defaults /dev/vda1 /mnt/sysimage/boot
>06:54:25,359 DEBUG program: Return code: 0
>06:54:25,363 INFO program: Running... mount -t bind -o bind,defaults /dev /mnt/sysimage/dev
>06:54:25,371 DEBUG program: Return code: 0
>06:54:25,373 INFO program: Running... mount -t devpts -o gid=5,mode=620 devpts /mnt/sysimage/dev/pts
>06:54:25,381 DEBUG program: Return code: 0
>06:54:25,383 INFO program: Running... mount -t tmpfs -o defaults tmpfs /mnt/sysimage/dev/shm
>06:54:25,390 DEBUG program: Return code: 0
>06:54:25,393 INFO program: Running... mount -t proc -o defaults proc /mnt/sysimage/proc
>06:54:25,401 DEBUG program: Return code: 0
>06:54:25,431 INFO program: Running... mount -t bind -o bind,defaults /run /mnt/sysimage/run
>06:54:25,437 DEBUG program: Return code: 0
>06:54:25,440 INFO program: Running... mount -t sysfs -o defaults sysfs /mnt/sysimage/sys
>06:54:25,448 DEBUG program: Return code: 0
>06:54:25,451 INFO program: Running... mount -t selinuxfs -o defaults selinuxfs /mnt/sysimage/sys/fs/selinux
>06:54:25,458 DEBUG program: Return code: 0
>06:54:32,150 INFO program: Running... /usr/libexec/anaconda/anaconda-yum --config /tmp/anaconda-yum.conf --tsfile /mnt/sysimage/anaconda-yum.yumtx --rpmlog /tmp/rpm-script.log --installroot /mnt/sysimage --release 7.0 --arch x86_64
>07:17:06,235 INFO program: Running... xfs_freeze -f /boot
>07:17:06,428 DEBUG program: Return code: 0
>07:17:06,429 INFO program: Running... xfs_freeze -u /boot
>07:17:06,538 DEBUG program: Return code: 0
>07:17:06,803 INFO program: Running... grub2-install --no-floppy /dev/vda
>07:17:15,709 INFO program: Installing for i386-pc platform.
>07:17:15,710 INFO program: Installation finished. No error reported.
>07:17:15,711 DEBUG program: Return code: 0
>07:17:15,852 INFO program: Running... xfs_freeze -f /boot
>07:17:16,142 DEBUG program: Return code: 0
>07:17:16,149 INFO program: Running... xfs_freeze -u /boot
>07:17:16,167 DEBUG program: Return code: 0
>07:17:16,168 INFO program: Running... /usr/sbin/rhcrashkernel-param
>07:17:16,229 INFO program: crashkernel=auto
>07:17:16,229 DEBUG program: Return code: 0
>07:17:16,231 INFO program: Running... grub2-set-default Red Hat Enterprise Linux Linux, with Linux 3.10.0-123.el7.x86_64
>07:17:16,694 DEBUG program: Return code: 0
>07:17:16,694 INFO program: Running... grub2-mkconfig -o /boot/grub2/grub.cfg
>07:17:18,459 INFO program: Generating grub configuration file ...
>07:17:18,460 INFO program: Found linux image: /boot/vmlinuz-3.10.0-123.el7.x86_64
>07:17:18,460 INFO program: Found initrd image: /boot/initramfs-3.10.0-123.el7.x86_64.img
>07:17:18,460 INFO program: Found linux image: /boot/vmlinuz-0-rescue-c8ffd95b6f3d4ecdb87004c8466f416b
>07:17:18,460 INFO program: Found initrd image: /boot/initramfs-0-rescue-c8ffd95b6f3d4ecdb87004c8466f416b.img
>07:17:18,460 INFO program: done
>07:17:18,461 DEBUG program: Return code: 0
>07:17:18,521 INFO program: Running... xfs_freeze -f /boot
>07:17:18,570 DEBUG program: Return code: 0
>07:17:18,570 INFO program: Running... xfs_freeze -u /boot
>07:17:18,586 DEBUG program: Return code: 0
>07:17:18,693 INFO program: Running... /usr/sbin/authconfig --update --nostart --enableshadow --passalgo=sha512
>07:17:19,342 DEBUG program: Return code: 0
>07:17:19,346 INFO program: Running... systemctl disable firstboot-graphical.service initial-setup-graphical.service initial-setup-text.service
>07:17:19,385 INFO program: rm '/etc/systemd/system/multi-user.target.wants/initial-setup-text.service'
>07:17:19,385 INFO program: rm '/etc/systemd/system/graphical.target.wants/initial-setup-graphical.service'
>07:17:19,386 DEBUG program: Return code: 0
>07:17:19,386 INFO program: Running... systemctl enable chronyd.service
>07:17:19,406 DEBUG program: Return code: 0
>07:17:19,693 INFO program: Running... /usr/bin/firewall-offline-cmd --enabled --service=ssh
>07:17:20,394 INFO program: Adding service 'ssh' to default zone.
>07:17:20,396 INFO program: success
>07:17:20,396 DEBUG program: Return code: 0
>07:17:20,398 INFO program: Running... hostname newrhel7
>07:17:20,438 DEBUG program: Return code: 0
>07:17:21,158 INFO program: Running... new-kernel-pkg --mkinitrd --dracut --depmod --update 3.10.0-123.el7.x86_64
>07:17:42,086 INFO program: cat: write error: Broken pipe
>07:17:42,086 DEBUG program: Return code: 0
>07:17:42,092 INFO program: Running... /bin/sh /tmp/ks-script-NXj73o

Actions: View

Attachments on bug 1141676: 937476 | 937477 | 937478 | 937479 | 937480 | 937481 | 937482 | 988643