Attachment 893160 Details for Bug 1095160 – program.log

program.log

program.log (text/plain), 36.64 KB, created by Bruno Goncalves on 2014-05-07 08:41:03 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Bruno Goncalves

Created: 2014-05-07 08:41:03 UTC

Size: 36.64 KB

patch

obsolete

>08:16:49,895 INFO program: Running... /bin/sh /tmp/ks-script-8Nl0VY
>08:16:50,962 INFO program: + wget -O - http://lab-02.rhts.eng.brq.redhat.com:8000/install_start/1342555
>08:16:50,962 INFO program: --2014-05-07 08:16:49--  http://lab-02.rhts.eng.brq.redhat.com:8000/install_start/1342555
>08:16:50,963 INFO program: Resolving lab-02.rhts.eng.brq.redhat.com (lab-02.rhts.eng.brq.redhat.com)... 2620:52:0:2223:216:3eff:fe52:518e, 10.34.32.5
>08:16:50,963 INFO program: Connecting to lab-02.rhts.eng.brq.redhat.com (lab-02.rhts.eng.brq.redhat.com)|2620:52:0:2223:216:3eff:fe52:518e|:8000... connected.
>08:16:50,963 INFO program: HTTP request sent, awaiting response... 200 OK
>08:16:50,964 INFO program: Length: 4 [text/plain]
>08:16:50,964 INFO program: Saving to: 'STDOUT'
>08:16:50,964 INFO program: True
>08:16:50,964 INFO program: 0K                                                       100%  419K=0s
>08:16:50,965 INFO program: 
>08:16:50,965 INFO program: 2014-05-07 08:16:50 (419 KB/s) - written to stdout [4/4]
>08:16:50,965 INFO program: 
>08:16:50,966 INFO program: + wget -O /tmp/anamon http://lab-02.rhts.eng.brq.redhat.com/beaker/anamon
>08:16:50,966 INFO program: --2014-05-07 08:16:50--  http://lab-02.rhts.eng.brq.redhat.com/beaker/anamon
>08:16:50,966 INFO program: Resolving lab-02.rhts.eng.brq.redhat.com (lab-02.rhts.eng.brq.redhat.com)... 2620:52:0:2223:216:3eff:fe52:518e, 10.34.32.5
>08:16:50,967 INFO program: Connecting to lab-02.rhts.eng.brq.redhat.com (lab-02.rhts.eng.brq.redhat.com)|2620:52:0:2223:216:3eff:fe52:518e|:80... connected.
>08:16:50,967 INFO program: HTTP request sent, awaiting response... 200 OK
>08:16:50,967 INFO program: Length: 8703 (8.5K) [text/plain]
>08:16:50,967 INFO program: Saving to: '/tmp/anamon'
>08:16:50,968 INFO program: 
>08:16:50,968 INFO program: 0K ........                                              100%  136M=0s
>08:16:50,968 INFO program: 
>08:16:50,969 INFO program: 2014-05-07 08:16:50 (136 MB/s) - '/tmp/anamon' saved [8703/8703]
>08:16:50,969 INFO program: 
>08:16:50,969 INFO program: + python /tmp/anamon --recipe-id 1342555 --xmlrpc-url http://lab-02.rhts.eng.brq.redhat.com:8000/RPC2
>08:16:50,970 DEBUG program: Return code: 0
>08:16:50,993 INFO program: Running... udevadm trigger --action=change --subsystem-match=block
>08:16:51,014 DEBUG program: Return code: 0
>08:16:51,015 INFO program: Running... udevadm settle --timeout=300
>08:16:51,169 DEBUG program: Return code: 0
>08:16:51,171 INFO program: Running... modprobe fcoe
>08:16:51,258 DEBUG program: Return code: 0
>08:16:51,260 INFO program: Running... /usr/libexec/fcoe/fcoe_edd.sh -i
>08:16:51,331 INFO program: No FCoE boot disk information is found in EDD!
>08:16:51,331 DEBUG program: Return code: 1
>08:16:51,345 INFO program: Running... udevadm settle --timeout=300
>08:16:51,364 DEBUG program: Return code: 0
>08:16:51,377 INFO program: Running... udevadm settle --timeout=300
>08:16:51,395 DEBUG program: Return code: 0
>08:16:51,420 INFO program: Running... loadkeys us
>08:16:51,501 INFO program: Loading /lib/kbd/keymaps/i386/qwerty/us.map.gz
>08:16:51,502 DEBUG program: Return code: 0
>08:16:51,502 INFO program: Running... loadkeys us
>08:16:51,522 INFO program: Loading /lib/kbd/keymaps/i386/qwerty/us.map.gz
>08:16:51,523 DEBUG program: Return code: 0
>08:16:51,654 INFO program: Running... modprobe xfs
>08:16:51,766 DEBUG program: Return code: 0
>08:16:54,111 INFO program: Running... udevadm settle --timeout=300
>08:16:54,131 DEBUG program: Return code: 0
>08:16:54,131 INFO program: Running... mpathconf --user_friendly_names y --with_multipathd y
>08:16:54,255 DEBUG program: Return code: 0
>08:16:54,256 INFO program: Running... systemctl start chronyd.service
>08:16:54,382 DEBUG program: Return code: 0
>08:16:54,383 INFO program: Running... udevadm settle --timeout=300
>08:16:54,438 DEBUG program: Return code: 0
>08:16:54,496 INFO program: Running... iscsi-iname
>08:16:54,514 INFO program: iqn.1994-05.com.redhat:259c8647ffbf
>08:16:54,515 DEBUG program: Return code: 0
>08:16:54,522 INFO program: Running... multipath -c /dev/sdb
>08:16:54,534 INFO program: /dev/sdb is a valid multipath device path
>08:16:54,534 DEBUG program: Return code: 0
>08:16:54,613 INFO program: Running... multipath -c /dev/sdc
>08:16:54,624 INFO program: /dev/sdc is a valid multipath device path
>08:16:54,624 DEBUG program: Return code: 0
>08:16:54,627 INFO program: Running... iscsi-iname
>08:16:54,634 INFO program: iqn.1994-05.com.redhat:d59996fb6b0
>08:16:54,635 DEBUG program: Return code: 0
>08:16:54,638 INFO program: Running... multipath -c /dev/sdc
>08:16:54,649 INFO program: /dev/sdc is a valid multipath device path
>08:16:54,650 DEBUG program: Return code: 0
>08:16:54,740 INFO program: Running... multipath -c /dev/sda
>08:16:54,752 INFO program: /dev/sda is not a valid multipath device path
>08:16:54,752 DEBUG program: Return code: 1
>08:16:54,856 INFO program: Running... dumpe2fs -h /dev/sda1
>08:16:54,911 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>08:16:54,912 INFO program: Filesystem volume name:   <none>
>08:16:54,912 INFO program: Last mounted on:          /boot
>08:16:54,913 INFO program: Filesystem UUID:          feacae91-1849-440b-86d4-bd0c8b21968a
>08:16:54,913 INFO program: Filesystem magic number:  0xEF53
>08:16:54,913 INFO program: Filesystem revision #:    1 (dynamic)
>08:16:54,914 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
>08:16:54,914 INFO program: Filesystem flags:         signed_directory_hash 
>08:16:54,914 INFO program: Default mount options:    user_xattr acl
>08:16:54,915 INFO program: Filesystem state:         clean
>08:16:54,915 INFO program: Errors behavior:          Continue
>08:16:54,916 INFO program: Filesystem OS type:       Linux
>08:16:54,916 INFO program: Inode count:              128016
>08:16:54,916 INFO program: Block count:              512000
>08:16:54,917 INFO program: Reserved block count:     25600
>08:16:54,917 INFO program: Free blocks:              455742
>08:16:54,917 INFO program: Free inodes:              127977
>08:16:54,918 INFO program: First block:              1
>08:16:54,918 INFO program: Block size:               1024
>08:16:54,918 INFO program: Fragment size:            1024
>08:16:54,919 INFO program: Reserved GDT blocks:      256
>08:16:54,919 INFO program: Blocks per group:         8192
>08:16:54,919 INFO program: Fragments per group:      8192
>08:16:54,920 INFO program: Inodes per group:         2032
>08:16:54,920 INFO program: Inode blocks per group:   254
>08:16:54,920 INFO program: RAID stride:              256
>08:16:54,921 INFO program: RAID stripe width:        256
>08:16:54,921 INFO program: Flex block group size:    16
>08:16:54,921 INFO program: Filesystem created:       Tue Dec 24 23:25:15 2013
>08:16:54,922 INFO program: Last mount time:          Tue May  6 14:58:18 2014
>08:16:54,922 INFO program: Last write time:          Tue May  6 14:58:18 2014
>08:16:54,923 INFO program: Mount count:              5
>08:16:54,923 INFO program: Maximum mount count:      -1
>08:16:54,924 INFO program: Last checked:             Tue Dec 24 23:25:15 2013
>08:16:54,924 INFO program: Check interval:           0 (<none>)
>08:16:54,924 INFO program: Lifetime writes:          59 MB
>08:16:54,925 INFO program: Reserved blocks uid:      0 (user root)
>08:16:54,925 INFO program: Reserved blocks gid:      0 (group root)
>08:16:54,925 INFO program: First inode:              11
>08:16:54,926 INFO program: Inode size:	          128
>08:16:54,926 INFO program: Journal inode:            8
>08:16:54,926 INFO program: Default directory hash:   half_md4
>08:16:54,926 INFO program: Directory Hash Seed:      dd235499-d90a-44f9-b3bd-0a3cc8a6a693
>08:16:54,927 INFO program: Journal backup:           inode blocks
>08:16:54,927 INFO program: Journal features:         (none)
>08:16:54,928 INFO program: Journal size:             8M
>08:16:54,928 INFO program: Journal length:           8192
>08:16:54,928 INFO program: Journal sequence:         0x00000029
>08:16:54,928 INFO program: Journal start:            0
>08:16:54,929 INFO program: 
>08:16:54,929 DEBUG program: Return code: 0
>08:16:54,930 INFO program: Running... resize2fs -P /dev/sda1
>08:16:54,952 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>08:16:54,952 INFO program: Estimated minimum size of the filesystem: 45117
>08:16:54,953 DEBUG program: Return code: 0
>08:16:55,040 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/sda2
>08:16:55,068 INFO program:   LVM2_PV_UUID=ZEUPfd-BeYf-42PH-HjuM-nXJi-pBtV-IljAEZ
>08:16:55,069 INFO program:   LVM2_PE_START=1024.00
>08:16:55,069 INFO program:   LVM2_VG_NAME=vg_storageqe84
>08:16:55,069 INFO program:   LVM2_VG_UUID=TgSANe-Ecew-NeQI-jFzH-ufZA-Vswu-lsYEpf
>08:16:55,070 INFO program:   LVM2_VG_SIZE=292421632.00
>08:16:55,070 INFO program:   LVM2_VG_FREE=0
>08:16:55,070 INFO program:   LVM2_VG_EXTENT_SIZE=4096.00
>08:16:55,070 INFO program:   LVM2_VG_EXTENT_COUNT=71392
>08:16:55,071 INFO program:   LVM2_VG_FREE_COUNT=0
>08:16:55,071 INFO program:   LVM2_PV_COUNT=1
>08:16:55,071 DEBUG program: Return code: 0
>08:16:55,085 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}  vg_storageqe84
>08:16:55,105 INFO program:   LVM2_LV_NAME=lv_home LVM2_LV_NAME=lv_root LVM2_LV_NAME=lv_swap
>08:16:55,105 INFO program:   LVM2_LV_UUID=LsHESm-mzbD-Bf5C-lJGv-hZuy-O2Xk-5mIy8U LVM2_LV_UUID=xE1xqP-mX6y-ZjTU-v1ht-Nohf-TzeS-Cx0l95 LVM2_LV_UUID=DAtApc-T7vk-uOuL-eJqR-t91v-zv7f-T1FFmU
>08:16:55,106 INFO program:   LVM2_LV_SIZE=231776256.00 LVM2_LV_SIZE=52428800.00 LVM2_LV_SIZE=8216576.00
>08:16:55,106 INFO program:   LVM2_LV_ATTR=-wi-a----- LVM2_LV_ATTR=-wi-a----- LVM2_LV_ATTR=-wi-a-----
>08:16:55,107 INFO program:   LVM2_SEGTYPE=linear LVM2_SEGTYPE=linear LVM2_SEGTYPE=linear
>08:16:55,107 DEBUG program: Return code: 0
>08:16:55,180 INFO program: Running... dumpe2fs -h /dev/mapper/vg_storageqe84-lv_home
>08:16:55,214 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>08:16:55,215 INFO program: Filesystem volume name:   <none>
>08:16:55,215 INFO program: Last mounted on:          /home
>08:16:55,215 INFO program: Filesystem UUID:          473a1c20-349b-471d-822b-6a5002b305ca
>08:16:55,216 INFO program: Filesystem magic number:  0xEF53
>08:16:55,216 INFO program: Filesystem revision #:    1 (dynamic)
>08:16:55,216 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype extent flex_bg sparse_super large_file huge_file uninit_bg dir_nlink extra_isize
>08:16:55,217 INFO program: Filesystem flags:         signed_directory_hash 
>08:16:55,217 INFO program: Default mount options:    user_xattr acl
>08:16:55,218 INFO program: Filesystem state:         clean
>08:16:55,218 INFO program: Errors behavior:          Continue
>08:16:55,218 INFO program: Filesystem OS type:       Linux
>08:16:55,218 INFO program: Inode count:              14491648
>08:16:55,219 INFO program: Block count:              57944064
>08:16:55,219 INFO program: Reserved block count:     2897203
>08:16:55,220 INFO program: Free blocks:              56986644
>08:16:55,220 INFO program: Free inodes:              14491632
>08:16:55,220 INFO program: First block:              0
>08:16:55,221 INFO program: Block size:               4096
>08:16:55,221 INFO program: Fragment size:            4096
>08:16:55,221 INFO program: Reserved GDT blocks:      1010
>08:16:55,222 INFO program: Blocks per group:         32768
>08:16:55,222 INFO program: Fragments per group:      32768
>08:16:55,222 INFO program: Inodes per group:         8192
>08:16:55,223 INFO program: Inode blocks per group:   512
>08:16:55,223 INFO program: RAID stride:              64
>08:16:55,223 INFO program: RAID stripe width:        64
>08:16:55,224 INFO program: Flex block group size:    16
>08:16:55,224 INFO program: Filesystem created:       Tue Dec 24 23:25:24 2013
>08:16:55,224 INFO program: Last mount time:          Tue May  6 14:58:18 2014
>08:16:55,225 INFO program: Last write time:          Tue May  6 19:44:24 2014
>08:16:55,225 INFO program: Mount count:              5
>08:16:55,225 INFO program: Maximum mount count:      -1
>08:16:55,226 INFO program: Last checked:             Tue Dec 24 23:25:24 2013
>08:16:55,226 INFO program: Check interval:           0 (<none>)
>08:16:55,227 INFO program: Lifetime writes:          3672 MB
>08:16:55,227 INFO program: Reserved blocks uid:      0 (user root)
>08:16:55,228 INFO program: Reserved blocks gid:      0 (group root)
>08:16:55,228 INFO program: First inode:              11
>08:16:55,228 INFO program: Inode size:	          256
>08:16:55,229 INFO program: Required extra isize:     28
>08:16:55,229 INFO program: Desired extra isize:      28
>08:16:55,229 INFO program: Journal inode:            8
>08:16:55,230 INFO program: Default directory hash:   half_md4
>08:16:55,230 INFO program: Directory Hash Seed:      963a3e48-ae16-45c7-9469-6f4ec99d9f32
>08:16:55,230 INFO program: Journal backup:           inode blocks
>08:16:55,231 INFO program: Journal features:         (none)
>08:16:55,231 INFO program: Journal size:             128M
>08:16:55,231 INFO program: Journal length:           32768
>08:16:55,232 INFO program: Journal sequence:         0x00000006
>08:16:55,232 INFO program: Journal start:            0
>08:16:55,232 INFO program: 
>08:16:55,233 DEBUG program: Return code: 0
>08:16:55,233 INFO program: Running... resize2fs -P /dev/mapper/vg_storageqe84-lv_home
>08:16:55,244 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>08:16:55,245 INFO program: Estimated minimum size of the filesystem: 159338
>08:16:55,245 DEBUG program: Return code: 0
>08:16:55,340 INFO program: Running... dumpe2fs -h /dev/mapper/vg_storageqe84-lv_root
>08:16:55,355 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>08:16:55,356 INFO program: Filesystem volume name:   <none>
>08:16:55,356 INFO program: Last mounted on:          /
>08:16:55,357 INFO program: Filesystem UUID:          7a73e2c0-4af6-4891-8eff-2bda7bad7f13
>08:16:55,357 INFO program: Filesystem magic number:  0xEF53
>08:16:55,357 INFO program: Filesystem revision #:    1 (dynamic)
>08:16:55,358 INFO program: Filesystem features:      has_journal ext_attr resize_inode dir_index filetype needs_recovery extent flex_bg sparse_super large_file huge_file uninit_bg dir_nlink extra_isize
>08:16:55,358 INFO program: Filesystem flags:         signed_directory_hash 
>08:16:55,359 INFO program: Default mount options:    user_xattr acl
>08:16:55,359 INFO program: Filesystem state:         clean
>08:16:55,359 INFO program: Errors behavior:          Continue
>08:16:55,360 INFO program: Filesystem OS type:       Linux
>08:16:55,360 INFO program: Inode count:              3276800
>08:16:55,360 INFO program: Block count:              13107200
>08:16:55,361 INFO program: Reserved block count:     655360
>08:16:55,361 INFO program: Free blocks:              12346125
>08:16:55,361 INFO program: Free inodes:              3189104
>08:16:55,362 INFO program: First block:              0
>08:16:55,362 INFO program: Block size:               4096
>08:16:55,362 INFO program: Fragment size:            4096
>08:16:55,363 INFO program: Reserved GDT blocks:      1020
>08:16:55,363 INFO program: Blocks per group:         32768
>08:16:55,363 INFO program: Fragments per group:      32768
>08:16:55,364 INFO program: Inodes per group:         8192
>08:16:55,364 INFO program: Inode blocks per group:   512
>08:16:55,364 INFO program: RAID stride:              64
>08:16:55,364 INFO program: RAID stripe width:        64
>08:16:55,365 INFO program: Flex block group size:    16
>08:16:55,365 INFO program: Filesystem created:       Tue Dec 24 23:25:17 2013
>08:16:55,365 INFO program: Last mount time:          Tue May  6 14:58:17 2014
>08:16:55,366 INFO program: Last write time:          Tue Dec 24 23:32:47 2013
>08:16:55,366 INFO program: Mount count:              5
>08:16:55,367 INFO program: Maximum mount count:      -1
>08:16:55,367 INFO program: Last checked:             Tue Dec 24 23:25:17 2013
>08:16:55,368 INFO program: Check interval:           0 (<none>)
>08:16:55,368 INFO program: Lifetime writes:          3874 MB
>08:16:55,369 INFO program: Reserved blocks uid:      0 (user root)
>08:16:55,369 INFO program: Reserved blocks gid:      0 (group root)
>08:16:55,370 INFO program: First inode:              11
>08:16:55,370 INFO program: Inode size:	          256
>08:16:55,370 INFO program: Required extra isize:     28
>08:16:55,371 INFO program: Desired extra isize:      28
>08:16:55,371 INFO program: Journal inode:            8
>08:16:55,372 INFO program: Default directory hash:   half_md4
>08:16:55,372 INFO program: Directory Hash Seed:      ea3b0ee9-433f-448e-81c0-6985f7e6f797
>08:16:55,373 INFO program: Journal backup:           inode blocks
>08:16:55,373 INFO program: Journal features:         journal_incompat_revoke
>08:16:55,373 INFO program: Journal size:             128M
>08:16:55,374 INFO program: Journal length:           32768
>08:16:55,374 INFO program: Journal sequence:         0x00000979
>08:16:55,375 INFO program: Journal start:            1
>08:16:55,375 INFO program: 
>08:16:55,375 DEBUG program: Return code: 0
>08:16:55,376 INFO program: Running... resize2fs -P /dev/mapper/vg_storageqe84-lv_root
>08:16:55,387 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>08:16:55,388 INFO program: Estimated minimum size of the filesystem: 592870
>08:16:55,389 DEBUG program: Return code: 0
>08:16:55,597 INFO program: Running... dumpe2fs -h /dev/loop1
>08:16:55,608 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>08:16:55,609 INFO program: Filesystem volume name:   Anaconda
>08:16:55,609 INFO program: Last mounted on:          /var/tmp/lorax.imgutils.oSKp92
>08:16:55,610 INFO program: Filesystem UUID:          49575ae8-5455-477c-80d2-f9a319989d13
>08:16:55,610 INFO program: Filesystem magic number:  0xEF53
>08:16:55,610 INFO program: Filesystem revision #:    1 (dynamic)
>08:16:55,611 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
>08:16:55,611 INFO program: Filesystem flags:         signed_directory_hash 
>08:16:55,612 INFO program: Default mount options:    user_xattr acl
>08:16:55,612 INFO program: Filesystem state:         clean
>08:16:55,612 INFO program: Errors behavior:          Continue
>08:16:55,613 INFO program: Filesystem OS type:       Linux
>08:16:55,613 INFO program: Inode count:              131072
>08:16:55,614 INFO program: Block count:              2097152
>08:16:55,614 INFO program: Reserved block count:     0
>08:16:55,614 INFO program: Free blocks:              1053295
>08:16:55,615 INFO program: Free inodes:              101797
>08:16:55,615 INFO program: First block:              1
>08:16:55,616 INFO program: Block size:               1024
>08:16:55,616 INFO program: Fragment size:            1024
>08:16:55,616 INFO program: Group descriptor size:    64
>08:16:55,617 INFO program: Reserved GDT blocks:      256
>08:16:55,617 INFO program: Blocks per group:         8192
>08:16:55,618 INFO program: Fragments per group:      8192
>08:16:55,618 INFO program: Inodes per group:         512
>08:16:55,618 INFO program: Inode blocks per group:   128
>08:16:55,619 INFO program: Flex block group size:    16
>08:16:55,619 INFO program: Filesystem created:       Mon May  5 20:03:35 2014
>08:16:55,620 INFO program: Last mount time:          Mon May  5 20:03:54 2014
>08:16:55,620 INFO program: Last write time:          Mon May  5 20:03:59 2014
>08:16:55,620 INFO program: Mount count:              2
>08:16:55,621 INFO program: Maximum mount count:      -1
>08:16:55,621 INFO program: Last checked:             Mon May  5 20:03:35 2014
>08:16:55,622 INFO program: Check interval:           0 (<none>)
>08:16:55,622 INFO program: Lifetime writes:          32 MB
>08:16:55,622 INFO program: Reserved blocks uid:      0 (user root)
>08:16:55,623 INFO program: Reserved blocks gid:      0 (group root)
>08:16:55,623 INFO program: First inode:              11
>08:16:55,624 INFO program: Inode size:	          256
>08:16:55,624 INFO program: Required extra isize:     28
>08:16:55,624 INFO program: Desired extra isize:      28
>08:16:55,625 INFO program: Journal inode:            8
>08:16:55,625 INFO program: Default directory hash:   half_md4
>08:16:55,626 INFO program: Directory Hash Seed:      fc286c57-b9fd-4fe1-9f1a-c1426a63f48c
>08:16:55,626 INFO program: Journal backup:           inode blocks
>08:16:55,626 INFO program: Journal features:         (none)
>08:16:55,627 INFO program: Journal size:             32M
>08:16:55,627 INFO program: Journal length:           32768
>08:16:55,628 INFO program: Journal sequence:         0x0000001b
>08:16:55,628 INFO program: Journal start:            0
>08:16:55,628 INFO program: 
>08:16:55,629 DEBUG program: Return code: 0
>08:16:55,630 INFO program: Running... resize2fs -P /dev/loop1
>08:16:55,640 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>08:16:55,641 INFO program: resize2fs: Device or resource busy while trying to open /dev/loop1
>08:16:55,641 INFO program: Couldn't find valid filesystem superblock.
>08:16:55,642 DEBUG program: Return code: 1
>08:16:55,760 INFO program: Running... dumpe2fs -h /dev/mapper/live-rw
>08:16:55,771 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>08:16:55,771 INFO program: Filesystem volume name:   Anaconda
>08:16:55,771 INFO program: Last mounted on:          /
>08:16:55,772 INFO program: Filesystem UUID:          49575ae8-5455-477c-80d2-f9a319989d13
>08:16:55,772 INFO program: Filesystem magic number:  0xEF53
>08:16:55,772 INFO program: Filesystem revision #:    1 (dynamic)
>08:16:55,772 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
>08:16:55,773 INFO program: Filesystem flags:         signed_directory_hash 
>08:16:55,773 INFO program: Default mount options:    user_xattr acl
>08:16:55,774 INFO program: Filesystem state:         clean
>08:16:55,774 INFO program: Errors behavior:          Continue
>08:16:55,774 INFO program: Filesystem OS type:       Linux
>08:16:55,774 INFO program: Inode count:              131072
>08:16:55,775 INFO program: Block count:              2097152
>08:16:55,775 INFO program: Reserved block count:     0
>08:16:55,775 INFO program: Free blocks:              1053295
>08:16:55,775 INFO program: Free inodes:              101797
>08:16:55,776 INFO program: First block:              1
>08:16:55,776 INFO program: Block size:               1024
>08:16:55,777 INFO program: Fragment size:            1024
>08:16:55,777 INFO program: Group descriptor size:    64
>08:16:55,777 INFO program: Reserved GDT blocks:      256
>08:16:55,778 INFO program: Blocks per group:         8192
>08:16:55,778 INFO program: Fragments per group:      8192
>08:16:55,779 INFO program: Inodes per group:         512
>08:16:55,779 INFO program: Inode blocks per group:   128
>08:16:55,780 INFO program: Flex block group size:    16
>08:16:55,780 INFO program: Filesystem created:       Mon May  5 20:03:35 2014
>08:16:55,780 INFO program: Last mount time:          Wed May  7 08:16:23 2014
>08:16:55,781 INFO program: Last write time:          Wed May  7 08:16:23 2014
>08:16:55,781 INFO program: Mount count:              3
>08:16:55,782 INFO program: Maximum mount count:      -1
>08:16:55,782 INFO program: Last checked:             Mon May  5 20:03:35 2014
>08:16:55,782 INFO program: Check interval:           0 (<none>)
>08:16:55,783 INFO program: Lifetime writes:          32 MB
>08:16:55,783 INFO program: Reserved blocks uid:      0 (user root)
>08:16:55,784 INFO program: Reserved blocks gid:      0 (group root)
>08:16:55,784 INFO program: First inode:              11
>08:16:55,784 INFO program: Inode size:	          256
>08:16:55,785 INFO program: Required extra isize:     28
>08:16:55,785 INFO program: Desired extra isize:      28
>08:16:55,786 INFO program: Journal inode:            8
>08:16:55,786 INFO program: Default directory hash:   half_md4
>08:16:55,786 INFO program: Directory Hash Seed:      fc286c57-b9fd-4fe1-9f1a-c1426a63f48c
>08:16:55,787 INFO program: Journal backup:           inode blocks
>08:16:55,787 INFO program: Journal features:         journal_64bit
>08:16:55,788 INFO program: Journal size:             32M
>08:16:55,788 INFO program: Journal length:           32768
>08:16:55,789 INFO program: Journal sequence:         0x0000001c
>08:16:55,789 INFO program: Journal start:            1
>08:16:55,789 INFO program: 
>08:16:55,790 DEBUG program: Return code: 0
>08:16:55,791 INFO program: Running... resize2fs -P /dev/mapper/live-rw
>08:16:55,800 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>08:16:55,801 INFO program: Estimated minimum size of the filesystem: 1029975
>08:16:55,801 DEBUG program: Return code: 0
>08:16:55,923 INFO program: Running... dumpe2fs -h /dev/mapper/live-base
>08:16:55,931 INFO program: dumpe2fs 1.42.9 (28-Dec-2013)
>08:16:55,932 INFO program: Filesystem volume name:   Anaconda
>08:16:55,933 INFO program: Last mounted on:          /var/tmp/lorax.imgutils.oSKp92
>08:16:55,933 INFO program: Filesystem UUID:          49575ae8-5455-477c-80d2-f9a319989d13
>08:16:55,933 INFO program: Filesystem magic number:  0xEF53
>08:16:55,934 INFO program: Filesystem revision #:    1 (dynamic)
>08:16:55,934 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
>08:16:55,935 INFO program: Filesystem flags:         signed_directory_hash 
>08:16:55,935 INFO program: Default mount options:    user_xattr acl
>08:16:55,936 INFO program: Filesystem state:         clean
>08:16:55,936 INFO program: Errors behavior:          Continue
>08:16:55,936 INFO program: Filesystem OS type:       Linux
>08:16:55,937 INFO program: Inode count:              131072
>08:16:55,937 INFO program: Block count:              2097152
>08:16:55,938 INFO program: Reserved block count:     0
>08:16:55,938 INFO program: Free blocks:              1053295
>08:16:55,938 INFO program: Free inodes:              101797
>08:16:55,939 INFO program: First block:              1
>08:16:55,939 INFO program: Block size:               1024
>08:16:55,940 INFO program: Fragment size:            1024
>08:16:55,940 INFO program: Group descriptor size:    64
>08:16:55,940 INFO program: Reserved GDT blocks:      256
>08:16:55,941 INFO program: Blocks per group:         8192
>08:16:55,941 INFO program: Fragments per group:      8192
>08:16:55,942 INFO program: Inodes per group:         512
>08:16:55,942 INFO program: Inode blocks per group:   128
>08:16:55,943 INFO program: Flex block group size:    16
>08:16:55,943 INFO program: Filesystem created:       Mon May  5 20:03:35 2014
>08:16:55,944 INFO program: Last mount time:          Mon May  5 20:03:54 2014
>08:16:55,944 INFO program: Last write time:          Mon May  5 20:03:59 2014
>08:16:55,944 INFO program: Mount count:              2
>08:16:55,945 INFO program: Maximum mount count:      -1
>08:16:55,945 INFO program: Last checked:             Mon May  5 20:03:35 2014
>08:16:55,946 INFO program: Check interval:           0 (<none>)
>08:16:55,946 INFO program: Lifetime writes:          32 MB
>08:16:55,946 INFO program: Reserved blocks uid:      0 (user root)
>08:16:55,947 INFO program: Reserved blocks gid:      0 (group root)
>08:16:55,947 INFO program: First inode:              11
>08:16:55,948 INFO program: Inode size:	          256
>08:16:55,948 INFO program: Required extra isize:     28
>08:16:55,948 INFO program: Desired extra isize:      28
>08:16:55,949 INFO program: Journal inode:            8
>08:16:55,949 INFO program: Default directory hash:   half_md4
>08:16:55,950 INFO program: Directory Hash Seed:      fc286c57-b9fd-4fe1-9f1a-c1426a63f48c
>08:16:55,950 INFO program: Journal backup:           inode blocks
>08:16:55,950 INFO program: Journal features:         (none)
>08:16:55,951 INFO program: Journal size:             32M
>08:16:55,951 INFO program: Journal length:           32768
>08:16:55,952 INFO program: Journal sequence:         0x0000001b
>08:16:55,952 INFO program: Journal start:            0
>08:16:55,952 INFO program: 
>08:16:55,953 DEBUG program: Return code: 0
>08:16:55,954 INFO program: Running... resize2fs -P /dev/mapper/live-base
>08:16:55,964 INFO program: resize2fs 1.42.9 (28-Dec-2013)
>08:16:55,965 INFO program: resize2fs: Operation not permitted while trying to open /dev/mapper/live-base
>08:16:55,965 INFO program: Couldn't find valid filesystem superblock.
>08:16:55,966 DEBUG program: Return code: 1
>08:16:56,143 INFO program: Running... xfs_db -c "sb 0" -c "p dblocks" -c "p blocksize" /dev/mapper/mpatha1
>08:16:56,226 INFO program: command "sb 0" not found
>08:16:56,226 INFO program: command "p dblocks" not found
>08:16:56,227 INFO program: command "p blocksize" not found
>08:16:56,227 DEBUG program: Return code: 0
>08:16:56,348 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/mapper/mpatha2
>08:16:56,370 INFO program:   Failed to find physical volume "/dev/mapper/mpatha2"
>08:16:56,371 DEBUG program: Return code: 5
>08:16:56,563 INFO program: Running... udevadm settle --timeout=300
>08:16:56,583 DEBUG program: Return code: 0
>08:16:56,606 INFO program: Running... udevadm settle --timeout=300
>08:16:56,627 DEBUG program: Return code: 0
>08:16:56,639 INFO program: Running... udevadm settle --timeout=300
>08:16:56,660 DEBUG program: Return code: 0
>08:16:56,665 INFO program: Running... udevadm settle --timeout=300
>08:16:56,687 DEBUG program: Return code: 0
>08:16:56,691 INFO program: Running... lvm lvchange -a n vg_storageqe84/lv_home
>08:16:56,748 DEBUG program: Return code: 0
>08:16:56,761 INFO program: Running... udevadm settle --timeout=300
>08:16:56,783 DEBUG program: Return code: 0
>08:16:56,788 INFO program: Running... lvm vgchange -a n vg_storageqe84
>08:16:56,837 INFO program:   0 logical volume(s) in volume group "vg_storageqe84" now active
>08:16:56,837 DEBUG program: Return code: 0
>08:16:56,846 INFO program: Running... udevadm settle --timeout=300
>08:16:56,863 DEBUG program: Return code: 0
>08:16:56,874 INFO program: Running... udevadm settle --timeout=300
>08:16:56,890 DEBUG program: Return code: 0
>08:16:56,905 INFO program: Running... udevadm settle --timeout=300
>08:16:56,931 DEBUG program: Return code: 0
>08:16:56,943 INFO program: Running... udevadm settle --timeout=300
>08:16:56,969 DEBUG program: Return code: 0
>08:16:56,983 INFO program: Running... udevadm settle --timeout=300
>08:16:57,005 DEBUG program: Return code: 0
>08:16:57,018 INFO program: Running... udevadm settle --timeout=300
>08:16:57,041 DEBUG program: Return code: 0
>08:16:57,051 INFO program: Running... udevadm settle --timeout=300
>08:16:57,070 DEBUG program: Return code: 0
>08:16:57,098 INFO program: Running... udevadm settle --timeout=300
>08:16:57,118 DEBUG program: Return code: 0
>08:16:57,132 INFO program: Running... udevadm settle --timeout=300
>08:16:57,150 DEBUG program: Return code: 0
>08:16:57,163 INFO program: Running... udevadm settle --timeout=300
>08:16:57,181 DEBUG program: Return code: 0
>08:16:57,215 INFO program: Running... mount -t xfs -o defaults,ro /dev/mapper/mpatha1 /mnt/sysimage
>08:16:57,697 DEBUG program: Return code: 0
>08:16:57,701 INFO program: Running... udevadm settle --timeout=300
>08:16:57,720 DEBUG program: Return code: 0
>08:16:57,720 INFO program: Running... umount /mnt/sysimage
>08:16:57,742 DEBUG program: Return code: 0
>08:16:57,743 INFO program: Running... udevadm settle --timeout=300
>08:16:57,785 DEBUG program: Return code: 0
>08:16:57,792 INFO program: Running... udevadm settle --timeout=300
>08:16:57,814 DEBUG program: Return code: 0
>08:16:57,826 INFO program: Running... udevadm settle --timeout=300
>08:16:57,847 DEBUG program: Return code: 0
>08:16:57,859 INFO program: Running... udevadm settle --timeout=300
>08:16:57,879 DEBUG program: Return code: 0
>08:16:58,072 INFO program: Running... hwclock --hctosys --utc
>08:16:59,002 DEBUG program: Return code: 0
>08:16:59,342 INFO program: Running... udevadm settle --timeout=300
>08:16:59,359 DEBUG program: Return code: 0
>08:16:59,374 INFO program: Running... udevadm settle --timeout=300
>08:16:59,389 DEBUG program: Return code: 0
>08:16:59,543 INFO program: Running... udevadm settle --timeout=300
>08:16:59,559 DEBUG program: Return code: 0
>08:16:59,571 INFO program: Running... udevadm settle --timeout=300
>08:16:59,588 DEBUG program: Return code: 0
>08:16:59,609 INFO program: Running... udevadm settle --timeout=300
>08:16:59,627 DEBUG program: Return code: 0
>08:16:59,635 INFO program: Running... udevadm settle --timeout=300
>08:16:59,651 DEBUG program: Return code: 0
>08:16:59,827 INFO program: Running... hwclock --systohc --utc
>08:17:00,500 DEBUG program: Return code: 0
>08:17:00,512 INFO program: Running... udevadm settle --timeout=300
>08:17:00,527 DEBUG program: Return code: 0
>08:17:00,530 INFO program: Running... lvm lvchange -a n --config  devices { filter=["r|/sda1$|","r|/vg_storageqe84-lv_home$|","r|/vg_storageqe84-lv_root$|","r|/vg_storageqe84-lv_swap$|","r|/vg_storageqe84$|","r|/sda2$|","r|/sda$|"] }  rhel_storageqe-84/root
>08:17:00,557 INFO program:   Volume group "rhel_storageqe-84" not found
>08:17:00,558 INFO program:   Skipping volume group rhel_storageqe-84
>08:17:00,558 DEBUG program: Return code: 5
>08:17:00,561 INFO program: Running... udevadm settle --timeout=300
>08:17:00,577 DEBUG program: Return code: 0
>08:17:00,580 INFO program: Running... lvm lvchange -a n --config  devices { filter=["r|/sda1$|","r|/vg_storageqe84-lv_home$|","r|/vg_storageqe84-lv_root$|","r|/vg_storageqe84-lv_swap$|","r|/vg_storageqe84$|","r|/sda2$|","r|/sda$|"] }  rhel_storageqe-84/swap
>08:17:00,604 INFO program:   Volume group "rhel_storageqe-84" not found
>08:17:00,604 INFO program:   Skipping volume group rhel_storageqe-84
>08:17:00,605 DEBUG program: Return code: 5
>08:17:00,626 INFO program: Running... wipefs -f -a /dev/mapper/mpatha1
>08:17:00,669 INFO program: /dev/mapper/mpatha1: 4 bytes were erased at offset 0x00000000 (xfs): 58 46 53 42
>08:17:00,669 DEBUG program: Return code: 0
>08:17:00,670 INFO program: Running... udevadm settle --timeout=300
>08:17:00,699 DEBUG program: Return code: 0
>08:17:00,706 INFO program: Running... udevadm settle --timeout=300
>08:17:00,726 DEBUG program: Return code: 0
>08:17:00,727 INFO program: Running... udevadm settle --timeout=300
>08:17:00,746 DEBUG program: Return code: 0
>08:17:00,751 INFO program: Running... lvm pvremove --force --force --yes --config  devices { filter=["r|/sda1$|","r|/vg_storageqe84-lv_home$|","r|/vg_storageqe84-lv_root$|","r|/vg_storageqe84-lv_swap$|","r|/vg_storageqe84$|","r|/sda2$|","r|/sda$|"] }  /dev/mapper/mpatha2
>08:17:00,790 INFO program:   No device found for PV ZEUPfd-BeYf-42PH-HjuM-nXJi-pBtV-IljAEZ.
>08:17:00,791 INFO program:   Internal error: Physical Volume /dev/mapper/mpatha2 has a label, but is neither in a VG nor orphan.
>08:17:00,791 DEBUG program: Return code: 5
>08:17:00,794 INFO program: Running... wipefs -f -a /dev/mapper/mpatha2
>08:17:00,875 INFO program: /dev/mapper/mpatha2: 8 bytes were erased at offset 0x00000218 (LVM2_member): 4c 56 4d 32 20 30 30 31
>08:17:00,875 DEBUG program: Return code: 0
>08:17:00,876 INFO program: Running... lvm pvscan --cache --config  devices { filter=["r|/sda1$|","r|/vg_storageqe84-lv_home$|","r|/vg_storageqe84-lv_root$|","r|/vg_storageqe84-lv_swap$|","r|/vg_storageqe84$|","r|/sda2$|","r|/sda$|"] }  /dev/mapper/mpatha2
>08:17:00,908 INFO program:   No PV label found on /dev/mapper/mpatha2.
>08:17:00,909 DEBUG program: Return code: 0
>08:17:00,912 INFO program: Running... udevadm settle --timeout=300
>08:17:00,933 DEBUG program: Return code: 0
>08:17:00,941 INFO program: Running... udevadm settle --timeout=300
>08:17:00,958 DEBUG program: Return code: 0
>08:17:00,960 INFO program: Running... udevadm settle --timeout=300
>08:17:00,977 DEBUG program: Return code: 0
>08:17:00,985 INFO program: Running... udevadm settle --timeout=300
>08:17:01,000 DEBUG program: Return code: 0
>08:17:01,160 INFO program: Running... udevadm settle --timeout=300
>08:17:01,234 DEBUG program: Return code: 0
>08:17:01,237 INFO program: Running... lvm lvchange -a n --config  devices { filter=["r|/sda1$|","r|/vg_storageqe84-lv_home$|","r|/vg_storageqe84-lv_root$|","r|/vg_storageqe84-lv_swap$|","r|/vg_storageqe84$|","r|/sda2$|","r|/sda$|"] }  rhel_storageqe-84/root
>08:17:01,268 INFO program:   Volume group "rhel_storageqe-84" not found
>08:17:01,268 INFO program:   Skipping volume group rhel_storageqe-84
>08:17:01,269 DEBUG program: Return code: 5
>08:17:01,273 INFO program: Running... udevadm settle --timeout=300
>08:17:01,290 DEBUG program: Return code: 0
>08:17:01,294 INFO program: Running... lvm lvchange -a n --config  devices { filter=["r|/sda1$|","r|/vg_storageqe84-lv_home$|","r|/vg_storageqe84-lv_root$|","r|/vg_storageqe84-lv_swap$|","r|/vg_storageqe84$|","r|/sda2$|","r|/sda$|"] }  rhel_storageqe-84/swap
>08:17:01,324 INFO program:   Volume group "rhel_storageqe-84" not found
>08:17:01,325 INFO program:   Skipping volume group rhel_storageqe-84
>08:17:01,325 DEBUG program: Return code: 5
>08:17:01,333 INFO program: Running... udevadm settle --timeout=300
>08:17:01,350 DEBUG program: Return code: 0

Actions: View

Attachments on bug 1095160: 893159 | 893160 | 893161 | 893162 | 893163 | 893164