Login
Log in using an SSO provider:
Fedora Account System
Red Hat Associate
Red Hat Customer
Login using a Red Hat Bugzilla account
Forgot Password
Create an Account
Red Hat Bugzilla – Attachment 1692572 Details for
Bug 1837840
V2V conversion will hang during converting guest from ESXi5.5 via vddk if nbdkit>=1.16.2-3
Home
New
Search
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh90 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
[?]
This site requires JavaScript to be enabled to function correctly, please enable it.
esx5.5-vddk6.5-hang.log
esx5.5-vddk6.5-hang.log (text/plain), 49.66 KB, created by
mxie@redhat.com
on 2020-05-27 07:01:43 UTC
(
hide
)
Description:
esx5.5-vddk6.5-hang.log
Filename:
MIME Type:
Creator:
mxie@redhat.com
Created:
2020-05-27 07:01:43 UTC
Size:
49.66 KB
patch
obsolete
># virt-v2v -ic vpx://root@10.73.73.148/data/10.73.3.19/?no_verify=1 -it vddk -io vddk-libdir=/home/vmware-vix-disklib-distrib -io vddk-thumbprint=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D esx5.5-rhel6.10-x86_64 -on 5.5-have-vddk -ip /home/passwd -v -x |& ts >May 27 12:57:54 virt-v2v: virt-v2v 1.42.0rhel=8,release=3.module+el8.3.0+6497+b190d2a5 (x86_64) >May 27 12:57:54 libvirt version: 6.3.0 >May 27 12:57:54 [ 0.2] Opening the source -i libvirt -ic vpx://root@10.73.73.148:443/data/10.73.3.19/?no_verify=1 esx5.5-rhel6.10-x86_64 -it vddk -io vddk-libdir=/home/vmware-vix-disklib-distrib -io vddk-thumbprint=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D >May 27 12:57:55 libvirt xml is: >May 27 12:57:55 <domain type='vmware' xmlns:vmware='http://libvirt.org/schemas/domain/vmware/1.0'> >May 27 12:57:55 <name>esx5.5-rhel6.10-x86_64</name> >May 27 12:57:55 <uuid>42379ca1-f0d2-b2af-1bb1-0980f0dcc053</uuid> >May 27 12:57:55 <memory unit='KiB'>2097152</memory> >May 27 12:57:55 <currentMemory unit='KiB'>2097152</currentMemory> >May 27 12:57:55 <vcpu placement='static'>1</vcpu> >May 27 12:57:55 <os> >May 27 12:57:55 <type arch='x86_64'>hvm</type> >May 27 12:57:55 </os> >May 27 12:57:55 <clock offset='utc'/> >May 27 12:57:55 <on_poweroff>destroy</on_poweroff> >May 27 12:57:55 <on_reboot>restart</on_reboot> >May 27 12:57:55 <on_crash>destroy</on_crash> >May 27 12:57:55 <devices> >May 27 12:57:55 <disk type='file' device='disk'> >May 27 12:57:55 <source file='[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk'/> >May 27 12:57:55 <target dev='sda' bus='scsi'/> >May 27 12:57:55 <address type='drive' controller='0' bus='0' target='0' unit='0'/> >May 27 12:57:55 </disk> >May 27 12:57:55 <controller type='scsi' index='0' model='vmpvscsi'/> >May 27 12:57:55 <interface type='bridge'> >May 27 12:57:55 <mac address='00:50:56:b7:dc:41'/> >May 27 12:57:55 <source bridge='VM Network'/> >May 27 12:57:55 <model type='vmxnet3'/> >May 27 12:57:55 </interface> >May 27 12:57:55 <video> >May 27 12:57:55 <model type='vmvga' vram='8192' primary='yes'/> >May 27 12:57:55 </video> >May 27 12:57:55 </devices> >May 27 12:57:55 <vmware:datacenterpath>data</vmware:datacenterpath> >May 27 12:57:55 <vmware:moref>vm-52</vmware:moref> >May 27 12:57:55 </domain> >May 27 12:57:55 >May 27 12:57:55 nbdkit --dump-config >May 27 12:57:55 nbdkit version: 1.20.2 >May 27 12:57:55 nbdkit --dump-config >May 27 12:57:55 nbdkit version: 1.20.2 >May 27 12:57:55 libguestfs: trace: set_verbose true >May 27 12:57:55 libguestfs: trace: set_verbose = 0 >May 27 12:57:55 libguestfs: trace: get_sockdir >May 27 12:57:55 libguestfs: trace: get_sockdir = "/tmp" >May 27 12:57:55 nbdkit --dump-config >May 27 12:57:55 nbdkit version: 1.20.2 >May 27 12:57:55 running nbdkit: >May 27 12:57:55 LANG=C 'nbdkit' '--exit-with-parent' '--foreground' '--newstyle' '--pidfile' '/tmp/v2vnbdkit.lVERoo/nbdkit1.pid' '--unix' '/tmp/v2vnbdkit.lVERoo/nbdkit1.sock' '-D' 'nbdkit.backend.datapath=0' '-D' 'vddk.datapath=0' '--exportname' '/' '--readonly' '--selinux-label' 'system_u:object_r:svirt_socket_t:s0' '--verbose' '--filter' 'cacheextents' '--filter' 'readahead' '--filter' 'retry' 'vddk' 'server=10.73.73.148' 'user=root' 'password=+/home/passwd' 'vm=moref=vm-52' 'file=[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk' 'libdir=/home/vmware-vix-disklib-distrib' 'thumbprint=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D' >May 27 12:57:55 nbdkit: debug: TLS disabled: could not load TLS certificates >May 27 12:57:55 nbdkit: debug: registering plugin /usr/lib64/nbdkit/plugins/nbdkit-vddk-plugin.so >May 27 12:57:55 nbdkit: debug: registered plugin /usr/lib64/nbdkit/plugins/nbdkit-vddk-plugin.so (name vddk) >May 27 12:57:55 nbdkit: debug: vddk: load >May 27 12:57:55 nbdkit: debug: registering filter /usr/lib64/nbdkit/filters/nbdkit-retry-filter.so >May 27 12:57:55 nbdkit: debug: registered filter /usr/lib64/nbdkit/filters/nbdkit-retry-filter.so (name retry) >May 27 12:57:55 nbdkit: debug: retry: load >May 27 12:57:55 nbdkit: debug: registering filter /usr/lib64/nbdkit/filters/nbdkit-readahead-filter.so >May 27 12:57:55 nbdkit: debug: registered filter /usr/lib64/nbdkit/filters/nbdkit-readahead-filter.so (name readahead) >May 27 12:57:55 nbdkit: debug: readahead: load >May 27 12:57:55 nbdkit: debug: registering filter /usr/lib64/nbdkit/filters/nbdkit-cacheextents-filter.so >May 27 12:57:55 nbdkit: debug: registered filter /usr/lib64/nbdkit/filters/nbdkit-cacheextents-filter.so (name cacheextents) >May 27 12:57:55 nbdkit: debug: cacheextents: load >May 27 12:57:55 nbdkit: debug: cacheextents: config key=server, value=10.73.73.148 >May 27 12:57:55 nbdkit: debug: readahead: config key=server, value=10.73.73.148 >May 27 12:57:55 nbdkit: debug: retry: config key=server, value=10.73.73.148 >May 27 12:57:55 nbdkit: debug: vddk: config key=server, value=10.73.73.148 >May 27 12:57:55 nbdkit: debug: cacheextents: config key=user, value=root >May 27 12:57:55 nbdkit: debug: readahead: config key=user, value=root >May 27 12:57:55 nbdkit: debug: retry: config key=user, value=root >May 27 12:57:55 nbdkit: debug: vddk: config key=user, value=root >May 27 12:57:55 nbdkit: debug: cacheextents: config key=password, value=+/home/passwd >May 27 12:57:55 nbdkit: debug: readahead: config key=password, value=+/home/passwd >May 27 12:57:55 nbdkit: debug: retry: config key=password, value=+/home/passwd >May 27 12:57:55 nbdkit: debug: vddk: config key=password, value=+/home/passwd >May 27 12:57:55 nbdkit: debug: cacheextents: config key=vm, value=moref=vm-52 >May 27 12:57:55 nbdkit: debug: readahead: config key=vm, value=moref=vm-52 >May 27 12:57:55 nbdkit: debug: retry: config key=vm, value=moref=vm-52 >May 27 12:57:55 nbdkit: debug: vddk: config key=vm, value=moref=vm-52 >May 27 12:57:55 nbdkit: debug: cacheextents: config key=file, value=[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk >May 27 12:57:55 nbdkit: debug: readahead: config key=file, value=[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk >May 27 12:57:55 nbdkit: debug: retry: config key=file, value=[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk >May 27 12:57:55 nbdkit: debug: vddk: config key=file, value=[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk >May 27 12:57:55 nbdkit: debug: cacheextents: config key=libdir, value=/home/vmware-vix-disklib-distrib >May 27 12:57:55 nbdkit: debug: readahead: config key=libdir, value=/home/vmware-vix-disklib-distrib >May 27 12:57:55 nbdkit: debug: retry: config key=libdir, value=/home/vmware-vix-disklib-distrib >May 27 12:57:55 nbdkit: debug: vddk: config key=libdir, value=/home/vmware-vix-disklib-distrib >May 27 12:57:55 nbdkit: debug: cacheextents: config key=thumbprint, value=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D >May 27 12:57:55 nbdkit: debug: readahead: config key=thumbprint, value=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D >May 27 12:57:55 nbdkit: debug: retry: config key=thumbprint, value=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D >May 27 12:57:55 nbdkit: debug: vddk: config key=thumbprint, value=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D >May 27 12:57:55 nbdkit: debug: cacheextents: config_complete >May 27 12:57:55 nbdkit: debug: readahead: config_complete >May 27 12:57:55 nbdkit: debug: retry: config_complete >May 27 12:57:55 nbdkit: debug: vddk: config_complete >May 27 12:57:55 nbdkit: debug: using thread model: serialize_all_requests >May 27 12:57:55 nbdkit: debug: cacheextents: get_ready >May 27 12:57:55 nbdkit: debug: readahead: get_ready >May 27 12:57:55 nbdkit: debug: retry: get_ready >May 27 12:57:55 nbdkit: debug: vddk: get_ready >May 27 12:57:55 nbdkit: debug: original command line occupies 584 bytes >May 27 12:57:55 nbdkit: debug: adding reexeced_= >May 27 12:57:55 nbdkit: debug: re-executing with updated LD_LIBRARY_PATH=/home/vmware-vix-disklib-distrib/lib64 >May 27 12:57:55 nbdkit: debug: TLS disabled: could not load TLS certificates >May 27 12:57:55 nbdkit: debug: registering plugin /usr/lib64/nbdkit/plugins/nbdkit-vddk-plugin.so >May 27 12:57:55 nbdkit: debug: registered plugin /usr/lib64/nbdkit/plugins/nbdkit-vddk-plugin.so (name vddk) >May 27 12:57:55 nbdkit: debug: vddk: load >May 27 12:57:55 nbdkit: debug: registering filter /usr/lib64/nbdkit/filters/nbdkit-retry-filter.so >May 27 12:57:55 nbdkit: debug: registered filter /usr/lib64/nbdkit/filters/nbdkit-retry-filter.so (name retry) >May 27 12:57:55 nbdkit: debug: retry: load >May 27 12:57:55 nbdkit: debug: registering filter /usr/lib64/nbdkit/filters/nbdkit-readahead-filter.so >May 27 12:57:55 nbdkit: debug: registered filter /usr/lib64/nbdkit/filters/nbdkit-readahead-filter.so (name readahead) >May 27 12:57:55 nbdkit: debug: readahead: load >May 27 12:57:55 nbdkit: debug: registering filter /usr/lib64/nbdkit/filters/nbdkit-cacheextents-filter.so >May 27 12:57:55 nbdkit: debug: registered filter /usr/lib64/nbdkit/filters/nbdkit-cacheextents-filter.so (name cacheextents) >May 27 12:57:55 nbdkit: debug: cacheextents: load >May 27 12:57:55 nbdkit: debug: cacheextents: config key=server, value=10.73.73.148 >May 27 12:57:55 nbdkit: debug: readahead: config key=server, value=10.73.73.148 >May 27 12:57:55 nbdkit: debug: retry: config key=server, value=10.73.73.148 >May 27 12:57:55 nbdkit: debug: vddk: config key=server, value=10.73.73.148 >May 27 12:57:55 nbdkit: debug: cacheextents: config key=user, value=root >May 27 12:57:55 nbdkit: debug: readahead: config key=user, value=root >May 27 12:57:55 nbdkit: debug: retry: config key=user, value=root >May 27 12:57:55 nbdkit: debug: vddk: config key=user, value=root >May 27 12:57:55 nbdkit: debug: cacheextents: config key=password, value=+/home/passwd >May 27 12:57:55 nbdkit: debug: readahead: config key=password, value=+/home/passwd >May 27 12:57:55 nbdkit: debug: retry: config key=password, value=+/home/passwd >May 27 12:57:55 nbdkit: debug: vddk: config key=password, value=+/home/passwd >May 27 12:57:55 nbdkit: debug: cacheextents: config key=vm, value=moref=vm-52 >May 27 12:57:55 nbdkit: debug: readahead: config key=vm, value=moref=vm-52 >May 27 12:57:55 nbdkit: debug: retry: config key=vm, value=moref=vm-52 >May 27 12:57:55 nbdkit: debug: vddk: config key=vm, value=moref=vm-52 >May 27 12:57:55 nbdkit: debug: cacheextents: config key=file, value=[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk >May 27 12:57:55 nbdkit: debug: readahead: config key=file, value=[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk >May 27 12:57:55 nbdkit: debug: retry: config key=file, value=[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk >May 27 12:57:55 nbdkit: debug: vddk: config key=file, value=[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk >May 27 12:57:55 nbdkit: debug: cacheextents: config key=libdir, value=/home/vmware-vix-disklib-distrib >May 27 12:57:55 nbdkit: debug: readahead: config key=libdir, value=/home/vmware-vix-disklib-distrib >May 27 12:57:55 nbdkit: debug: retry: config key=libdir, value=/home/vmware-vix-disklib-distrib >May 27 12:57:55 nbdkit: debug: vddk: config key=libdir, value=/home/vmware-vix-disklib-distrib >May 27 12:57:55 nbdkit: debug: cacheextents: config key=thumbprint, value=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D >May 27 12:57:55 nbdkit: debug: readahead: config key=thumbprint, value=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D >May 27 12:57:55 nbdkit: debug: retry: config key=thumbprint, value=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D >May 27 12:57:55 nbdkit: debug: vddk: config key=thumbprint, value=AA:F5:4C:48:C9:BF:75:1A:94:41:61:4C:D5:EC:DF:46:48:B5:9B:4D >May 27 12:57:55 nbdkit: debug: cacheextents: config key=reexeced_, value= >May 27 12:57:55 nbdkit: debug: readahead: config key=reexeced_, value= >May 27 12:57:55 nbdkit: debug: retry: config key=reexeced_, value= >May 27 12:57:55 nbdkit: debug: vddk: config key=reexeced_, value= >May 27 12:57:55 nbdkit: debug: cacheextents: config_complete >May 27 12:57:55 nbdkit: debug: readahead: config_complete >May 27 12:57:55 nbdkit: debug: retry: config_complete >May 27 12:57:55 nbdkit: debug: vddk: config_complete >May 27 12:57:55 nbdkit: debug: cleaning up after re-exec >May 27 12:57:55 nbdkit: debug: using thread model: serialize_all_requests >May 27 12:57:55 nbdkit: debug: cacheextents: get_ready >May 27 12:57:55 nbdkit: debug: readahead: get_ready >May 27 12:57:55 nbdkit: debug: retry: get_ready >May 27 12:57:55 nbdkit: debug: vddk: get_ready >May 27 12:57:55 nbdkit: debug: VDDK call: VixDiskLib_InitEx (5, 5, &debug_fn, &error_fn, &error_fn, /home/vmware-vix-disklib-distrib, NULL) >May 27 12:57:55 nbdkit: debug: OBJLIB-LIB: Objlib initialized. >May 27 12:57:55 nbdkit: debug: VixDiskLib: Attempting to locate advanced transport module in "/home/vmware-vix-disklib-distrib". >May 27 12:57:55 nbdkit: debug: VixDiskLib: Advanced transport plugin /home/vmware-vix-disklib-distrib/lib64/libdiskLibPlugin.so was successfully loaded into vixDiskLib. >May 27 12:57:55 nbdkit: debug: VixDiskLib: Enabling advanced transport modes. >May 27 12:57:55 nbdkit: debug: VixDiskLib: Initialize transport modes. >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.432+08:00 info -[7FCECB493700] [Originator@6876 sub=ThreadPool] Thread enlisted >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.432+08:00 info -[7FCECB598A40] [Originator@6876 sub=vm:DictionaryLoad: Cannot open file "/etc/vmware/vsphereFeatures/vsphereFeatures.cfg] : No such file or directory. >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.433+08:00 info -[7FCECB598A40] [Originator@6876 sub=vm:[msg.dictionary.load.openFailed] Cannot open file "/etc/vmware/vsphereFeatures/vsphereFeatures.cfg] : No such file or directory. >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.433+08:00 info -[7FCECB598A40] [Originator@6876 sub=vm:FeatureStateLib: Error while loading feature config file: /etc/vmware/vsphereFeatures/vsphereFeatures.cfg] using default feature state values. >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.432+08:00 info -[7FCECB411700] [Originator@6876 sub=ThreadPool] Thread enlisted >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.433+08:00 info -[7FCECB411700] [Originator@6876 sub=ThreadPool] Entering fair thread loop >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.432+08:00 info -[7FCECB493700] [Originator@6876 sub=ThreadPool] Entering IO thread loop >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.432+08:00 info -[7FCECB4D4700] [Originator@6876 sub=ThreadPool] Thread enlisted >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.433+08:00 info -[7FCECB4D4700] [Originator@6876 sub=ThreadPool] Entering worker thread loop >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.432+08:00 info -[7FCECB452700] [Originator@6876 sub=ThreadPool] Thread enlisted >May 27 12:57:55 nbdkit: debug: 2020-05-27T12:57:55.433+08:00 info -[7FCECB452700] [Originator@6876 sub=ThreadPool] Entering IO thread loop >May 27 12:57:55 nbdkit: debug: VixDiskLib: Available transport modes: file:san:hotadd:nbdssl:nbd. >May 27 12:57:55 nbdkit: debug: VMware VixDiskLib (6.5) Release build-6195444 >May 27 12:57:55 nbdkit: debug: DictionaryLoad: Cannot open file "/etc/vmware/config": No such file or directory. >May 27 12:57:55 nbdkit: debug: [msg.dictionary.load.openFailed] Cannot open file "/etc/vmware/config": No such file or directory. >May 27 12:57:55 nbdkit: debug: PREF Optional preferences file not found at /etc/vmware/config. Using default values. >May 27 12:57:55 nbdkit: debug: DictionaryLoad: Cannot open file "/usr/lib/vmware/settings": No such file or directory. >May 27 12:57:55 nbdkit: debug: [msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/settings": No such file or directory. >May 27 12:57:55 nbdkit: debug: PREF Optional preferences file not found at /usr/lib/vmware/settings. Using default values. >May 27 12:57:55 nbdkit: debug: DictionaryLoad: Cannot open file "/usr/lib/vmware/config": No such file or directory. >May 27 12:57:55 nbdkit: debug: [msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/config": No such file or directory. >May 27 12:57:55 nbdkit: debug: PREF Optional preferences file not found at /usr/lib/vmware/config. Using default values. >May 27 12:57:55 nbdkit: debug: DictionaryLoad: Cannot open file "/root/.vmware/config": No such file or directory. >May 27 12:57:55 nbdkit: debug: [msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/config": No such file or directory. >May 27 12:57:55 nbdkit: debug: PREF Optional preferences file not found at /root/.vmware/config. Using default values. >May 27 12:57:55 nbdkit: debug: DictionaryLoad: Cannot open file "/root/.vmware/preferences": No such file or directory. >May 27 12:57:55 nbdkit: debug: [msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/preferences": No such file or directory. >May 27 12:57:55 nbdkit: debug: PREF Optional preferences file not found at /root/.vmware/preferences. Using default values. >May 27 12:57:55 nbdkit: debug: lib/ssl: OpenSSL using FIPS_drbg for RAND >May 27 12:57:55 nbdkit: debug: lib/ssl: protocol list tls1.2 >May 27 12:57:55 nbdkit: debug: lib/ssl: protocol list tls1.2 (openssl flags 0x17000000) >May 27 12:57:55 nbdkit: debug: lib/ssl: cipher list !aNULL:kECDH+AESGCM:ECDH+AESGCM:RSA+AESGCM:kECDH+AES:ECDH+AES:RSA+AES >May 27 12:57:55 nbdkit: debug: VixDiskLibVim: VixDiskLibVim_Init: Initialization is completed. >May 27 12:57:55 nbdkit: debug: bound to unix socket /tmp/v2vnbdkit.lVERoo/nbdkit1.sock >May 27 12:57:55 nbdkit: debug: written pidfile /tmp/v2vnbdkit.lVERoo/nbdkit1.pid >May 27 12:57:56 chcon 'system_u:object_r:svirt_image_t:s0' '/tmp/v2vnbdkit.lVERoo/nbdkit1.sock' >May 27 12:57:56 source name: esx5.5-rhel6.10-x86_64 >May 27 12:57:56 hypervisor type: vmware >May 27 12:57:56 VM genid: >May 27 12:57:56 memory: 2147483648 (bytes) >May 27 12:57:56 nr vCPUs: 1 >May 27 12:57:56 CPU vendor: >May 27 12:57:56 CPU model: >May 27 12:57:56 CPU topology: >May 27 12:57:56 CPU features: >May 27 12:57:56 firmware: unknown >May 27 12:57:56 display: >May 27 12:57:56 video: vmvga >May 27 12:57:56 sound: >May 27 12:57:56 disks: >May 27 12:57:56 nbd:unix:/tmp/v2vnbdkit.lVERoo/nbdkit1.sock:exportname=/ (raw) [scsi] >May 27 12:57:56 removable media: >May 27 12:57:56 >May 27 12:57:56 NICs: >May 27 12:57:56 Bridge "VM Network" mac: 00:50:56:b7:dc:41 [vmxnet3] >May 27 12:57:56 >May 27 12:57:56 check_host_free_space: overlay_dir=/var/tmp free_space=725197942784 >May 27 12:57:56 [ 1.7] Creating an overlay to protect the source from being modified >May 27 12:57:56 qemu-img 'create' '-q' '-f' 'qcow2' '-b' 'nbd:unix:/tmp/v2vnbdkit.lVERoo/nbdkit1.sock:exportname=/' '-o' 'compat=1.1,backing_fmt=raw' '/var/tmp/v2vovla85659.qcow2' >May 27 12:57:56 nbdkit: debug: accepted connection >May 27 12:57:56 nbdkit: vddk[1]: debug: cacheextents: preconnect >May 27 12:57:56 nbdkit: vddk[1]: debug: readahead: preconnect >May 27 12:57:56 nbdkit: vddk[1]: debug: retry: preconnect >May 27 12:57:56 nbdkit: vddk[1]: debug: vddk: preconnect >May 27 12:57:56 nbdkit: vddk[1]: debug: newstyle negotiation: flags: global 0x3 >May 27 12:57:56 nbdkit: vddk[1]: debug: newstyle negotiation: client flags: 0x3 >May 27 12:57:56 nbdkit: vddk[1]: debug: newstyle negotiation: NBD_OPT_STRUCTURED_REPLY: client requested structured replies >May 27 12:57:56 nbdkit: vddk[1]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: client requested export '/' >May 27 12:57:56 nbdkit: vddk[1]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: set count: 1 >May 27 12:57:56 nbdkit: vddk[1]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: set base:allocation >May 27 12:57:56 nbdkit: vddk[1]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: replying with base:allocation id 1 >May 27 12:57:56 nbdkit: vddk[1]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: reply complete >May 27 12:57:56 nbdkit: vddk[1]: debug: newstyle negotiation: NBD_OPT_GO: client requested export '/' >May 27 12:57:56 nbdkit: vddk[1]: debug: cacheextents: open readonly=1 >May 27 12:57:56 nbdkit: vddk[1]: debug: readahead: open readonly=1 >May 27 12:57:56 nbdkit: vddk[1]: debug: retry: open readonly=1 >May 27 12:57:56 nbdkit: vddk[1]: debug: vddk: open readonly=1 >May 27 12:57:56 nbdkit: vddk[1]: debug: VDDK call: VixDiskLib_ConnectEx (h->params, 1, NULL, NULL, &connection) >May 27 12:57:56 nbdkit: vddk[1]: debug: VixDiskLib: VixDiskLib_ConnectEx: Establish connection using (null). >May 27 12:57:56 nbdkit: vddk[1]: debug: VixDiskLib: VixDiskLib_Connect: Establish connection. >May 27 12:57:56 nbdkit: vddk[1]: debug: VixDiskLib: Resolve host. >May 27 12:57:56 nbdkit: vddk[1]: debug: 2020-05-27T12:57:56.377+08:00 error -[7FCEB3FFF700] [Originator@6876 sub=Default] Cannot use advanced transport modes for 10.73.73.148/moref=vm-52/: Cannot create directory /tmp/vmware-root/42379ca1-f0d2-b2af-1bb1-0980f0dcc053-vm-52. >May 27 12:57:56 nbdkit: vddk[1]: debug: VixDiskLib: VixDiskLib_ConnectEx: Advanced transport modes not available for opening moref=vm-52. (Cannot use advanced transport modes for 10.73.73.148/moref=vm-52/: Cannot create directory /tmp/vmware-root/42379ca1-f0d2-b2af-1bb1-0980f0dcc053-vm-52.) >May 27 12:57:56 nbdkit: vddk[1]: debug: VDDK call: VixDiskLib_Open (connection, [ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk, 4, &handle) >May 27 12:57:56 nbdkit: vddk[1]: debug: VixDiskLib: VixDiskLib_OpenEx: Open a disk. >May 27 12:57:56 nbdkit: vddk[1]: debug: VixDiskLibVim: VixDiskLibVim_GetNfcTicket: Get NFC ticket for [ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk. >May 27 12:57:56 nbdkit: vddk[1]: debug: DictionaryLoad: Cannot open file "/etc/vmware/config": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: [msg.dictionary.load.openFailed] Cannot open file "/etc/vmware/config": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: PREF Optional preferences file not found at /etc/vmware/config. Using default values. >May 27 12:57:56 nbdkit: vddk[1]: debug: DictionaryLoad: Cannot open file "/usr/lib/vmware/settings": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: [msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/settings": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: PREF Optional preferences file not found at /usr/lib/vmware/settings. Using default values. >May 27 12:57:56 nbdkit: vddk[1]: debug: DictionaryLoad: Cannot open file "/usr/lib/vmware/config": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: [msg.dictionary.load.openFailed] Cannot open file "/usr/lib/vmware/config": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: PREF Optional preferences file not found at /usr/lib/vmware/config. Using default values. >May 27 12:57:56 nbdkit: vddk[1]: debug: DictionaryLoad: Cannot open file "/root/.vmware/config": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: [msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/config": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: PREF Optional preferences file not found at /root/.vmware/config. Using default values. >May 27 12:57:56 nbdkit: vddk[1]: debug: DictionaryLoad: Cannot open file "/root/.vmware/preferences": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: [msg.dictionary.load.openFailed] Cannot open file "/root/.vmware/preferences": No such file or directory. >May 27 12:57:56 nbdkit: vddk[1]: debug: PREF Optional preferences file not found at /root/.vmware/preferences. Using default values. >May 27 12:57:56 nbdkit: vddk[1]: debug: lib/ssl: OpenSSL using FIPS_drbg for RAND >May 27 12:57:56 nbdkit: vddk[1]: debug: lib/ssl: protocol list tls1.2 >May 27 12:57:56 nbdkit: vddk[1]: debug: lib/ssl: protocol list tls1.2 (openssl flags 0x17000000) >May 27 12:57:56 nbdkit: vddk[1]: debug: lib/ssl: cipher list !aNULL:kECDH+AESGCM:ECDH+AESGCM:RSA+AESGCM:kECDH+AES:ECDH+AES:RSA+AES >May 27 12:57:56 nbdkit: vddk[1]: debug: VixDiskLibVim: Request RandomAccessRO diskKey = 2000, readOnly = 1, openSnapshot = 0. >May 27 12:57:57 nbdkit: vddk[1]: debug: VixDiskLibVim: VixDiskLibVim_FreeNfcTicket: Free NFC ticket. >May 27 12:57:57 nbdkit: vddk[1]: debug: NBD_ClientOpen: attempting to create connection to vpxa-nfc://[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk@10.73.3.19:902 >May 27 12:57:57 nbdkit: vddk[1]: debug: CnxAuthdProtoSecureConnect: Target authd does not support BANNER (530). >May 27 12:57:57 nbdkit: vddk[1]: debug: DictionaryLoad: Cannot open file "/etc/vmware/vsphereFeatures/vsphereFeatures.cfg": No such file or directory. >May 27 12:57:57 nbdkit: vddk[1]: debug: [msg.dictionary.load.openFailed] Cannot open file "/etc/vmware/vsphereFeatures/vsphereFeatures.cfg": No such file or directory. >May 27 12:57:57 nbdkit: vddk[1]: debug: FeatureStateLib: Error while loading feature config file: /etc/vmware/vsphereFeatures/vsphereFeatures.cfg, using default feature state values. >May 27 12:57:57 nbdkit: vddk[1]: debug: Opening file [ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk (vpxa-nfc://[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk@10.73.3.19:902) >May 27 12:57:57 nbdkit: vddk[1]: debug: DISKLIB-LINK : Opened 'vpxa-nfc://[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk@10.73.3.19:902' (0x1e): custom, 33554432 sectors / 16 GB. >May 27 12:57:58 nbdkit: vddk[1]: debug: DISKLIB-LIB : Opened "vpxa-nfc://[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk@10.73.3.19:902" (flags 0x1e, type custom). >May 27 12:57:58 nbdkit: vddk[1]: debug: VixDiskLib: VixDiskLib_GetTransportMode: Retrieve transport mode. >May 27 12:57:58 nbdkit: vddk[1]: debug: transport mode: nbd >May 27 12:57:58 nbdkit: vddk[1]: debug: vddk: open returned handle 0x7fcea0001e30 >May 27 12:57:58 nbdkit: vddk[1]: debug: retry: open returned handle 0x7fcea0490640 >May 27 12:57:58 nbdkit: vddk[1]: debug: readahead: open returned handle 0x7fceb3fff580 >May 27 12:57:58 nbdkit: vddk[1]: debug: cacheextents: open returned handle 0x7fceb3fff580 >May 27 12:57:58 nbdkit: vddk[1]: debug: vddk: prepare readonly=1 >May 27 12:57:58 nbdkit: vddk[1]: debug: retry: prepare readonly=1 >May 27 12:57:58 nbdkit: vddk[1]: debug: readahead: prepare readonly=1 >May 27 12:57:58 nbdkit: vddk[1]: debug: retry: get_size >May 27 12:57:58 nbdkit: vddk[1]: debug: vddk: get_size >May 27 12:57:58 nbdkit: vddk[1]: debug: VDDK call: VixDiskLib_GetInfo (handle, &info) >May 27 12:57:58 nbdkit: vddk[1]: debug: VixDiskLib: VixDiskLib_GetInfo: Retrieve disk info. >May 27 12:57:59 nbdkit: vddk[1]: debug: VDDK call: VixDiskLib_FreeInfo (info) >May 27 12:57:59 nbdkit: vddk[1]: debug: VixDiskLib: VixDiskLib_FreeInfo: Clean up VixDiskLib. >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: prepare readonly=1 >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: get_size >May 27 12:57:59 nbdkit: vddk[1]: debug: readahead: get_size >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: can_zero >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: can_fast_zero >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: can_trim >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: can_fua >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: can_flush >May 27 12:57:59 nbdkit: vddk[1]: debug: readahead: can_flush >May 27 12:57:59 nbdkit: vddk[1]: debug: retry: can_flush >May 27 12:57:59 nbdkit: vddk[1]: debug: vddk: can_flush >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: is_rotational >May 27 12:57:59 nbdkit: vddk[1]: debug: readahead: is_rotational >May 27 12:57:59 nbdkit: vddk[1]: debug: retry: is_rotational >May 27 12:57:59 nbdkit: vddk[1]: debug: vddk: is_rotational >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: can_multi_conn >May 27 12:57:59 nbdkit: vddk[1]: debug: readahead: can_multi_conn >May 27 12:57:59 nbdkit: vddk[1]: debug: retry: can_multi_conn >May 27 12:57:59 nbdkit: vddk[1]: debug: vddk: can_multi_conn >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: can_cache >May 27 12:57:59 nbdkit: vddk[1]: debug: readahead: can_cache >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: can_extents >May 27 12:57:59 nbdkit: vddk[1]: debug: readahead: can_extents >May 27 12:57:59 nbdkit: vddk[1]: debug: retry: can_extents >May 27 12:57:59 nbdkit: vddk[1]: debug: vddk: can_extents >May 27 12:57:59 nbdkit: vddk[1]: debug: can_extents: VixDiskLib_QueryAllocatedBlocks == NULL, probably this is VDDK < 6.7 >May 27 12:57:59 nbdkit: vddk[1]: debug: newstyle negotiation: flags: export 0x487 >May 27 12:57:59 nbdkit: vddk[1]: debug: newstyle negotiation: NBD_OPT_GO: ignoring NBD_INFO_* request 3 (NBD_INFO_BLOCK_SIZE) >May 27 12:57:59 nbdkit: vddk[1]: debug: handshake complete, processing requests serially >May 27 12:57:59 nbdkit: vddk[1]: debug: client sent NBD_CMD_DISC, closing connection >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: finalize >May 27 12:57:59 nbdkit: vddk[1]: debug: readahead: finalize >May 27 12:57:59 nbdkit: vddk[1]: debug: retry: finalize >May 27 12:57:59 nbdkit: vddk[1]: debug: vddk: finalize >May 27 12:57:59 nbdkit: vddk[1]: debug: cacheextents: close >May 27 12:57:59 nbdkit: vddk[1]: debug: readahead: close >May 27 12:57:59 nbdkit: vddk[1]: debug: retry: close >May 27 12:57:59 nbdkit: vddk[1]: debug: reopens needed: 0 >May 27 12:57:59 nbdkit: vddk[1]: debug: vddk: close >May 27 12:57:59 nbdkit: vddk[1]: debug: VDDK call: VixDiskLib_Close (handle) >May 27 12:57:59 nbdkit: vddk[1]: debug: VixDiskLib: VixDiskLib_Close: Close disk. >May 27 12:57:59 nbdkit: vddk[1]: debug: VDDK call: VixDiskLib_Disconnect (connection) >May 27 12:57:59 nbdkit: vddk[1]: debug: VixDiskLib: VixDiskLib_Disconnect: Disconnect. >May 27 12:57:59 libguestfs: trace: set_verbose true >May 27 12:57:59 libguestfs: trace: set_verbose = 0 >May 27 12:57:59 libguestfs: trace: disk_has_backing_file "/var/tmp/v2vovla85659.qcow2" >May 27 12:57:59 libguestfs: command: run: qemu-img --help | grep -sqE -- '\binfo\b.*-U\b' >May 27 12:57:59 libguestfs: command: run: qemu-img >May 27 12:57:59 libguestfs: command: run: \ info >May 27 12:57:59 libguestfs: command: run: \ -U >May 27 12:57:59 libguestfs: command: run: \ --output json >May 27 12:57:59 libguestfs: command: run: \ /var/tmp/v2vovla85659.qcow2 >May 27 12:57:59 libguestfs: parse_json: qemu-img info JSON output:\n{\n "backing-filename-format": "raw",\n "virtual-size": 17179869184,\n "filename": "/var/tmp/v2vovla85659.qcow2",\n "cluster-size": 65536,\n "format": "qcow2",\n "actual-size": 200704,\n "format-specific": {\n "type": "qcow2",\n "data": {\n "compat": "1.1",\n "lazy-refcounts": false,\n "refcount-bits": 16,\n "corrupt": false\n }\n },\n "full-backing-filename": "nbd:unix:/tmp/v2vnbdkit.lVERoo/nbdkit1.sock:exportname=/",\n "backing-filename": "nbd:unix:/tmp/v2vnbdkit.lVERoo/nbdkit1.sock:exportname=/",\n "dirty-flag": false\n}\n\n >May 27 12:57:59 libguestfs: trace: disk_has_backing_file = 1 >May 27 12:57:59 libguestfs: trace: set_verbose true >May 27 12:57:59 libguestfs: trace: set_verbose = 0 >May 27 12:57:59 libguestfs: trace: disk_virtual_size "/var/tmp/v2vovla85659.qcow2" >May 27 12:57:59 libguestfs: command: run: qemu-img --help | grep -sqE -- '\binfo\b.*-U\b' >May 27 12:57:59 libguestfs: command: run: qemu-img >May 27 12:57:59 libguestfs: command: run: \ info >May 27 12:57:59 libguestfs: command: run: \ -U >May 27 12:57:59 libguestfs: command: run: \ --output json >May 27 12:57:59 libguestfs: command: run: \ /var/tmp/v2vovla85659.qcow2 >May 27 12:57:59 libguestfs: parse_json: qemu-img info JSON output:\n{\n "backing-filename-format": "raw",\n "virtual-size": 17179869184,\n "filename": "/var/tmp/v2vovla85659.qcow2",\n "cluster-size": 65536,\n "format": "qcow2",\n "actual-size": 200704,\n "format-specific": {\n "type": "qcow2",\n "data": {\n "compat": "1.1",\n "lazy-refcounts": false,\n "refcount-bits": 16,\n "corrupt": false\n }\n },\n "full-backing-filename": "nbd:unix:/tmp/v2vnbdkit.lVERoo/nbdkit1.sock:exportname=/",\n "backing-filename": "nbd:unix:/tmp/v2vnbdkit.lVERoo/nbdkit1.sock:exportname=/",\n "dirty-flag": false\n}\n\n >May 27 12:57:59 libguestfs: trace: disk_virtual_size = 17179869184 >May 27 12:57:59 [ 5.0] Opening the overlay >May 27 12:57:59 libguestfs: trace: set_verbose true >May 27 12:57:59 libguestfs: trace: set_verbose = 0 >May 27 12:57:59 libguestfs: trace: set_identifier "v2v" >May 27 12:57:59 libguestfs: trace: v2v: set_identifier = 0 >May 27 12:57:59 libguestfs: trace: v2v: get_memsize >May 27 12:57:59 libguestfs: trace: v2v: get_memsize = 768 >May 27 12:57:59 libguestfs: trace: v2v: set_memsize 2150 >May 27 12:57:59 libguestfs: trace: v2v: set_memsize = 0 >May 27 12:57:59 libguestfs: trace: v2v: set_network true >May 27 12:57:59 libguestfs: trace: v2v: set_network = 0 >May 27 12:57:59 libguestfs: trace: v2v: add_drive "/var/tmp/v2vovla85659.qcow2" "format:qcow2" "cachemode:unsafe" "discard:besteffort" "copyonread:true" >May 27 12:57:59 libguestfs: trace: v2v: add_drive = 0 >May 27 12:57:59 libguestfs: trace: v2v: launch >May 27 12:57:59 libguestfs: trace: v2v: max_disks >May 27 12:57:59 libguestfs: trace: v2v: max_disks = 255 >May 27 12:57:59 libguestfs: trace: v2v: get_tmpdir >May 27 12:57:59 libguestfs: trace: v2v: get_tmpdir = "/tmp" >May 27 12:57:59 libguestfs: trace: v2v: version >May 27 12:57:59 libguestfs: trace: v2v: version = <struct guestfs_version = major: 1, minor: 42, release: 0, extra: rhel=8,release=1.module+el8.3.0+6496+d39ac712,libvirt, > >May 27 12:57:59 libguestfs: trace: v2v: get_backend >May 27 12:57:59 libguestfs: trace: v2v: get_backend = "libvirt" >May 27 12:57:59 libguestfs: launch: program=virt-v2v >May 27 12:57:59 libguestfs: launch: identifier=v2v >May 27 12:57:59 libguestfs: launch: version=1.42.0rhel=8,release=1.module+el8.3.0+6496+d39ac712,libvirt >May 27 12:57:59 libguestfs: launch: backend registered: unix >May 27 12:57:59 libguestfs: launch: backend registered: uml >May 27 12:57:59 libguestfs: launch: backend registered: libvirt >May 27 12:57:59 libguestfs: launch: backend registered: direct >May 27 12:57:59 libguestfs: launch: backend=libvirt >May 27 12:57:59 libguestfs: launch: tmpdir=/tmp/libguestfswUGQzV >May 27 12:57:59 libguestfs: launch: umask=0022 >May 27 12:57:59 libguestfs: launch: euid=0 >May 27 12:57:59 libguestfs: libvirt version = 6003000 (6.3.0) >May 27 12:57:59 libguestfs: guest random name = guestfs-0q46q6wiufuj5ukj >May 27 12:57:59 libguestfs: connect to libvirt >May 27 12:57:59 libguestfs: opening libvirt handle: URI = qemu:///system, auth = default+wrapper, flags = 0 >May 27 12:57:59 libguestfs: successfully opened libvirt handle: conn = 0x5637bffb7f40 >May 27 12:57:59 libguestfs: qemu version (reported by libvirt) = 5000000 (5.0.0) >May 27 12:57:59 libguestfs: get libvirt capabilities >May 27 12:57:59 libguestfs: parsing capabilities XML >May 27 12:57:59 libguestfs: trace: v2v: get_backend_setting "force_tcg" >May 27 12:57:59 libguestfs: trace: v2v: get_backend_setting = NULL (error) >May 27 12:57:59 libguestfs: parsing domcapabilities XML >May 27 12:57:59 libguestfs: trace: v2v: get_backend_setting "internal_libvirt_label" >May 27 12:57:59 libguestfs: trace: v2v: get_backend_setting = NULL (error) >May 27 12:57:59 libguestfs: trace: v2v: get_backend_setting "internal_libvirt_imagelabel" >May 27 12:57:59 libguestfs: trace: v2v: get_backend_setting = NULL (error) >May 27 12:57:59 libguestfs: trace: v2v: get_backend_setting "internal_libvirt_norelabel_disks" >May 27 12:57:59 libguestfs: trace: v2v: get_backend_setting = NULL (error) >May 27 12:57:59 libguestfs: build appliance >May 27 12:57:59 libguestfs: trace: v2v: get_cachedir >May 27 12:57:59 libguestfs: trace: v2v: get_cachedir = "/var/tmp" >May 27 12:57:59 libguestfs: begin building supermin appliance >May 27 12:57:59 libguestfs: run supermin >May 27 12:57:59 libguestfs: command: run: /usr/bin/supermin >May 27 12:57:59 libguestfs: command: run: \ --build >May 27 12:57:59 libguestfs: command: run: \ --verbose >May 27 12:57:59 libguestfs: command: run: \ --if-newer >May 27 12:57:59 libguestfs: command: run: \ --lock /var/tmp/.guestfs-0/lock >May 27 12:57:59 libguestfs: command: run: \ --copy-kernel >May 27 12:57:59 libguestfs: command: run: \ -f ext2 >May 27 12:57:59 libguestfs: command: run: \ --host-cpu x86_64 >May 27 12:57:59 libguestfs: command: run: \ /usr/lib64/guestfs/supermin.d >May 27 12:57:59 libguestfs: command: run: \ -o /var/tmp/.guestfs-0/appliance.d >May 27 12:57:59 supermin: version: 5.1.19 >May 27 12:57:59 supermin: rpm: detected RPM version 4.14 >May 27 12:57:59 supermin: package handler: fedora/rpm >May 27 12:57:59 supermin: acquiring lock on /var/tmp/.guestfs-0/lock >May 27 12:57:59 supermin: if-newer: output does not need rebuilding >May 27 12:57:59 libguestfs: finished building supermin appliance >May 27 12:57:59 libguestfs: trace: v2v: disk_create "/tmp/libguestfswUGQzV/overlay1.qcow2" "qcow2" -1 "backingfile:/var/tmp/.guestfs-0/appliance.d/root" "backingformat:raw" >May 27 12:57:59 libguestfs: command: run: qemu-img >May 27 12:57:59 libguestfs: command: run: \ create >May 27 12:57:59 libguestfs: command: run: \ -f qcow2 >May 27 12:57:59 libguestfs: command: run: \ -o backing_file=/var/tmp/.guestfs-0/appliance.d/root,backing_fmt=raw >May 27 12:57:59 libguestfs: command: run: \ /tmp/libguestfswUGQzV/overlay1.qcow2 >May 27 12:57:59 Formatting '/tmp/libguestfswUGQzV/overlay1.qcow2', fmt=qcow2 size=4294967296 backing_file=/var/tmp/.guestfs-0/appliance.d/root backing_fmt=raw cluster_size=65536 lazy_refcounts=off refcount_bits=16 >May 27 12:57:59 libguestfs: trace: v2v: disk_create = 0 >May 27 12:57:59 libguestfs: trace: v2v: get_sockdir >May 27 12:57:59 libguestfs: trace: v2v: get_sockdir = "/tmp" >May 27 12:57:59 libguestfs: create libvirt XML >May 27 12:57:59 libguestfs: command: run: file >May 27 12:57:59 libguestfs: command: run: \ -- /var/tmp/.guestfs-0/appliance.d/root >May 27 12:57:59 libguestfs: trace: v2v: get_cachedir >May 27 12:57:59 libguestfs: trace: v2v: get_cachedir = "/var/tmp" >May 27 12:57:59 libguestfs: libvirt XML:\n<?xml version="1.0"?>\n<domain type="kvm" xmlns:qemu="http://libvirt.org/schemas/domain/qemu/1.0">\n <name>guestfs-0q46q6wiufuj5ukj</name>\n <memory unit="MiB">2150</memory>\n <currentMemory unit="MiB">2150</currentMemory>\n <cpu mode="host-passthrough">\n <model fallback="allow"/>\n </cpu>\n <vcpu>1</vcpu>\n <clock offset="utc">\n <timer name="rtc" tickpolicy="catchup"/>\n <timer name="pit" tickpolicy="delay"/>\n <timer name="hpet" present="no"/>\n </clock>\n <os>\n <type>hvm</type>\n <kernel>/var/tmp/.guestfs-0/appliance.d/kernel</kernel>\n <initrd>/var/tmp/.guestfs-0/appliance.d/initrd</initrd>\n <cmdline>panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=81b85e4f-3ff3-4510-b643-fa7ae5823200 selinux=0 guestfs_verbose=1 guestfs_network=1 TERM=xterm-256color guestfs_identifier=v2v</cmdline>\n <bios useserial="yes"/>\n </os>\n <on_reboot>destroy</on_reboot>\n <devices>\n <rng model="virtio">\n <backend model="random">/dev/urandom</backend>\n </rng>\n <controller type="scsi" index="0" model="virtio-scsi"/>\n <disk device="disk" type="file">\n <source file="/var/tmp/v2vovla85659.qcow2"/>\n <target dev="sda" bus="scsi"/>\n <driver name="qemu" type="qcow2" cache="unsafe" discard="unmap" copy_on_read="on"/>\n <address type="drive" controller="0" bus="0" target="0" unit="0"/>\n </disk>\n <disk type="file" device="disk">\n <source file="/tmp/libguestfswUGQzV/overlay1.qcow2"/>\n <target dev="sdb" bus="scsi"/>\n <driver name="qemu" type="qcow2" cache="unsafe"/>\n <address type="drive" controller="0" bus="0" target="1" unit="0"/>\n </disk>\n <serial type="unix">\n <source mode="connect" path="/tmp/libguestfsS2yW9s/console.sock"/>\n <target port="0"/>\n </serial>\n <channel type="unix">\n <source mode="connect" path="/tmp/libguestfsS2yW9s/guestfsd.sock"/>\n <target type="virtio" name="org.libguestfs.channel.0"/>\n </channel>\n <controller type="usb" model="none"/>\n <memballoon model="none"/>\n </devices>\n <qemu:commandline>\n <qemu:env name="TMPDIR" value="/var/tmp"/>\n <qemu:arg value="-netdev"/>\n <qemu:arg value="user,id=usernet,net=169.254.0.0/16"/>\n <qemu:arg value="-device"/>\n <qemu:arg value="virtio-net-pci,netdev=usernet"/>\n </qemu:commandline>\n</domain>\n >May 27 12:57:59 libguestfs: trace: v2v: get_cachedir >May 27 12:57:59 libguestfs: trace: v2v: get_cachedir = "/var/tmp" >May 27 12:57:59 libguestfs: command: run: ls >May 27 12:57:59 libguestfs: command: run: \ -a >May 27 12:57:59 libguestfs: command: run: \ -l >May 27 12:57:59 libguestfs: command: run: \ -R >May 27 12:57:59 libguestfs: command: run: \ -Z /var/tmp/.guestfs-0 >May 27 12:57:59 libguestfs: /var/tmp/.guestfs-0: >May 27 12:57:59 libguestfs: total 4 >May 27 12:57:59 libguestfs: drwxr-xr-x. 3 root root unconfined_u:object_r:user_tmp_t:s0 37 May 27 12:57 . >May 27 12:57:59 libguestfs: drwxrwxrwt. 17 root root system_u:object_r:tmp_t:s0 4096 May 27 12:57 .. >May 27 12:57:59 libguestfs: drwxr-xr-x. 2 root root unconfined_u:object_r:user_tmp_t:s0 46 May 27 12:47 appliance.d >May 27 12:57:59 libguestfs: -rw-r--r--. 1 root root unconfined_u:object_r:user_tmp_t:s0 0 May 26 21:21 lock >May 27 12:57:59 libguestfs: >May 27 12:57:59 libguestfs: /var/tmp/.guestfs-0/appliance.d: >May 27 12:57:59 libguestfs: total 331380 >May 27 12:57:59 libguestfs: drwxr-xr-x. 2 root root unconfined_u:object_r:user_tmp_t:s0 46 May 27 12:47 . >May 27 12:57:59 libguestfs: drwxr-xr-x. 3 root root unconfined_u:object_r:user_tmp_t:s0 37 May 27 12:57 .. >May 27 12:57:59 libguestfs: -rw-r--r--. 1 qemu qemu system_u:object_r:virt_content_t:s0 4525056 May 27 12:57 initrd >May 27 12:57:59 libguestfs: -rwxr-xr-x. 1 qemu qemu system_u:object_r:virt_content_t:s0 9048720 May 27 12:57 kernel >May 27 12:57:59 libguestfs: -rw-r--r--. 1 qemu qemu system_u:object_r:virt_content_t:s0 4294967296 May 27 12:57 root >May 27 12:57:59 libguestfs: command: run: ls >May 27 12:57:59 libguestfs: command: run: \ -a >May 27 12:57:59 libguestfs: command: run: \ -l >May 27 12:57:59 libguestfs: command: run: \ -Z /tmp/libguestfsS2yW9s >May 27 12:57:59 libguestfs: total 4 >May 27 12:57:59 libguestfs: drwxr-xr-x. 2 root root unconfined_u:object_r:user_tmp_t:s0 47 May 27 12:57 . >May 27 12:57:59 libguestfs: drwxrwxrwt. 36 root root system_u:object_r:tmp_t:s0 4096 May 27 12:57 .. >May 27 12:57:59 libguestfs: srw-rw----. 1 root qemu unconfined_u:object_r:user_tmp_t:s0 0 May 27 12:57 console.sock >May 27 12:57:59 libguestfs: srw-rw----. 1 root qemu unconfined_u:object_r:user_tmp_t:s0 0 May 27 12:57 guestfsd.sock >May 27 12:57:59 libguestfs: launch libvirt guest >May 27 12:57:59 nbdkit: debug: accepted connection >May 27 12:57:59 nbdkit: vddk[2]: debug: cacheextents: preconnect >May 27 12:57:59 nbdkit: vddk[2]: debug: readahead: preconnect >May 27 12:57:59 nbdkit: vddk[2]: debug: retry: preconnect >May 27 12:57:59 nbdkit: vddk[2]: debug: vddk: preconnect >May 27 12:57:59 nbdkit: vddk[2]: debug: newstyle negotiation: flags: global 0x3 >May 27 12:57:59 nbdkit: vddk[2]: debug: newstyle negotiation: client flags: 0x3 >May 27 12:57:59 nbdkit: vddk[2]: debug: newstyle negotiation: NBD_OPT_STRUCTURED_REPLY: client requested structured replies >May 27 12:57:59 nbdkit: vddk[2]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: client requested export '/' >May 27 12:57:59 nbdkit: vddk[2]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: set count: 1 >May 27 12:57:59 nbdkit: vddk[2]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: set base:allocation >May 27 12:57:59 nbdkit: vddk[2]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: replying with base:allocation id 1 >May 27 12:57:59 nbdkit: vddk[2]: debug: newstyle negotiation: NBD_OPT_SET_META_CONTEXT: reply complete >May 27 12:57:59 nbdkit: vddk[2]: debug: newstyle negotiation: NBD_OPT_GO: client requested export '/' >May 27 12:57:59 nbdkit: vddk[2]: debug: cacheextents: open readonly=1 >May 27 12:57:59 nbdkit: vddk[2]: debug: readahead: open readonly=1 >May 27 12:57:59 nbdkit: vddk[2]: debug: retry: open readonly=1 >May 27 12:57:59 nbdkit: vddk[2]: debug: vddk: open readonly=1 >May 27 12:57:59 nbdkit: vddk[2]: debug: VDDK call: VixDiskLib_ConnectEx (h->params, 1, NULL, NULL, &connection) >May 27 12:57:59 nbdkit: vddk[2]: debug: VixDiskLib: VixDiskLib_ConnectEx: Establish connection using (null). >May 27 12:57:59 nbdkit: vddk[2]: debug: VixDiskLib: VixDiskLib_Connect: Establish connection. >May 27 12:57:59 nbdkit: vddk[2]: debug: VixDiskLib: Resolve host. >May 27 12:58:00 nbdkit: vddk[2]: debug: 2020-05-27T12:58:00.018+08:00 error -[7FCEB3FFF700] [Originator@6876 sub=Default] Cannot use advanced transport modes for 10.73.73.148/moref=vm-52/: Cannot create directory /tmp/vmware-root/42379ca1-f0d2-b2af-1bb1-0980f0dcc053-vm-52. >May 27 12:58:00 nbdkit: vddk[2]: debug: VixDiskLib: VixDiskLib_ConnectEx: Advanced transport modes not available for opening moref=vm-52. (Cannot use advanced transport modes for 10.73.73.148/moref=vm-52/: Cannot create directory /tmp/vmware-root/42379ca1-f0d2-b2af-1bb1-0980f0dcc053-vm-52.) >May 27 12:58:00 nbdkit: vddk[2]: debug: VDDK call: VixDiskLib_Open (connection, [ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk, 4, &handle) >May 27 12:58:00 nbdkit: vddk[2]: debug: VixDiskLib: VixDiskLib_OpenEx: Open a disk. >May 27 12:58:00 nbdkit: vddk[2]: debug: VixDiskLibVim: VixDiskLibVim_GetNfcTicket: Get NFC ticket for [ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk. >May 27 12:58:00 nbdkit: vddk[2]: debug: VixDiskLibVim: Request RandomAccessRO diskKey = 2000, readOnly = 1, openSnapshot = 0. >May 27 12:58:00 nbdkit: vddk[2]: debug: VixDiskLibVim: VixDiskLibVim_FreeNfcTicket: Free NFC ticket. >May 27 12:58:00 nbdkit: vddk[2]: debug: NBD_ClientOpen: attempting to create connection to vpxa-nfc://[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk@10.73.3.19:902 >May 27 12:58:00 nbdkit: vddk[2]: debug: CnxAuthdProtoSecureConnect: Target authd does not support BANNER (530). >May 27 12:58:00 nbdkit: vddk[2]: debug: DictionaryLoad: Cannot open file "/etc/vmware/vsphereFeatures/vsphereFeatures.cfg": No such file or directory. >May 27 12:58:00 nbdkit: vddk[2]: debug: [msg.dictionary.load.openFailed] Cannot open file "/etc/vmware/vsphereFeatures/vsphereFeatures.cfg": No such file or directory. >May 27 12:58:00 nbdkit: vddk[2]: debug: FeatureStateLib: Error while loading feature config file: /etc/vmware/vsphereFeatures/vsphereFeatures.cfg, using default feature state values. >May 27 12:58:00 nbdkit: vddk[2]: debug: Opening file [ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk (vpxa-nfc://[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk@10.73.3.19:902) >May 27 12:58:00 nbdkit: vddk[2]: debug: DISKLIB-LINK : Opened 'vpxa-nfc://[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk@10.73.3.19:902' (0x1e): custom, 33554432 sectors / 16 GB. >May 27 12:58:01 nbdkit: vddk[2]: debug: DISKLIB-LIB : Opened "vpxa-nfc://[ESX5.5-matrix] esx5.5-rhel6.10-x86_64/esx5.5-rhel6.10-x86_64.vmdk@10.73.3.19:902" (flags 0x1e, type custom). >May 27 12:58:01 nbdkit: vddk[2]: debug: VixDiskLib: VixDiskLib_GetTransportMode: Retrieve transport mode. >May 27 12:58:01 nbdkit: vddk[2]: debug: transport mode: nbd >May 27 12:58:01 nbdkit: vddk[2]: debug: vddk: open returned handle 0x7fcea017dc10 >May 27 12:58:01 nbdkit: vddk[2]: debug: retry: open returned handle 0x7fcea05d38e0 >May 27 12:58:01 nbdkit: vddk[2]: debug: readahead: open returned handle 0x7fceb3fff580 >May 27 12:58:01 nbdkit: vddk[2]: debug: cacheextents: open returned handle 0x7fceb3fff580 >May 27 12:58:01 nbdkit: vddk[2]: debug: vddk: prepare readonly=1 >May 27 12:58:01 nbdkit: vddk[2]: debug: retry: prepare readonly=1 >May 27 12:58:01 nbdkit: vddk[2]: debug: readahead: prepare readonly=1 >May 27 12:58:01 nbdkit: vddk[2]: debug: retry: get_size >May 27 12:58:01 nbdkit: vddk[2]: debug: vddk: get_size >May 27 12:58:01 nbdkit: vddk[2]: debug: VDDK call: VixDiskLib_GetInfo (handle, &info) >May 27 12:58:01 nbdkit: vddk[2]: debug: VixDiskLib: VixDiskLib_GetInfo: Retrieve disk info. >May 27 12:58:02 nbdkit: vddk[2]: debug: VDDK call: VixDiskLib_FreeInfo (info) >May 27 12:58:02 nbdkit: vddk[2]: debug: VixDiskLib: VixDiskLib_FreeInfo: Clean up VixDiskLib. >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: prepare readonly=1 >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: get_size >May 27 12:58:02 nbdkit: vddk[2]: debug: readahead: get_size >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: can_zero >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: can_fast_zero >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: can_trim >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: can_fua >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: can_flush >May 27 12:58:02 nbdkit: vddk[2]: debug: readahead: can_flush >May 27 12:58:02 nbdkit: vddk[2]: debug: retry: can_flush >May 27 12:58:02 nbdkit: vddk[2]: debug: vddk: can_flush >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: is_rotational >May 27 12:58:02 nbdkit: vddk[2]: debug: readahead: is_rotational >May 27 12:58:02 nbdkit: vddk[2]: debug: retry: is_rotational >May 27 12:58:02 nbdkit: vddk[2]: debug: vddk: is_rotational >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: can_multi_conn >May 27 12:58:02 nbdkit: vddk[2]: debug: readahead: can_multi_conn >May 27 12:58:02 nbdkit: vddk[2]: debug: retry: can_multi_conn >May 27 12:58:02 nbdkit: vddk[2]: debug: vddk: can_multi_conn >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: can_cache >May 27 12:58:02 nbdkit: vddk[2]: debug: readahead: can_cache >May 27 12:58:02 nbdkit: vddk[2]: debug: cacheextents: can_extents >May 27 12:58:02 nbdkit: vddk[2]: debug: readahead: can_extents >May 27 12:58:02 nbdkit: vddk[2]: debug: retry: can_extents >May 27 12:58:02 nbdkit: vddk[2]: debug: vddk: can_extents >May 27 12:58:02 nbdkit: vddk[2]: debug: can_extents: VixDiskLib_QueryAllocatedBlocks == NULL, probably this is VDDK < 6.7 >May 27 12:58:02 nbdkit: vddk[2]: debug: newstyle negotiation: flags: export 0x487 >May 27 12:58:02 nbdkit: vddk[2]: debug: newstyle negotiation: NBD_OPT_GO: ignoring NBD_INFO_* request 3 (NBD_INFO_BLOCK_SIZE) >May 27 12:58:02 nbdkit: vddk[2]: debug: handshake complete, processing requests serially > >
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1837840
:
1692569
| 1692572 |
1692596
|
1692597
|
1692598
|
1692600