Attachment 846 Details for Bug 5845 – toolsDeployPkg.log

toolsDeployPkg.log

toolsDeployPkg.log (text/plain), 13.62 KB, created by wmn365 on 2023-07-12 11:01:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: wmn365

Created: 2023-07-12 11:01:07 UTC

Size: 13.62 KB

patch

obsolete

>[2023-07-11T10:56:16.661Z] [   debug] ## Starting deploy pkg operation
>[2023-07-11T10:56:16.661Z] [    info] VMware Tools Version: 12.1.5.39265 (build-20735119)
>[2023-07-11T10:56:16.661Z] [   debug] Deploying /var/run/16cbb0f6/imcf-M7FCOm
>[2023-07-11T10:56:16.661Z] [   debug] The valid [deployPkg] wait-cloudinit-timeout value range: 0 ~ 1800
>[2023-07-11T10:56:16.661Z] [    info] Initializing deployment module.
>[2023-07-11T10:56:16.661Z] [    info] Cleaning old state files.
>[2023-07-11T10:56:16.661Z] [    info] EXIT STATE 'INPROGRESS'.
>[2023-07-11T10:56:16.661Z] [   debug] Setting deploy error: 'Error removing lock '/var/log/.vmware-deploy.INPROGRESS'.(No such file or directory)'.
>[2023-07-11T10:56:16.661Z] [    info] EXIT STATE 'Done'.
>[2023-07-11T10:56:16.661Z] [   debug] Setting deploy error: 'Error removing lock '/var/log/.vmware-deploy.Done'.(No such file or directory)'.
>[2023-07-11T10:56:16.661Z] [    info] EXIT STATE 'ERRORED'.
>[2023-07-11T10:56:16.661Z] [   debug] Setting deploy error: 'Error removing lock '/var/log/.vmware-deploy.ERRORED'.(No such file or directory)'.
>[2023-07-11T10:56:16.661Z] [   debug] Setting deploy error: 'Success.'.
>[2023-07-11T10:56:16.661Z] [    info] Deploying cabinet file '/var/run/16cbb0f6/imcf-M7FCOm'.
>[2023-07-11T10:56:16.661Z] [    info] Transitioning from state '(null)' to state 'INPROGRESS'.
>[2023-07-11T10:56:16.661Z] [    info] ENTER STATE 'INPROGRESS'.
>[2023-07-11T10:56:16.662Z] [    info] Reading cabinet file '/var/run/16cbb0f6/imcf-M7FCOm' and will extract it to '/var/run/.vmware-imgcust-dvUpKAg'.
>[2023-07-11T10:56:16.662Z] [    info] Flags in the header: 0.
>[2023-07-11T10:56:16.662Z] [    info] Original deployment command: '/usr/bin/perl -I/tmp/.vmware/linux/deploy/scripts /tmp/.vmware/linux/deploy/scripts/Customize.pl /tmp/.vmware/linux/deploy/cust.cfg'.
>[2023-07-11T10:56:16.662Z] [    info] Actual deployment command: '/usr/bin/perl -I/var/run/.vmware-imgcust-dvUpKAg/scripts /var/run/.vmware-imgcust-dvUpKAg/scripts/Customize.pl /var/run/.vmware-imgcust-dvUpKAg/cust.cfg'.
>[2023-07-11T10:56:16.662Z] [    info] Extracting package files.
>[2023-07-11T10:56:16.699Z] [   debug] Command to exec : '/usr/bin/cloud-init'.
>[2023-07-11T10:56:16.699Z] [    info] sizeof ProcessInternal is 56
>[2023-07-11T10:56:16.699Z] [    info] Returning, pending output from stdout
>[2023-07-11T10:56:16.700Z] [    info] Returning, pending output from stderr
>[2023-07-11T10:56:16.703Z] [   error] execv failed to run (/usr/bin/cloud-init), errno=(2), error message:(No such file or directory)
>[2023-07-11T10:56:16.717Z] [    info] Process exited normally after 0 seconds, returned 127
>[2023-07-11T10:56:16.717Z] [    info] No more output from stdout
>[2023-07-11T10:56:16.717Z] [    info] No more output from stderr
>[2023-07-11T10:56:16.717Z] [    info] Customization command output:
>''.
>[2023-07-11T10:56:16.717Z] [   error] Customization command failed with exitcode: 127, stderr: ''.
>[2023-07-11T10:56:16.717Z] [    info] Cloud-init is not installed.
>[2023-07-11T10:56:16.717Z] [    info] UseCloudInitWorkflow return: 3
>[2023-07-11T10:56:16.717Z] [    info] Executing traditional GOSC workflow.
>[2023-07-11T10:56:16.717Z] [   debug] Command to exec : '/usr/bin/perl'.
>[2023-07-11T10:56:16.717Z] [    info] sizeof ProcessInternal is 56
>[2023-07-11T10:56:16.717Z] [    info] Returning, pending output from stdout
>[2023-07-11T10:56:16.718Z] [    info] Returning, pending output from stderr
>[2023-07-11T10:56:18.028Z] [    info] Process exited normally after 1 seconds, returned 255
>[2023-07-11T10:56:18.028Z] [    info] Saving output from stdout
>[2023-07-11T10:56:18.028Z] [    info] Saving output from stdout
>[2023-07-11T10:56:18.028Z] [    info] Saving output from stdout
>[2023-07-11T10:56:18.028Z] [    info] Saving output from stdout
>[2023-07-11T10:56:18.028Z] [    info] Saving output from stdout
>[2023-07-11T10:56:18.028Z] [    info] Saving output from stdout
>[2023-07-11T10:56:18.028Z] [    info] Saving output from stdout
>[2023-07-11T10:56:18.028Z] [    info] Saving output from stdout
>[2023-07-11T10:56:18.028Z] [    info] No more output from stdout
>[2023-07-11T10:56:18.028Z] [    info] No more output from stderr
>[2023-07-11T10:56:18.028Z] [    info] Customization command output:
>=================== Perl script log start =================
>2023-07-11T10:56:17 DEBUG: Creating directory /var/lock/vmware 
>2023-07-11T10:56:17 DEBUG: Opening /var/lock/vmware/gosc in O_CREAT|O_EXCL|O_WRONLY mode 
>2023-07-11T10:56:17 INFO: Opening file name /var/run/.vmware-imgcust-dvUpKAg/cust.cfg. 
>2023-07-11T10:56:17 DEBUG: Processing line: '[NETWORK]' 
>2023-07-11T10:56:17 DEBUG: FOUND CATEGORY = NETWORK 
>2023-07-11T10:56:17 DEBUG: Processing line: 'NETWORKING = yes' 
>2023-07-11T10:56:17 DEBUG: ADDED KEY-VAL :: 'NETWORK|NETWORKING' = 'yes' 
>2023-07-11T10:56:17 DEBUG: Processing line: 'BOOTPROTO = dhcp' 
>2023-07-11T10:56:17 DEBUG: ADDED KEY-VAL :: 'NETWORK|BOOTPROTO' = 'dhcp' 
>2023-07-11T10:56:17 DEBUG: Processing line: 'HOSTNAME = 11' 
>2023-07-11T10:56:17 DEBUG: ADDED KEY-VAL :: 'NETWORK|HOSTNAME' = '11' 
>2023-07-11T10:56:17 DEBUG: Processing line: '' 
>2023-07-11T10:56:17 DEBUG: Empty line. Ignored. 
>2023-07-11T10:56:17 DEBUG: Processing line: '[NIC-CONFIG]' 
>2023-07-11T10:56:17 DEBUG: FOUND CATEGORY = NIC-CONFIG 
>2023-07-11T10:56:17 DEBUG: Processing line: 'NICS = NIC1' 
>2023-07-11T10:56:17 DEBUG: ADDED KEY-VAL :: 'NIC-CONFIG|NICS' = 'NIC1' 
>2023-07-11T10:56:17 DEBUG: Processing line: '' 
>2023-07-11T10:56:17 DEBUG: Empty line. Ignored. 
>2023-07-11T10:56:17 DEBUG: Processing line: '[NIC1]' 
>2023-07-11T10:56:17 DEBUG: FOUND CATEGORY = NIC1 
>2023-07-11T10:56:17 DEBUG: Processing line: 'MACADDR = 00:50:56:98:9b:03' 
>2023-07-11T10:56:17 DEBUG: ADDED KEY-VAL :: 'NIC1|MACADDR' = '00:50:56:98:9b:03' 
>2023-07-11T10:56:17 DEBUG: Processing line: 'ONBOOT = yes' 
>2023-07-11T10:56:17 DEBUG: ADDED KEY-VAL :: 'NIC1|ONBOOT' = 'yes' 
>2023-07-11T10:56:17 DEBUG: Processing line: 'IPv4_MODE = BACKWARDS_COMPATIBLE' 
>2023-07-11T10:56:17 DEBUG: ADDED KEY-VAL :: 'NIC1|IPv4_MODE' = 'BACKWARDS_COMPATIBLE' 
>2023-07-11T10:56:17 DEBUG: Processing line: 'BOOTPROTO = dhcp' 
>2023-07-11T10:56:17 DEBUG: ADDED KEY-VAL :: 'NIC1|BOOTPROTO' = 'dhcp' 
>2023-07-11T10:56:17 DEBUG: Processing line: '' 
>2023-07-11T10:56:17 DEBUG: Empty line. Ignored. 
>2023-07-11T10:56:17 DEBUG: Processing line: '' 
>2023-07-11T10:56:17 DEBUG: Empty line. Ignored. 
>2023-07-11T10:56:17 DEBUG: Processing line: '[DNS]' 
>2023-07-11T10:56:17 DEBUG: FOUND CATEGORY = DNS 
>2023-07-11T10:56:17 DEBUG: Processing line: 'DNSFROMDHCP=yes' 
>2023-07-11T10:56:17 DEBUG: ADDED KEY-VAL :: 'DNS|DNSFROMDHCP' = 'yes' 
>2023-07-11T10:56:17 DEBUG: Processing line: '' 
>2023-07-11T10:56:17 DEBUG: Empty line. Ignored. 
>2023-07-11T10:56:17 DEBUG: Processing line: '' 
>2023-07-11T10:56:17 DEBUG: Empty line. Ignored. 
>2023-07-11T10:56:17 DEBUG: Opening file /var/run/.vmware-imgcust-dvUpKAg/buildInfo.txt. 
>2023-07-11T10:56:17 INFO: SYSIMAGE_VERSION = 11.4.0.25296 
>2023-07-11T10:56:17 INFO: BUILDNUMBER = build-19901193 
>2023-07-11T10:56:17 DEBUG: Reading issue file ...  
>2023-07-11T10:56:17 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:17 DEBUG: Exit Code: 0 
>2023-07-11T10:56:17 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:17 DEBUG: Reading /etc/os-release file ...  
>2023-07-11T10:56:17 DEBUG: opening file /etc/os-release. 
>2023-07-11T10:56:17 DEBUG: Match found   : Line = PRETTY_NAME="Anolis OS 8.8"
> 
>2023-07-11T10:56:17 DEBUG: Actual String : "Anolis OS 8.8" 
>2023-07-11T10:56:17 DEBUG: Reading issue file ...  
>2023-07-11T10:56:17 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:17 DEBUG: Exit Code: 0 
>2023-07-11T10:56:17 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:17 DEBUG: file content: '\S
>Kernel \r on an \m
>
>' 
>2023-07-11T10:56:17 DEBUG: Reading /etc/os-release file ...  
>2023-07-11T10:56:17 DEBUG: opening file /etc/os-release. 
>2023-07-11T10:56:17 DEBUG: Match found   : Line = ID="anolis"
> 
>2023-07-11T10:56:17 DEBUG: Actual String : "anolis" 
>2023-07-11T10:56:17 DEBUG: ID: "anolis" 
>2023-07-11T10:56:17 DEBUG: opening file /etc/os-release. 
>2023-07-11T10:56:17 DEBUG: Match found   : Line = VERSION_ID="8.8"
> 
>2023-07-11T10:56:17 DEBUG: Actual String : "8.8" 
>2023-07-11T10:56:17 DEBUG: Version: "8.8" 
>2023-07-11T10:56:17 WARNING: Amazon Linux flavor not detected 
>2023-07-11T10:56:17 DEBUG: Reading issue file ...  
>2023-07-11T10:56:17 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 WARNING: RedHat release file not available. Ignoring it. 
>2023-07-11T10:56:18 WARNING: Redhat flavor not detected 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 WARNING: RedHat release file not available. Ignoring it. 
>2023-07-11T10:56:18 WARNING: Redhat flavor not detected 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 WARNING: RedHat release file not available. Ignoring it. 
>2023-07-11T10:56:18 WARNING: Redhat flavor not detected 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: Reading issue file ...  
>2023-07-11T10:56:18 DEBUG: Command: 'cat /etc/issue' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 0 
>2023-07-11T10:56:18 DEBUG: Result: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 DEBUG: \S
>Kernel \r on an \m
>
> 
>2023-07-11T10:56:18 INFO: Getting hostname 
>2023-07-11T10:56:18 DEBUG: Command: '/sbin/uname -a' 
>2023-07-11T10:56:18 DEBUG: Exit Code: 72057594037927935 
>2023-07-11T10:56:18 DEBUG: Result:  
>2023-07-11T10:56:18 ERROR: Fatal error occurred during customization !! Customization halted. 
>2023-07-11T10:56:18 ERROR: Error : Customization Failure !! Unknown distribution. at /var/run/.vmware-imgcust-dvUpKAg/scripts/Customize.pl line 117.
> 
>2023-07-11T10:56:18 INFO: Return code is 255. 
>2023-07-11T10:56:18 DEBUG: Removing lock file /var/lock/vmware/gosc. 
>
>=================== Perl script log end =================
>[2023-07-11T10:56:18.028Z] [   error] Customization command failed with exitcode: 255, stderr: ''.
>[2023-07-11T10:56:18.028Z] [   error] Customization process returned with error.
>[2023-07-11T10:56:18.028Z] [   debug] Deployment result = 255.
>[2023-07-11T10:56:18.028Z] [    info] Setting 'generic' error status in vmx.
>[2023-07-11T10:56:18.029Z] [    info] Transitioning from state 'INPROGRESS' to state 'ERRORED'.
>[2023-07-11T10:56:18.029Z] [    info] ENTER STATE 'ERRORED'.
>[2023-07-11T10:56:18.029Z] [    info] EXIT STATE 'INPROGRESS'.
>[2023-07-11T10:56:18.029Z] [   debug] Setting deploy error: 'Deployment failed.The forked off process returned error code.'.
>[2023-07-11T10:56:18.029Z] [   error] Deployment failed.The forked off process returned error code.
>[2023-07-11T10:56:18.029Z] [    info] Launching cleanup.
>[2023-07-11T10:56:18.029Z] [   debug] Command to exec : '/bin/rm'.
>[2023-07-11T10:56:18.029Z] [    info] sizeof ProcessInternal is 56
>[2023-07-11T10:56:18.030Z] [    info] Returning, pending output from stdout
>[2023-07-11T10:56:18.030Z] [    info] Returning, pending output from stderr
>[2023-07-11T10:56:18.130Z] [    info] Process exited normally after 0 seconds, returned 0
>[2023-07-11T10:56:18.130Z] [    info] No more output from stdout
>[2023-07-11T10:56:18.130Z] [    info] No more output from stderr
>[2023-07-11T10:56:18.130Z] [    info] Customization command output:
>''.
>[2023-07-11T10:56:18.130Z] [    info] sSkipReboot: 'false', forceSkipReboot 'false'.
>[2023-07-11T10:56:18.130Z] [   error] Deploy error: 'Deployment failed.The forked off process returned error code.'.
>[2023-07-11T10:56:18.130Z] [   error] Package deploy failed in DeployPkg_DeployPackageFromFile
>[2023-07-11T10:56:18.130Z] [   debug] ## Closing log

Actions: View

Attachments on bug 5845: 846 | 847 | 848