0 Replies Latest reply on Dec 22, 2013 12:24 PM by Davehouser

    Cannot create VSA cluster: java.lang.Exception: Failed to ping VSA service on VM <IP address>

    Davehouser Lurker

      I have continued to receive this error when trying to perform a Brownfield VSA install on a 3 host cluster. I have tried everything. The <IP address> in this case is the VSA managment IP for Host0 (172.30.25.136)

       

      My environment

       

      • (3) Dell Poweredge R620 servers (48 gigs of ram Each, (2) 2.9 Ghz processors)
      • ESXi Dell 5.1 installed on SD on each host.
      • VMware 5.1.0-947939.iso
      • VSA 5.1.3.0.iso
      • Only SSON, inventory, vCenter server, and VSA were installed.
      • Hosts added by IP address not DNS
      • Network configured ahead of time on each host.
      • vCenter runs as a VM, Domain controller is outside of environment.

       

      What i have tried

       

      • Configured dev.properties to set evc to "false" and "highest". I have also adjusted to turn off roll back and time out to 15 min.
      • Watched all VSA appliances power up to log in screen.
      • I am able to Ping 172.30.25.136 from my computer and from the host while the install is happening.
      • The install stops when each host reconfigured auto start for VMs It stops at the first host that houses the VSA appliance at question. When i log into the host housing the appliance that VSA installer claims it does not respond to pings, i can see the install is stuck at (Acquire CIM service), the other two hosts reconfigure Autostart Manager fine.
      • I have re installed vCenter server several times.
      • I have followed instructions from the following posts.
      • Reinstalled eveything (ESXi, Cisco configs, Windows server, vCenter, VSA)

       

      What I find really odd, Is i have the exact same cluster created as a duplicate which worked fine (3) other Dell R620 servers. Every aspect of it is the same, servers, versions everything. It was able to create the VSA HA cluster, and complete the VSA install. The only difference between the two is the that one sits behind two physical switches for redundancy purposes (stacked). While the cluster that I am struggling with sits on just one Cisco switch. VSAManager Log below. Pictures attached. I hope someone can help.

       

      2013-12-22 10:09:33,670 85 [IphlpManager] [pool-3-thread-1] INFO  - Found adapter for IP address: 172.30.25.7

        name = Local Area Connection

        netmask = 255.255.255.128

        gateway=172.30.25.1

      2013-12-22 10:09:33,685 43 [SimplePBEStringEncryptor] [pool-3-thread-1] INFO  - Retrieved encryption data from VC registry key

      2013-12-22 10:09:33,701 116 [KeystoreX509TrustManager] [pool-3-thread-1] INFO  - Loading from trust store: C:/ProgramData/VMware/VSA Manager/security/keystore.jks

      2013-12-22 10:09:33,717 110 [KeystoreX509TrustManager] [pool-3-thread-1] INFO  - Created trust store: C:/ProgramData/VMware/VSA Manager/security/keystore.jks

      2013-12-22 10:09:34,838 115 [DbUpgrade] [pool-3-thread-1] INFO  - Running DB script

      2013-12-22 10:09:35,212 118 [DbUpgrade] [pool-3-thread-1] INFO  - ij> --

      -- Schema creation

      --

       

       

      CREATE SCHEMA vsa;

      0 rows inserted/updated/deleted

      ij> -- CREATE the info table

      CREATE TABLE vsa.info (

        name VARCHAR(128) NOT NULL PRIMARY KEY,

        param VARCHAR(256) NOT NULL

      );

      0 rows inserted/updated/deleted

      ij> -- CREATE the datacenter table

      CREATE TABLE vsa.datacenter (

        id INT PRIMARY KEY GENERATED BY DEFAULT AS IDENTITY,

        moref VARCHAR(256) NOT NULL,

        name VARCHAR(256) NOT NULL,

        cluster_uuid VARCHAR(256),

        password VARCHAR(256),

        newpassword VARCHAR(256)

      );

      0 rows inserted/updated/deleted

      ij> ALTER TABLE vsa.datacenter

      ADD CONSTRAINT DATACENTER_MOREF_UC UNIQUE (moref);

      0 rows inserted/updated/deleted

      ij> -- CREATE the pseudosva table

      CREATE TABLE vsa.pseudosva (

        id INT PRIMARY KEY GENERATED BY DEFAULT AS IDENTITY,

        datacenterid INT CONSTRAINT PESUDOSVA_FK REFERENCES vsa.datacenter (id) ON DELETE CASCADE ON UPDATE RESTRICT,

        ipaddr VARCHAR(128) NOT NULL,

        netmask VARCHAR(128) NOT NULL,

        gateway VARCHAR (128) NOT NULL,

        vlanid VARCHAR(128) NOT NULL

      );

      0 rows inserted/updated/deleted

      ij> -- CREATE the host table

      CREATE TABLE vsa.host (

        id INT PRIMARY KEY GENERATED BY DEFAULT AS IDENTITY,

        datacenterid INT CONSTRAINT HOST_FK REFERENCES vsa.datacenter (id) ON DELETE CASCADE ON UPDATE RESTRICT,

        moref VARCHAR(256) NOT NULL,

        name VARCHAR(256) NOT NULL

      );

      0 rows inserted/updated/deleted

      ij> ALTER TABLE vsa.host

      ADD CONSTRAINT HOST_UC UNIQUE (datacenterid, moref);

      0 rows inserted/updated/deleted

      ij> -- CREATE the vm table

      CREATE TABLE vsa.vm (

        id INT PRIMARY KEY GENERATED BY DEFAULT AS IDENTITY,

        hostid INT CONSTRAINT VM_FK REFERENCES vsa.host (id) ON DELETE CASCADE ON UPDATE RESTRICT,

        uuid VARCHAR(256),

        moref VARCHAR(256),

        name VARCHAR(256)

      );

      0 rows inserted/updated/deleted

      ij> -- CREATE the network table

      CREATE TABLE vsa.network (

        id INT PRIMARY KEY GENERATED BY DEFAULT AS IDENTITY,

        hostid INT CONSTRAINT NETWORK_FK REFERENCES vsa.host (id) ON DELETE CASCADE ON UPDATE RESTRICT,

        name VARCHAR(256) NOT NULL,

        protocol VARCHAR(256) NOT NULL,

        ipaddr VARCHAR(128) NOT NULL,

        netmask VARCHAR(128) NOT NULL,

        gateway VARCHAR(128) NOT NULL,

        vlanid VARCHAR(128) NOT NULL

      );

      0 rows inserted/updated/deleted

      ij> ALTER TABLE vsa.network

      ADD CONSTRAINT NETWORK_UC UNIQUE (hostid, name);

      0 rows inserted/updated/deleted

      ij> -- CREATE the task table

      CREATE TABLE vsa.clustertask (

        id INT PRIMARY KEY GENERATED BY DEFAULT AS IDENTITY,

          datacenterid INT CONSTRAINT TASK_FK REFERENCES vsa.datacenter (id) ON DELETE CASCADE ON UPDATE RESTRICT,

        uuid VARCHAR(256) NOT NULL,

        name VARCHAR(256) NOT NULL,

        status VARCHAR(256),

        state blob(32K)

      );

      0 rows inserted/updated/deleted

      ij> -- CREATE networks that belong to datacenter rather than hosts

      CREATE TABLE vsa.dcnetwork (

          id INT PRIMARY KEY GENERATED BY DEFAULT AS IDENTITY,

          datacentermoref VARCHAR(256) CONSTRAINT DCNETWORK_DATACENTER_FK REFERENCES vsa.datacenter(moref) ON DELETE CASCADE ON UPDATE RESTRICT,

          name VARCHAR(256) NOT NULL,

          protocol VARCHAR(256) NOT NULL,

          ipaddr VARCHAR(128) NOT NULL,

          netmask VARCHAR(128) NOT NULL,

          gateway VARCHAR(128) NOT NULL

      );

      0 rows inserted/updated/deleted

      ij> ALTER TABLE vsa.dcnetwork

      ADD CONSTRAINT DCNETWORK_UC UNIQUE (datacentermoref, name);

      0 rows inserted/updated/deleted

      ij> --

      -- Data population

      --

       

       

      -- CREATE the Job table

      CREATE TABLE vsa.job (

          id INT PRIMARY KEY GENERATED BY DEFAULT AS IDENTITY,

          datacentermoref VARCHAR(256) CONSTRAINT SVATASK_DATACENTER_FK REFERENCES vsa.datacenter(moref) ON DELETE CASCADE ON UPDATE RESTRICT,

          runningtask VARCHAR(256) NOT NULL,

          level VARCHAR(128) NOT NULL,

          type INT NOT NULL,

          progress INT NOT NULL,

          taskexception VARCHAR(256),

          taskwarning VARCHAR(256),

          rollbackexception VARCHAR(256),

          rollbackwarning  VARCHAR(256),

          data blob(32K)

      );

      0 rows inserted/updated/deleted

      ij> INSERT INTO vsa.info VALUES('version', '1.5.0');

      1 row inserted/updated/deleted

      ij>

      2013-12-22 10:09:35,321 390 [SvaSolutionLicenseManager] [pool-3-thread-1] INFO  - VSA(Standard) evaluation expiration date stored successfully in persistence

      2013-12-22 10:09:35,336 57 [ExtService] [pool-3-thread-1] INFO  - Login to VC

      2013-12-22 10:09:35,990 105 [KeyStoreConfig] [Timeout guard] INFO  - loading keystore from classpath: rui.pfx

      2013-12-22 10:09:35,990 111 [KeyStoreConfig] [Timeout guard] INFO  - keystore is loaded.

      2013-12-22 10:09:35,990 97 [CustomSslSocketFactory] [Timeout guard] INFO  - SSL Socket factory initialized

      2013-12-22 10:09:36,255 41 [SanityServices] [pool-3-thread-1] INFO  - Starting license enforcement

      2013-12-22 10:09:36,255 63 [SanityServices] [pool-3-thread-1] INFO  - Start monitoring VSAManager.log at Mon 2013.12.23 at 02:00:00 AM PST

      2013-12-22 10:09:36,442 99 [SessionService] [pool-3-thread-1] INFO  - Finished initializing all context tasks

      2013-12-22 10:09:36,488 119 [SvaLicenseManager] [LicenseManager-1] INFO  - Changing license assignment manager to SOLUTION

      2013-12-22 10:09:36,488 656 [SvaSolutionLicenseManager] [LicenseManager-1] INFO  - Uploading DLFs

      2013-12-22 10:09:36,520 318 [LicenseUtils] [LicenseManager-1] INFO  - Uploading license file: C:\Program Files\VMware\Infrastructure\tomcat\webapps\VSAManager\WEB-INF\classes\licenses\license-vsa-50-e1-t1-201006.txt

      2013-12-22 10:09:36,582 668 [SvaSolutionLicenseManager] [LicenseManager-1] WARN  - Unable to upload license file. File already exists: C:\Program Files\VMware\Infrastructure\tomcat\webapps\VSAManager\WEB-INF\classes\licenses\license-vsa-50-e1-t1-201006.txt

      2013-12-22 10:09:36,613 489 [SvaSolutionLicenseManager] [LicenseManager-1] INFO  - Registering VSA License entity with version: 5.0 and expiration date : 2014-02-22

      2013-12-22 10:09:36,613 235 [Utils] [pool-3-thread-1] INFO  - Retrieve dev.property: test.on=false

      2013-12-22 10:09:36,706 500 [SvaSolutionLicenseManager] [LicenseManager-1] WARN  - Exception while trying to register VSA license entity. Entity already exists.

      2013-12-22 10:09:36,722 545 [SvaSolutionLicenseManager] [LicenseManager-1] INFO  - VSA License entity already has an assigned license

      2013-12-22 10:09:36,753 145 [SvaLicenseManager] [LicenseManager-1] INFO  - Days before license expiration :: 61

      2013-12-22 10:09:36,753 85 [SvaLicenseManager] [LicenseManager-1] INFO  - License verified and found satisfactory

      2013-12-22 10:14:04,205 85 [IphlpManager] [pool-3-thread-1] INFO  - Found adapter for IP address: 172.30.25.7

        name = Local Area Connection

        netmask = 255.255.255.128

        gateway=172.30.25.1

      2013-12-22 10:14:04,236 43 [SimplePBEStringEncryptor] [pool-3-thread-1] INFO  - Retrieved encryption data from VC registry key

      2013-12-22 10:14:04,236 116 [KeystoreX509TrustManager] [pool-3-thread-1] INFO  - Loading from trust store: C:/ProgramData/VMware/VSA Manager/security/keystore.jks

      2013-12-22 10:14:05,830 162 [DbUpgrade] [pool-3-thread-1] INFO  - Checking vsaDB consistency

      2013-12-22 10:14:05,892 150 [DbUpgrade] [pool-3-thread-1] INFO  - Backing up vsaDB

      2013-12-22 10:14:06,248 57 [ExtService] [pool-3-thread-1] INFO  - Login to VC

      2013-12-22 10:14:08,244 105 [KeyStoreConfig] [Timeout guard] INFO  - loading keystore from classpath: rui.pfx

      2013-12-22 10:14:08,260 111 [KeyStoreConfig] [Timeout guard] INFO  - keystore is loaded.

      2013-12-22 10:14:08,260 97 [CustomSslSocketFactory] [Timeout guard] INFO  - SSL Socket factory initialized

      2013-12-22 10:14:08,925 41 [SanityServices] [pool-3-thread-1] INFO  - Starting license enforcement

      2013-12-22 10:14:08,941 63 [SanityServices] [pool-3-thread-1] INFO  - Start monitoring VSAManager.log at Mon 2013.12.23 at 02:00:00 AM PST

      2013-12-22 10:14:09,359 99 [SessionService] [pool-3-thread-1] INFO  - Finished initializing all context tasks

      2013-12-22 10:14:09,529 119 [SvaLicenseManager] [LicenseManager-1] INFO  - Changing license assignment manager to SOLUTION

      2013-12-22 10:14:09,544 656 [SvaSolutionLicenseManager] [LicenseManager-1] INFO  - Uploading DLFs

      2013-12-22 10:14:09,560 318 [LicenseUtils] [LicenseManager-1] INFO  - Uploading license file: C:\Program Files\VMware\Infrastructure\tomcat\webapps\VSAManager\WEB-INF\classes\licenses\license-vsa-50-e1-t1-201006.txt

      2013-12-22 10:14:09,668 668 [SvaSolutionLicenseManager] [LicenseManager-1] WARN  - Unable to upload license file. File already exists: C:\Program Files\VMware\Infrastructure\tomcat\webapps\VSAManager\WEB-INF\classes\licenses\license-vsa-50-e1-t1-201006.txt

      2013-12-22 10:14:09,684 489 [SvaSolutionLicenseManager] [LicenseManager-1] INFO  - Registering VSA License entity with version: 5.0 and expiration date : 2014-02-22

      2013-12-22 10:14:09,730 500 [SvaSolutionLicenseManager] [LicenseManager-1] WARN  - Exception while trying to register VSA license entity. Entity already exists.

      2013-12-22 10:14:09,745 545 [SvaSolutionLicenseManager] [LicenseManager-1] INFO  - VSA License entity already has an assigned license

      2013-12-22 10:14:09,854 145 [SvaLicenseManager] [LicenseManager-1] INFO  - Days before license expiration :: 61

      2013-12-22 10:14:09,854 85 [SvaLicenseManager] [LicenseManager-1] INFO  - License verified and found satisfactory

      2013-12-22 10:14:09,978 235 [Utils] [pool-3-thread-1] INFO  - Retrieve dev.property: test.on=false

      2013-12-22 10:20:04,747 85 [IphlpManager] [pool-3-thread-1] INFO  - Found adapter for IP address: 172.30.25.7

        name = Local Area Connection

        netmask = 255.255.255.128

        gateway=172.30.25.1

      2013-12-22 10:20:04,763 43 [SimplePBEStringEncryptor] [pool-3-thread-1] INFO  - Retrieved encryption data from VC registry key

      2013-12-22 10:20:04,763 116 [KeystoreX509TrustManager] [pool-3-thread-1] INFO  - Loading from trust store: C:/ProgramData/VMware/VSA Manager/security/keystore.jks

      2013-12-22 10:20:05,830 162 [DbUpgrade] [pool-3-thread-1] INFO  - Checking vsaDB consistency

      2013-12-22 10:20:05,861 150 [DbUpgrade] [pool-3-thread-1] INFO  - Backing up vsaDB

      2013-12-22 10:20:06,371 57 [ExtService] [pool-3-thread-1] INFO  - Login to VC

      2013-12-22 10:20:07,670 105 [KeyStoreConfig] [Timeout guard] INFO  - loading keystore from classpath: rui.pfx

      2013-12-22 10:20:07,701 111 [KeyStoreConfig] [Timeout guard] INFO  - keystore is loaded.

      2013-12-22 10:20:07,701 97 [CustomSslSocketFactory] [Timeout guard] INFO  - SSL Socket factory initialized

      2013-12-22 10:20:08,196 41 [SanityServices] [pool-3-thread-1] INFO  - Starting license enforcement

      2013-12-22 10:20:08,211 63 [SanityServices] [pool-3-thread-1] INFO  - Start monitoring VSAManager.log at Mon 2013.12.23 at 02:00:00 AM PST

      2013-12-22 10:20:08,490 99 [SessionService] [pool-3-thread-1] INFO  - Finished initializing all context tasks

      2013-12-22 10:20:08,814 235 [Utils] [pool-3-thread-1] INFO  - Retrieve dev.property: test.on=false

      2013-12-22 10:20:08,830 119 [SvaLicenseManager] [LicenseManager-1] INFO  - Changing license assignment manager to SOLUTION

      2013-12-22 10:20:08,830 656 [SvaSolutionLicenseManager] [LicenseManager-1] INFO  - Uploading DLFs

      2013-12-22 10:20:08,845 318 [LicenseUtils] [LicenseManager-1] INFO  - Uploading license file: C:\Program Files\VMware\Infrastructure\tomcat\webapps\VSAManager\WEB-INF\classes\licenses\license-vsa-50-e1-t1-201006.txt

      2013-12-22 10:20:08,938 668 [SvaSolutionLicenseManager] [LicenseManager-1] WARN  - Unable to upload license file. File already exists: C:\Program Files\VMware\Infrastructure\tomcat\webapps\VSAManager\WEB-INF\classes\licenses\license-vsa-50-e1-t1-201006.txt

      2013-12-22 10:20:08,938 489 [SvaSolutionLicenseManager] [LicenseManager-1] INFO  - Registering VSA License entity with version: 5.0 and expiration date : 2014-02-22

      2013-12-22 10:20:09,000 500 [SvaSolutionLicenseManager] [LicenseManager-1] WARN  - Exception while trying to register VSA license entity. Entity already exists.

      2013-12-22 10:20:09,015 545 [SvaSolutionLicenseManager] [LicenseManager-1] INFO  - VSA License entity already has an assigned license

      2013-12-22 10:20:09,046 145 [SvaLicenseManager] [LicenseManager-1] INFO  - Days before license expiration :: 61

      2013-12-22 10:20:09,046 85 [SvaLicenseManager] [LicenseManager-1] INFO  - License verified and found satisfactory

      2013-12-22 10:25:52,987 85 [IphlpManager] [pool-3-thread-1] INFO  - Found adapter for IP address: 172.30.25.7

        name = Local Area Connection

        netmask = 255.255.255.128

        gateway=172.30.25.1

      2013-12-22 10:25:53,003 43 [SimplePBEStringEncryptor] [pool-3-thread-1] INFO  - Retrieved encryption data from VC registry key

      2013-12-22 10:25:53,003 116 [KeystoreX509TrustManager] [pool-3-thread-1] INFO  - Loading from trust store: C:/ProgramData/VMware/VSA Manager/security/keystore.jks

      2013-12-22 10:25:54,163 162 [DbUpgrade] [pool-3-thread-1] INFO  - Checking vsaDB consistency

      2013-12-22 10:25:54,194 150 [DbUpgrade] [pool-3-thread-1] INFO  - Backing up vsaDB

      2013-12-22 10:25:54,736 57 [ExtService] [pool-3-thread-1] INFO  - Login to VC

      2013-12-22 10:25:55,989 105 [KeyStoreConfig] [Timeout guard] INFO  - loading keystore from classpath: rui.pfx

      2013-12-22 10:25:55,989 111 [KeyStoreConfig] [Timeout guard] INFO  - keystore is loaded.

      2013-12-22 10:25:56,004 97 [CustomSslSocketFactory] [Timeout guard] INFO  - SSL Socket factory initialized

      2013-12-22 10:25:56,422 41 [SanityServices] [pool-3-thread-1] INFO  - Starting license enforcement

      2013-12-22 10:25:56,438 63 [SanityServices] [pool-3-thread-1] INFO  - Start monitoring VSAManager.log at Mon 2013.12.23 at 02:00:00 AM PST

      2013-12-22 10:25:56,716 119 [SvaLicenseManager] [SanityService-4] INFO  - Changing license assignment manager to SOLUTION

      2013-12-22 10:25:56,716 656 [SvaSolutionLicenseManager] [SanityService-4] INFO  - Uploading DLFs

      2013-12-22 10:25:56,732 318 [LicenseUtils] [SanityService-4] INFO  - Uploading license file: C:\Program Files\VMware\Infrastructure\tomcat\webapps\VSAManager\WEB-INF\classes\licenses\license-vsa-50-e1-t1-201006.txt

      2013-12-22 10:25:56,763 99 [SessionService] [pool-3-thread-1] INFO  - Finished initializing all context tasks

      2013-12-22 10:25:56,840 668 [SvaSolutionLicenseManager] [SanityService-4] WARN  - Unable to upload license file. File already exists: C:\Program Files\VMware\Infrastructure\tomcat\webapps\VSAManager\WEB-INF\classes\licenses\license-vsa-50-e1-t1-201006.txt

      2013-12-22 10:25:56,855 489 [SvaSolutionLicenseManager] [SanityService-4] INFO  - Registering VSA License entity with version: 5.0 and expiration date : 2014-02-22

      2013-12-22 10:25:57,196 235 [Utils] [pool-3-thread-1] INFO  - Retrieve dev.property: test.on=false

      2013-12-22 10:25:57,211 500 [SvaSolutionLicenseManager] [SanityService-4] WARN  - Exception while trying to register VSA license entity. Entity already exists.

      2013-12-22 10:25:57,227 545 [SvaSolutionLicenseManager] [SanityService-4] INFO  - VSA License entity already has an assigned license

      2013-12-22 10:25:57,459 145 [SvaLicenseManager] [LicenseManager-1] INFO  - Days before license expiration :: 61

      2013-12-22 10:25:57,459 85 [SvaLicenseManager] [LicenseManager-1] INFO  - License verified and found satisfactory

      2013-12-22 10:28:13,957 363 [ExtManager] [tomcat-http--48] INFO  - /sva-scriptConfig.xml : <?xml version="1.0"?>

      <scriptConfiguration version="1.0">

        <key>com.vmware.sva</key>

        <description>VSA Manager</description>

        <extension parent="InventoryView.Datacenter">

         <title locale="en">VSA Manager</title>

         <url>https://172.30.25.7:443/VSAManager/SVAUI/SVA.jsp</url>

        </extension>

        <extension parent="MainMenus.Help">

         <title locale="en">VSA Manager Help</title>

         <url>https://172.30.25.7:443/VSAManager/SVAUI/help/en_US/index.html</url>

        </extension>

      </scriptConfiguration>

       

       

      2013-12-22 10:30:56,343 1465 [ClusterService] [SanityService-4] WARN  - Any hosts that were previously added to the Datacenter will not have their IPs added to the ACL unless more hosts are added/removed.

      2013-12-22 10:30:56,467 1479 [ClusterService] [SanityService-4] INFO  - No Hosts have been added/removed since: 13-12-22 10:30:56

      2013-12-22 10:31:38,941 83 [SVAManager] [tomcat-http--11] INFO  - Requesting creation of a user session from http session context

      2013-12-22 10:31:38,956 113 [UserSession] [tomcat-http--11] INFO  - Http session ID is 4B92238832441F9CE5513330212C6307

      2013-12-22 10:31:38,956 117 [UserSession] [tomcat-http--11] INFO  - vc Session ID 527b5820-0df3-5e2b-ec8c-dab3564f4848

      2013-12-22 10:31:38,956 118 [UserSession] [tomcat-http--11] INFO  - Datacenter moref is Datacenter:datacenter-21

      2013-12-22 10:31:38,956 130 [UserSession] [tomcat-http--11] INFO  - Starting to enumerate values for FlexSession

      2013-12-22 10:31:38,956 139 [UserSession] [tomcat-http--11] ERROR - Unable to display value for attribute__flexSession

      2013-12-22 10:31:38,956 136 [UserSession] [tomcat-http--11] INFO  - Attribute Name extensionMoref--Attribute Value: Datacenter:datacenter-21

      2013-12-22 10:31:38,956 136 [UserSession] [tomcat-http--11] INFO  - Attribute Name vcSessionId--Attribute Value: 527b5820-0df3-5e2b-ec8c-dab3564f4848

      2013-12-22 10:31:38,956 177 [UserSession] [tomcat-http--11] INFO  - Creating a User session using sessionId 527b5820-0df3-5e2b-ec8c-dab3564f4848

      2013-12-22 10:31:38,972 107 [VCUserSession] [tomcat-http--11] INFO  - Creating a VC User session using sessionId 527b5820-0df3-5e2b-ec8c-dab3564f4848

      2013-12-22 10:31:40,187 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:40,374 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:40,374 124 [MappingService] [tomcat-http--11] INFO  - Context for datacenter datacenter-21 has not been set up yet.

      2013-12-22 10:31:40,374 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:40,592 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:40,669 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:40,856 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:40,856 124 [MappingService] [tomcat-http--11] INFO  - Context for datacenter datacenter-21 has not been set up yet.

      2013-12-22 10:31:40,919 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:41,105 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:41,152 124 [MappingService] [tomcat-http--11] INFO  - Context for datacenter datacenter-21 has not been set up yet.

      2013-12-22 10:31:41,510 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:41,697 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:41,697 124 [MappingService] [tomcat-http--11] INFO  - Context for datacenter datacenter-21 has not been set up yet.

      2013-12-22 10:31:41,697 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:41,915 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:41,915 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:42,087 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:42,087 124 [MappingService] [tomcat-http--11] INFO  - Context for datacenter datacenter-21 has not been set up yet.

      2013-12-22 10:31:42,149 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:42,367 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: host.audit=true

      2013-12-22 10:31:43,301 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:31:43,379 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.local=true

      2013-12-22 10:31:43,379 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.relative.path=/ovf/sva_OVF10.ovf

      2013-12-22 10:31:43,379 729 [Utils] [tomcat-http--11] INFO  - Retrieved default ovf url: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_OVF10.ovf

      2013-12-22 10:31:43,659 200 [OvfData] [tomcat-http--11] INFO  - Default disk size 1000 MB

      2013-12-22 10:31:43,800 213 [OvfData] [tomcat-http--11] INFO  - Total system disk size 9216 MB

      2013-12-22 10:31:43,800 216 [OvfData] [tomcat-http--11] INFO  - Total initial reserved disk size 22528 MB

      2013-12-22 10:31:44,516 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:31:44,547 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:31:44,609 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.local=true

      2013-12-22 10:31:44,609 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.relative.path=/ovf/sva_OVF10.ovf

      2013-12-22 10:31:44,609 729 [Utils] [tomcat-http--11] INFO  - Retrieved default ovf url: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_OVF10.ovf

      2013-12-22 10:31:44,672 200 [OvfData] [tomcat-http--11] INFO  - Default disk size 1000 MB

      2013-12-22 10:31:44,749 213 [OvfData] [tomcat-http--11] INFO  - Total system disk size 9216 MB

      2013-12-22 10:31:44,749 216 [OvfData] [tomcat-http--11] INFO  - Total initial reserved disk size 22528 MB

      2013-12-22 10:31:44,765 1282 [VCHostAudit] [tomcat-http--11] INFO  - Datastore pochesxi2-ds01 state is green

      2013-12-22 10:31:44,765 881 [VCHostAudit] [tomcat-http--11] INFO  - Host host-40 hardware configuration:

        vendor: Dell Inc.

        vSphere product version: 5.1.0

        vSphere product: embeddedEsx

        CPU speed in GHz: 2,899

        Memory capacity in GB: 47

        Number of NICs: 8

      2013-12-22 10:31:44,968 474 [VCUserSession] [tomcat-http--11] INFO  - Host Info retrieved - HostDao [refMorID=host-40, name=172.30.25.143, processorTypes=[intel, intel], statusDetails=Status is ONLINE, auditPassed=true, auditReport=null, isHCL=true, netmask=255.255.255.224, gateway=172.30.25.129, subnet=172.30.25.128, totalCapacityInBytes=2986881318912, freeCapacityInBytes=2985840082944, usedCapacityInBytes=1041235968, usedOtherCapacityInBytes=14999879680, totalVsaCapacityInBytes=2971881439232, freeVsaCapacityInBytes=2971881439232, usedVsaCapacityInBytes=0, minVsaCapacityInBytes=18253611008, reservedVsaCapacityInBytes=13958643712, status=ONLINE, group=UNSUPPORTED, vsaHost=false, hostAgentApiVersion=5.1, hostAgentVersion=5.1.0]

      2013-12-22 10:31:45,045 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:31:45,123 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.local=true

      2013-12-22 10:31:45,123 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.relative.path=/ovf/sva_OVF10.ovf

      2013-12-22 10:31:45,123 729 [Utils] [tomcat-http--11] INFO  - Retrieved default ovf url: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_OVF10.ovf

      2013-12-22 10:31:45,186 200 [OvfData] [tomcat-http--11] INFO  - Default disk size 1000 MB

      2013-12-22 10:31:45,341 213 [OvfData] [tomcat-http--11] INFO  - Total system disk size 9216 MB

      2013-12-22 10:31:45,341 216 [OvfData] [tomcat-http--11] INFO  - Total initial reserved disk size 22528 MB

      2013-12-22 10:31:45,606 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:31:45,637 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:31:45,699 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.local=true

      2013-12-22 10:31:45,699 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.relative.path=/ovf/sva_OVF10.ovf

      2013-12-22 10:31:45,699 729 [Utils] [tomcat-http--11] INFO  - Retrieved default ovf url: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_OVF10.ovf

      2013-12-22 10:31:45,762 200 [OvfData] [tomcat-http--11] INFO  - Default disk size 1000 MB

      2013-12-22 10:31:45,808 213 [OvfData] [tomcat-http--11] INFO  - Total system disk size 9216 MB

      2013-12-22 10:31:45,808 216 [OvfData] [tomcat-http--11] INFO  - Total initial reserved disk size 22528 MB

      2013-12-22 10:31:45,824 1282 [VCHostAudit] [tomcat-http--11] INFO  - Datastore pochesxi1-ds01 state is green

      2013-12-22 10:31:45,824 881 [VCHostAudit] [tomcat-http--11] INFO  - Host host-31 hardware configuration:

        vendor: Dell Inc.

        vSphere product version: 5.1.0

        vSphere product: embeddedEsx

        CPU speed in GHz: 2,899

        Memory capacity in GB: 47

        Number of NICs: 8

      2013-12-22 10:31:45,949 474 [VCUserSession] [tomcat-http--11] INFO  - Host Info retrieved - HostDao [refMorID=host-31, name=172.30.25.139, processorTypes=[intel, intel], statusDetails=Status is ONLINE, auditPassed=true, auditReport=null, isHCL=true, netmask=255.255.255.224, gateway=172.30.25.129, subnet=172.30.25.128, totalCapacityInBytes=2986881318912, freeCapacityInBytes=2985840082944, usedCapacityInBytes=1041235968, usedOtherCapacityInBytes=14999879680, totalVsaCapacityInBytes=2971881439232, freeVsaCapacityInBytes=2971881439232, usedVsaCapacityInBytes=0, minVsaCapacityInBytes=18253611008, reservedVsaCapacityInBytes=13958643712, status=ONLINE, group=UNSUPPORTED, vsaHost=false, hostAgentApiVersion=5.1, hostAgentVersion=5.1.0]

      2013-12-22 10:31:46,026 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:31:46,151 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.local=true

      2013-12-22 10:31:46,151 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.relative.path=/ovf/sva_OVF10.ovf

      2013-12-22 10:31:46,151 729 [Utils] [tomcat-http--11] INFO  - Retrieved default ovf url: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_OVF10.ovf

      2013-12-22 10:31:46,213 200 [OvfData] [tomcat-http--11] INFO  - Default disk size 1000 MB

      2013-12-22 10:31:46,260 213 [OvfData] [tomcat-http--11] INFO  - Total system disk size 9216 MB

      2013-12-22 10:31:46,260 216 [OvfData] [tomcat-http--11] INFO  - Total initial reserved disk size 22528 MB

      2013-12-22 10:31:46,774 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:31:48,035 720 [VCHostAudit] [tomcat-http--11] INFO  - Host: 172.30.25.135 Available Memory (bytes): 46986690560 Total Memory (bytes): 51491872768 Minimum Memory (gb): 1

      2013-12-22 10:31:48,035 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ha.config=true

      2013-12-22 10:31:48,067 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:31:48,129 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.local=true

      2013-12-22 10:31:48,129 235 [Utils] [tomcat-http--11] INFO  - Retrieve dev.property: ovf.relative.path=/ovf/sva_OVF10.ovf

      2013-12-22 10:31:48,129 729 [Utils] [tomcat-http--11] INFO  - Retrieved default ovf url: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_OVF10.ovf

      2013-12-22 10:31:48,191 200 [OvfData] [tomcat-http--11] INFO  - Default disk size 1000 MB

      2013-12-22 10:31:48,238 213 [OvfData] [tomcat-http--11] INFO  - Total system disk size 9216 MB

      2013-12-22 10:31:48,238 216 [OvfData] [tomcat-http--11] INFO  - Total initial reserved disk size 22528 MB

      2013-12-22 10:31:48,253 1282 [VCHostAudit] [tomcat-http--11] INFO  - Datastore pochesxi0-ds01 state is green

      2013-12-22 10:31:48,253 881 [VCHostAudit] [tomcat-http--11] INFO  - Host host-28 hardware configuration:

        vendor: Dell Inc.

        vSphere product version: 5.1.0

        vSphere product: embeddedEsx

        CPU speed in GHz: 2,899

        Memory capacity in GB: 47

        Number of NICs: 8

      2013-12-22 10:31:48,378 474 [VCUserSession] [tomcat-http--11] INFO  - Host Info retrieved - HostDao [refMorID=host-28, name=172.30.25.135, processorTypes=[intel, intel], statusDetails=Status is ONLINE, auditPassed=true, auditReport=[SvaManagerException: Host Audit Failed. Key: AUTOSTART_SET_ON_VM, SvaManagerException: Host Audit Failed. Key: INVALID_PHYSICAL_NIC_TO_SWITCH_CONNECTIVITY], isHCL=true, netmask=255.255.255.224, gateway=172.30.25.129, subnet=172.30.25.128, totalCapacityInBytes=2986881318912, freeCapacityInBytes=2953772531712, usedCapacityInBytes=33108787200, usedOtherCapacityInBytes=47067430912, totalVsaCapacityInBytes=2939813888000, freeVsaCapacityInBytes=2939813888000, usedVsaCapacityInBytes=0, minVsaCapacityInBytes=18253611008, reservedVsaCapacityInBytes=13958643712, status=ONLINE, group=UNSUPPORTED, vsaHost=false, hostAgentApiVersion=5.1, hostAgentVersion=5.1.0]

      2013-12-22 10:31:48,534 85 [IphlpManager] [tomcat-http--11] INFO  - Found adapter for IP address: 172.30.25.7

        name = Local Area Connection

        netmask = 255.255.255.128

        gateway=172.30.25.1

      2013-12-22 10:31:48,580 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:48,752 196 [UserSession] [tomcat-http--11] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:31:48,798 124 [MappingService] [tomcat-http--11] INFO  - Context for datacenter datacenter-21 has not been set up yet.

      2013-12-22 10:32:08,669 126 [SVAManager] [tomcat-http--11] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:32:38,680 126 [SVAManager] [tomcat-http--11] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:33:09,022 126 [SVAManager] [tomcat-http--11] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:33:39,171 126 [SVAManager] [tomcat-http--11] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:33:44,725 196 [UserSession] [tomcat-http--43] INFO  - **** extenMoref **** Datacenter:datacenter-21

      2013-12-22 10:33:44,911 235 [Utils] [tomcat-http--43] INFO  - Retrieve dev.property: ovf.local=true

      2013-12-22 10:33:44,911 235 [Utils] [tomcat-http--43] INFO  - Retrieve dev.property: ovf.relative.path=/ovf/sva_OVF10.ovf

      2013-12-22 10:33:44,911 729 [Utils] [tomcat-http--43] INFO  - Retrieved default ovf url: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_OVF10.ovf

      2013-12-22 10:33:44,927 235 [Utils] [tomcat-http--43] INFO  - Retrieve dev.property: vm.rollback=true

      2013-12-22 10:33:45,035 45 [DefaultJobRunner] [pool-109-thread-1] INFO  - Starting job. JobName:ClusterCreation-datacenter-21

      2013-12-22 10:33:45,035 58 [DefaultJobRunner] [pool-109-thread-1] INFO  - Starting Task group. TaskGroupName:ClusterCreation-PreHostReboot

      2013-12-22 10:33:45,051 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:TaskInitCreateCluster

      2013-12-22 10:33:45,066 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:TaskInitCreateCluster

      2013-12-22 10:33:45,315 157 [SvaSolutionLicenseManager] [pool-109-thread-1] INFO  - AcquireLicense - Updating license count to : 1

      2013-12-22 10:33:45,423 361 [Utils] [pool-109-thread-1] INFO  - Persisting host: host-40

      2013-12-22 10:33:45,485 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-Back End @ hostmoref: host-40

      2013-12-22 10:33:45,501 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-Front End @ hostmoref: host-40

      2013-12-22 10:33:45,516 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-NFS @ hostmoref: host-40

      2013-12-22 10:33:45,516 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-VMotion @ hostmoref: host-40

      2013-12-22 10:33:45,578 361 [Utils] [pool-109-thread-1] INFO  - Persisting host: host-31

      2013-12-22 10:33:45,640 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-Back End @ hostmoref: host-31

      2013-12-22 10:33:45,640 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-Front End @ hostmoref: host-31

      2013-12-22 10:33:45,656 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-NFS @ hostmoref: host-31

      2013-12-22 10:33:45,656 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-VMotion @ hostmoref: host-31

      2013-12-22 10:33:45,780 361 [Utils] [pool-109-thread-1] INFO  - Persisting host: host-28

      2013-12-22 10:33:45,780 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-Back End @ hostmoref: host-28

      2013-12-22 10:33:45,796 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-Front End @ hostmoref: host-28

      2013-12-22 10:33:45,796 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-NFS @ hostmoref: host-28

      2013-12-22 10:33:45,811 369 [Utils] [pool-109-thread-1] INFO  - Persisting svaNetwork: VSA-VMotion @ hostmoref: host-28

      2013-12-22 10:33:45,842 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:TaskInitCreateCluster

      2013-12-22 10:33:45,842 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:TaskInitCreateCluster

      2013-12-22 10:33:45,842 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:LogParams

      2013-12-22 10:33:45,842 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:LogParams

      2013-12-22 10:33:45,842 101 [TaskLogClusterCreationParams] [pool-109-thread-1] INFO  - Dump VSA Cluster Configuration

       

       

      VSA Management Network Settings

                   IP Address        Netmask           Gateway/Interface  

      Cluster   : 172.30.25.134     255.255.255.224   172.30.25.129      

       

       

       

       

      VSA VM Network Settings :  host-40

                   IP Address        Netmask           Gateway           Vlan ID

      Management: 172.30.25.144     255.255.255.224   172.30.25.129     502    

      NFS       : 172.30.25.145     255.255.255.224   172.30.25.129     502    

      Backend   : 192.168.0.3       255.255.255.0     -                 504    

      Feature   : 172.30.25.146     255.255.255.224   172.30.25.129     502    

       

       

      VSA VM Network Settings :  host-31

                   IP Address        Netmask           Gateway           Vlan ID

      Management: 172.30.25.140     255.255.255.224   172.30.25.129     502    

      NFS       : 172.30.25.141     255.255.255.224   172.30.25.129     502    

      Backend   : 192.168.0.2       255.255.255.0     -                 504    

      Feature   : 172.30.25.142     255.255.255.224   172.30.25.129     502    

       

       

      VSA VM Network Settings :  host-28

                   IP Address        Netmask           Gateway           Vlan ID

      Management: 172.30.25.136     255.255.255.224   172.30.25.129     502    

      NFS       : 172.30.25.137     255.255.255.224   172.30.25.129     502    

      Backend   : 192.168.0.1       255.255.255.0     -                 504    

      Feature   : 172.30.25.138     255.255.255.224   172.30.25.129     502    

       

       

      OVF Location: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_OVF10.ovf

      Eager Zero  : true

       

       

      2013-12-22 10:33:45,842 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:LogParams

      2013-12-22 10:33:45,842 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:LogParams

      2013-12-22 10:33:45,842 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:ClusterExists

      2013-12-22 10:33:45,842 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:ClusterExists

      2013-12-22 10:33:45,889 433 [SessionService] [pool-109-thread-1] INFO  - Cluster create in progress for DC: datacenter-21

      2013-12-22 10:33:45,889 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:ClusterExists

      2013-12-22 10:33:45,889 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:ClusterExists

      2013-12-22 10:33:45,889 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:AuditHostGroup

      2013-12-22 10:33:45,904 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:AuditHostGroup

      2013-12-22 10:33:52,265 235 [Utils] [pool-109-thread-1] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:33:52,265 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:AuditHostGroup

      2013-12-22 10:33:52,265 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:AuditHostGroup

      2013-12-22 10:33:52,265 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:NetworkConfigValidation

      2013-12-22 10:33:52,265 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:NetworkConfigValidation

      2013-12-22 10:34:00,379 436 [NetworkConfigValidator] [pool-109-thread-1] INFO  - Cluster's subnet is 172.30.25.128. Gateway is 172.30.25.129

      2013-12-22 10:34:00,379 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:NetworkConfigValidation

      2013-12-22 10:34:00,379 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:NetworkConfigValidation

      2013-12-22 10:34:00,394 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:Audit2N-LV

      2013-12-22 10:34:00,394 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:Audit2N-LV

      2013-12-22 10:34:00,394 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:Audit2N-LV

      2013-12-22 10:34:00,394 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:Audit2N-LV

      2013-12-22 10:34:00,394 65 [DefaultJobRunner] [pool-109-thread-1] INFO  - TaskGroup execution finished successfully. TaskGroupName:ClusterCreation-PreHostReboot

      2013-12-22 10:34:00,394 58 [DefaultJobRunner] [pool-109-thread-1] INFO  - Starting Task group. TaskGroupName:Set Hosts VMFS Heap Size

      2013-12-22 10:34:00,394 49 [DefaultTaskRunner] [pool-110-thread-2] INFO  - Notifying listeners of task begin. TaskID:SetVMFSHeapSizehost-31

      2013-12-22 10:34:00,394 69 [DefaultTaskRunner] [pool-110-thread-2] INFO  - Starting execution of task. TaskID:SetVMFSHeapSizehost-31

      2013-12-22 10:34:00,394 49 [DefaultTaskRunner] [pool-110-thread-1] INFO  - Notifying listeners of task begin. TaskID:SetVMFSHeapSizehost-40

      2013-12-22 10:34:00,394 69 [DefaultTaskRunner] [pool-110-thread-1] INFO  - Starting execution of task. TaskID:SetVMFSHeapSizehost-40

      2013-12-22 10:34:00,394 49 [DefaultTaskRunner] [pool-110-thread-3] INFO  - Notifying listeners of task begin. TaskID:SetVMFSHeapSizehost-28

      2013-12-22 10:34:00,394 69 [DefaultTaskRunner] [pool-110-thread-3] INFO  - Starting execution of task. TaskID:SetVMFSHeapSizehost-28

      2013-12-22 10:34:00,829 71 [DefaultTaskRunner] [pool-110-thread-2] INFO  - Task execution finished with result:true. TaskID:SetVMFSHeapSizehost-31

      2013-12-22 10:34:00,829 73 [DefaultTaskRunner] [pool-110-thread-2] INFO  - Notifying listeners of task end. TaskID:SetVMFSHeapSizehost-31

      2013-12-22 10:34:00,922 71 [DefaultTaskRunner] [pool-110-thread-3] INFO  - Task execution finished with result:true. TaskID:SetVMFSHeapSizehost-28

      2013-12-22 10:34:00,922 73 [DefaultTaskRunner] [pool-110-thread-3] INFO  - Notifying listeners of task end. TaskID:SetVMFSHeapSizehost-28

      2013-12-22 10:34:00,922 71 [DefaultTaskRunner] [pool-110-thread-1] INFO  - Task execution finished with result:true. TaskID:SetVMFSHeapSizehost-40

      2013-12-22 10:34:00,922 73 [DefaultTaskRunner] [pool-110-thread-1] INFO  - Notifying listeners of task end. TaskID:SetVMFSHeapSizehost-40

      2013-12-22 10:34:00,922 65 [DefaultJobRunner] [pool-109-thread-1] INFO  - TaskGroup execution finished successfully. TaskGroupName:Set Hosts VMFS Heap Size

      2013-12-22 10:34:00,922 58 [DefaultJobRunner] [pool-109-thread-1] INFO  - Starting Task group. TaskGroupName:ClusterCreation-PostHostReboot

      2013-12-22 10:34:00,922 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:Run2NLV

      2013-12-22 10:34:00,922 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:Run2NLV

      2013-12-22 10:34:00,922 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:Run2NLV

      2013-12-22 10:34:00,922 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:Run2NLV

      2013-12-22 10:34:00,922 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:AddTagsToHosts

      2013-12-22 10:34:00,922 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:AddTagsToHosts

      2013-12-22 10:34:00,999 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:AddTagsToHosts

      2013-12-22 10:34:00,999 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:AddTagsToHosts

      2013-12-22 10:34:00,999 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:NetworkConfig

      2013-12-22 10:34:00,999 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:NetworkConfig

      2013-12-22 10:34:01,372 235 [Utils] [pool-109-thread-1] INFO  - Retrieve dev.property: network.config=true

      2013-12-22 10:34:01,372 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:NetworkConfig

      2013-12-22 10:34:01,372 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:NetworkConfig

      2013-12-22 10:34:01,372 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:ConfigureHA

      2013-12-22 10:34:01,372 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:ConfigureHA

      2013-12-22 10:34:01,372 235 [Utils] [pool-109-thread-1] INFO  - Retrieve dev.property: ha.config=true

      2013-12-22 10:34:01,372 235 [Utils] [pool-109-thread-1] INFO  - Retrieve dev.property: ha.config=true

      2013-12-22 10:34:01,418 82 [HAClusterConfigurator] [pool-109-thread-1] INFO  - Now configuring admission control.

      2013-12-22 10:34:01,558 235 [Utils] [pool-109-thread-1] INFO  - Retrieve dev.property: evc.config=false

      2013-12-22 10:34:09,036 126 [SVAManager] [tomcat-http--28] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:34:38,924 126 [SVAManager] [tomcat-http--43] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:35:08,903 126 [SVAManager] [tomcat-http--14] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:35:38,831 126 [SVAManager] [tomcat-http--14] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:35:55,936 76 [CheckVSAHostUpgradedTask] [SanityService-5] INFO  - VSA Clusters successfully checked for memory-overcommitment options

      2013-12-22 10:35:56,497 1505 [ClusterService] [SanityService-5] INFO  - Host added/removed from/to DC: datacenter-21, which does not contain a VSA Cluster.

      2013-12-22 10:36:08,836 126 [SVAManager] [tomcat-http--24] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:36:38,838 126 [SVAManager] [tomcat-http--12] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:37:08,817 126 [SVAManager] [tomcat-http--12] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:37:38,802 126 [SVAManager] [tomcat-http--48] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:38:08,914 126 [SVAManager] [tomcat-http--19] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:38:38,969 126 [SVAManager] [tomcat-http--25] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:38:53,416 258 [HAClusterConfigurator] [pool-109-thread-1] INFO  - Task of configuring HA on host 172.30.25.135 succeeded

      2013-12-22 10:38:53,712 258 [HAClusterConfigurator] [pool-109-thread-1] INFO  - Task of configuring HA on host 172.30.25.139 succeeded

      2013-12-22 10:38:53,790 258 [HAClusterConfigurator] [pool-109-thread-1] INFO  - Task of configuring HA on host 172.30.25.143 succeeded

      2013-12-22 10:38:53,821 71 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task execution finished with result:true. TaskID:ConfigureHA

      2013-12-22 10:38:53,821 73 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task end. TaskID:ConfigureHA

      2013-12-22 10:38:53,821 49 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task begin. TaskID:InstallsVSAVMs

      2013-12-22 10:38:53,821 69 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting execution of task. TaskID:InstallsVSAVMs

      2013-12-22 10:38:53,868 1450 [VCUtils] [pool-109-thread-1] INFO  - New VSA VM will start from index : 0

      2013-12-22 10:38:53,868 40 [TaskInstallVSAVMs] [pool-109-thread-1] INFO  - VSA VMs will be installed from index:0

      2013-12-22 10:38:53,899 200 [OvfData] [pool-109-thread-1] INFO  - Default disk size 1000 MB

      2013-12-22 10:38:53,915 213 [OvfData] [pool-109-thread-1] INFO  - Total system disk size 9216 MB

      2013-12-22 10:38:53,915 216 [OvfData] [pool-109-thread-1] INFO  - Total initial reserved disk size 22528 MB

      2013-12-22 10:38:53,915 45 [TaskInstallVSAVMs] [pool-109-thread-1] INFO  - OVF Data initialized

      2013-12-22 10:38:53,915 147 [VCUserSession] [pool-109-thread-1] INFO  - OVF import

      2013-12-22 10:38:54,335 183 [VAppImporter] [TaskExecutionService-3] INFO  - Set flag for contiguous allocation

      2013-12-22 10:38:54,335 183 [VAppImporter] [TaskExecutionService-1] INFO  - Set flag for contiguous allocation

      2013-12-22 10:38:54,351 183 [VAppImporter] [TaskExecutionService-2] INFO  - Set flag for contiguous allocation

      2013-12-22 10:38:56,810 209 [VAppImporter] [TaskExecutionService-3] INFO  -

      Host "172.30.25.135" new configuration values for contiguous allocation:

                   VMFS3.EnableSVAVMFS -    1             

                   Misc.VmkStressEnable-    0             

       

       

      2013-12-22 10:38:56,919 506 [VAppImporter] [TaskExecutionService-3] INFO  - Host host-28's VMFS datastore pochesxi0-ds01 state is green

      2013-12-22 10:38:57,464 235 [Utils] [TaskExecutionService-3] INFO  - Retrieve dev.property: disk.size.default=false

      2013-12-22 10:38:57,682 209 [VAppImporter] [TaskExecutionService-2] INFO  -

      Host "172.30.25.139" new configuration values for contiguous allocation:

                   VMFS3.EnableSVAVMFS -    1             

                   Misc.VmkStressEnable-    0             

       

       

      2013-12-22 10:38:57,698 209 [VAppImporter] [TaskExecutionService-1] INFO  -

      Host "172.30.25.143" new configuration values for contiguous allocation:

                   VMFS3.EnableSVAVMFS -    1             

                   Misc.VmkStressEnable-    0             

       

       

      2013-12-22 10:38:57,744 1145 [VAppImporter] [TaskExecutionService-3] INFO  -

      Host "172.30.25.135" VMFS datastore disk data (bytes)

                   free space          -    2916280696832 

                   system disk space   -    9663676416    

                   user selected space -    true          

                   user selected value -    2850698643374 

                   reserved disk space -    23622320128   

                   data disk number    -    16            

                   data disk size      -    176692270202  

       

       

      2013-12-22 10:38:57,744 357 [VAppImporter] [TaskExecutionService-3] INFO  - Set data disk size 168506 MB

      2013-12-22 10:38:57,791 506 [VAppImporter] [TaskExecutionService-2] INFO  - Host host-31's VMFS datastore pochesxi1-ds01 state is green

      2013-12-22 10:38:57,807 506 [VAppImporter] [TaskExecutionService-1] INFO  - Host host-40's VMFS datastore pochesxi2-ds01 state is green

      2013-12-22 10:38:58,025 235 [Utils] [TaskExecutionService-1] INFO  - Retrieve dev.property: disk.size.default=false

      2013-12-22 10:38:58,118 235 [Utils] [TaskExecutionService-2] INFO  - Retrieve dev.property: disk.size.default=false

      2013-12-22 10:38:59,083 1145 [VAppImporter] [TaskExecutionService-1] INFO  -

      Host "172.30.25.143" VMFS datastore disk data (bytes)

                   free space          -    2985835888640 

                   system disk space   -    9663676416    

                   user selected space -    true          

                   user selected value -    2850698643374 

                   reserved disk space -    23622320128   

                   data disk number    -    16            

                   data disk size      -    176692270202  

       

       

      2013-12-22 10:38:59,083 357 [VAppImporter] [TaskExecutionService-1] INFO  - Set data disk size 168506 MB

      2013-12-22 10:38:59,099 1145 [VAppImporter] [TaskExecutionService-2] INFO  -

      Host "172.30.25.139" VMFS datastore disk data (bytes)

                   free space          -    2985837985792 

                   system disk space   -    9663676416    

                   user selected space -    true          

                   user selected value -    2850698643374 

                   reserved disk space -    23622320128   

                   data disk number    -    16            

                   data disk size      -    176692270202  

       

       

      2013-12-22 10:38:59,099 357 [VAppImporter] [TaskExecutionService-2] INFO  - Set data disk size 168506 MB

      2013-12-22 10:39:08,907 126 [SVAManager] [tomcat-http--25] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:39:38,880 126 [SVAManager] [tomcat-http--21] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:40:10,679 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:40:41,529 126 [SVAManager] [tomcat-http--48] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:40:58,693 1479 [ClusterService] [SanityService-5] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 10:41:10,718 126 [SVAManager] [tomcat-http--48] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:41:40,179 126 [SVAManager] [tomcat-http--36] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:42:09,770 126 [SVAManager] [tomcat-http--18] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:42:39,516 126 [SVAManager] [tomcat-http--18] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:43:09,361 126 [SVAManager] [tomcat-http--10] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:43:39,242 126 [SVAManager] [tomcat-http--30] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:44:09,141 126 [SVAManager] [tomcat-http--2] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:44:39,073 126 [SVAManager] [tomcat-http--2] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:45:09,715 126 [SVAManager] [tomcat-http--44] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:45:40,121 126 [SVAManager] [tomcat-http--39] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:45:57,548 1479 [ClusterService] [SanityService-5] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 10:46:09,751 126 [SVAManager] [tomcat-http--39] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:46:39,538 126 [SVAManager] [tomcat-http--24] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:47:09,355 126 [SVAManager] [tomcat-http--5] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:47:39,279 126 [SVAManager] [tomcat-http--32] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:48:09,203 126 [SVAManager] [tomcat-http--32] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:48:39,173 126 [SVAManager] [tomcat-http--23] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:49:09,127 126 [SVAManager] [tomcat-http--17] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:49:39,174 126 [SVAManager] [tomcat-http--36] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:50:09,421 126 [SVAManager] [tomcat-http--36] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:50:39,685 126 [SVAManager] [tomcat-http--32] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:50:57,039 1479 [ClusterService] [SanityService-4] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 10:51:09,519 126 [SVAManager] [tomcat-http--50] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:51:39,434 126 [SVAManager] [tomcat-http--50] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:52:09,359 126 [SVAManager] [tomcat-http--48] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:52:39,355 126 [SVAManager] [tomcat-http--22] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:53:09,348 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:53:39,307 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:54:09,319 126 [SVAManager] [tomcat-http--32] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:54:39,352 126 [SVAManager] [tomcat-http--16] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:55:09,504 126 [SVAManager] [tomcat-http--16] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:55:39,622 126 [SVAManager] [tomcat-http--44] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:55:56,886 1479 [ClusterService] [SanityService-3] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 10:56:09,589 126 [SVAManager] [tomcat-http--46] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:56:39,562 126 [SVAManager] [tomcat-http--45] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:57:10,740 126 [SVAManager] [tomcat-http--45] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:57:41,688 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:58:11,038 126 [SVAManager] [tomcat-http--25] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:58:40,665 126 [SVAManager] [tomcat-http--25] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:59:10,318 126 [SVAManager] [tomcat-http--10] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 10:59:40,116 126 [SVAManager] [tomcat-http--34] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:00:09,998 126 [SVAManager] [tomcat-http--15] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:00:39,936 126 [SVAManager] [tomcat-http--15] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:00:57,018 1479 [ClusterService] [SanityService-3] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:01:09,853 126 [SVAManager] [tomcat-http--3] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:01:39,799 126 [SVAManager] [tomcat-http--27] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:02:10,811 126 [SVAManager] [tomcat-http--11] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:02:40,713 126 [SVAManager] [tomcat-http--11] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:03:10,416 126 [SVAManager] [tomcat-http--6] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:03:40,268 126 [SVAManager] [tomcat-http--19] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:04:10,105 126 [SVAManager] [tomcat-http--19] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:04:43,042 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:05:09,980 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:05:39,947 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:05:56,977 1479 [ClusterService] [SanityService-2] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:06:09,909 126 [SVAManager] [tomcat-http--33] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:06:39,884 126 [SVAManager] [tomcat-http--19] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:07:10,074 126 [SVAManager] [tomcat-http--19] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:07:40,311 126 [SVAManager] [tomcat-http--49] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:08:10,221 126 [SVAManager] [tomcat-http--5] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:08:40,142 126 [SVAManager] [tomcat-http--5] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:09:10,143 126 [SVAManager] [tomcat-http--38] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:09:40,103 126 [SVAManager] [tomcat-http--1] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:10:10,119 126 [SVAManager] [tomcat-http--36] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:10:40,082 126 [SVAManager] [tomcat-http--36] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:10:57,000 1479 [ClusterService] [SanityService-1] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:11:10,098 126 [SVAManager] [tomcat-http--16] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:11:40,134 126 [SVAManager] [tomcat-http--5] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:12:10,202 126 [SVAManager] [tomcat-http--5] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:12:40,275 126 [SVAManager] [tomcat-http--45] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:13:10,270 126 [SVAManager] [tomcat-http--46] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:13:40,516 126 [SVAManager] [tomcat-http--34] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:14:10,722 126 [SVAManager] [tomcat-http--34] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:14:41,836 126 [SVAManager] [tomcat-http--12] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:15:11,304 126 [SVAManager] [tomcat-http--24] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:15:40,991 126 [SVAManager] [tomcat-http--12] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:15:57,728 1479 [ClusterService] [SanityService-1] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:16:10,768 126 [SVAManager] [tomcat-http--15] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:16:40,666 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:17:10,555 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:17:40,488 126 [SVAManager] [tomcat-http--32] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:18:10,456 126 [SVAManager] [tomcat-http--20] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:18:40,430 126 [SVAManager] [tomcat-http--20] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:19:10,634 126 [SVAManager] [tomcat-http--43] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:19:41,137 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:20:10,912 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:20:40,766 126 [SVAManager] [tomcat-http--31] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:20:57,427 1479 [ClusterService] [SanityService-1] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:21:10,654 126 [SVAManager] [tomcat-http--11] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:21:40,580 126 [SVAManager] [tomcat-http--14] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:22:10,524 126 [SVAManager] [tomcat-http--14] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:22:40,502 126 [SVAManager] [tomcat-http--27] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:23:10,474 126 [SVAManager] [tomcat-http--1] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:23:40,455 126 [SVAManager] [tomcat-http--27] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:24:10,520 126 [SVAManager] [tomcat-http--32] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:24:40,736 126 [SVAManager] [tomcat-http--19] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:25:10,784 126 [SVAManager] [tomcat-http--14] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:25:40,631 126 [SVAManager] [tomcat-http--14] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:25:57,314 1479 [ClusterService] [SanityService-1] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:26:10,616 126 [SVAManager] [tomcat-http--4] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:26:40,585 126 [SVAManager] [tomcat-http--40] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:27:10,562 126 [SVAManager] [tomcat-http--40] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:27:40,563 126 [SVAManager] [tomcat-http--21] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:28:10,567 126 [SVAManager] [tomcat-http--5] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:28:40,605 126 [SVAManager] [tomcat-http--9] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:29:10,633 126 [SVAManager] [tomcat-http--9] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:29:40,741 126 [SVAManager] [tomcat-http--13] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:30:10,748 126 [SVAManager] [tomcat-http--40] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:30:40,753 126 [SVAManager] [tomcat-http--40] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:30:57,325 1479 [ClusterService] [SanityService-1] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:31:10,764 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:31:41,876 126 [SVAManager] [tomcat-http--20] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:32:11,570 126 [SVAManager] [tomcat-http--7] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:32:41,346 126 [SVAManager] [tomcat-http--7] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:33:11,234 126 [SVAManager] [tomcat-http--11] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:33:41,121 126 [SVAManager] [tomcat-http--38] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:34:11,065 126 [SVAManager] [tomcat-http--27] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:34:40,993 126 [SVAManager] [tomcat-http--27] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:35:10,949 126 [SVAManager] [tomcat-http--25] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:35:43,944 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:35:57,413 1479 [ClusterService] [SanityService-5] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:36:10,913 126 [SVAManager] [tomcat-http--47] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:36:41,408 126 [SVAManager] [tomcat-http--42] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:37:09,004 820 [VAppImporter] [TaskExecutionService-2] INFO  - Checking local VMFS datastore for: /vsa-ovf/sva_system1.vmdk

      2013-12-22 11:37:09,004 907 [VAppImporter] [Thread-335] INFO  - LeaseProgressUpdater threads starts

      2013-12-22 11:37:09,081 506 [VAppImporter] [TaskExecutionService-2] INFO  - Host host-31's VMFS datastore pochesxi1-ds01 state is red

      2013-12-22 11:37:11,254 126 [SVAManager] [tomcat-http--17] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:37:18,456 820 [VAppImporter] [TaskExecutionService-1] INFO  - Checking local VMFS datastore for: /vsa-ovf/sva_system1.vmdk

      2013-12-22 11:37:18,456 907 [VAppImporter] [Thread-337] INFO  - LeaseProgressUpdater threads starts

      2013-12-22 11:37:18,534 506 [VAppImporter] [TaskExecutionService-1] INFO  - Host host-40's VMFS datastore pochesxi2-ds01 state is red

      2013-12-22 11:37:18,720 833 [VAppImporter] [TaskExecutionService-2] INFO  - Copying system disk file from VC: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_system1.vmdk to: https://172.30.25.139/nfc/52e83f0e-a737-9100-d1a2-6a556b12bd52/disk-16.vmdk

      2013-12-22 11:37:18,751 833 [VAppImporter] [TaskExecutionService-1] INFO  - Copying system disk file from VC: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_system1.vmdk to: https://172.30.25.143/nfc/52b03852-5e4e-d2a1-a095-49c387c6fb12/disk-16.vmdk

      2013-12-22 11:37:35,958 820 [VAppImporter] [TaskExecutionService-3] INFO  - Checking local VMFS datastore for: /vsa-ovf/sva_system1.vmdk

      2013-12-22 11:37:35,958 907 [VAppImporter] [Thread-339] INFO  - LeaseProgressUpdater threads starts

      2013-12-22 11:37:36,036 506 [VAppImporter] [TaskExecutionService-3] INFO  - Host host-28's VMFS datastore pochesxi0-ds01 state is red

      2013-12-22 11:37:36,223 833 [VAppImporter] [TaskExecutionService-3] INFO  - Copying system disk file from VC: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_system1.vmdk to: https://172.30.25.135/nfc/52628490-35c4-1c04-e69f-3a8f3dc2beb0/disk-16.vmdk

      2013-12-22 11:37:41,218 126 [SVAManager] [tomcat-http--17] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:38:11,203 126 [SVAManager] [tomcat-http--34] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:38:41,107 126 [SVAManager] [tomcat-http--29] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:38:41,558 403 [FileLoader] [TaskExecutionService-2] INFO  - Uploaded file takes 82 seconds

      2013-12-22 11:38:41,574 357 [FileLoader] [TaskExecutionService-2] INFO  - Uploaded file created on server

      2013-12-22 11:38:41,574 840 [VAppImporter] [TaskExecutionService-2] INFO  - Uploaded file: sva_system1.vmdk

      2013-12-22 11:38:41,574 820 [VAppImporter] [TaskExecutionService-2] INFO  - Checking local VMFS datastore for: /vsa-ovf/sva_system2.vmdk

      2013-12-22 11:38:41,652 506 [VAppImporter] [TaskExecutionService-2] INFO  - Host host-31's VMFS datastore pochesxi1-ds01 state is red

      2013-12-22 11:38:41,808 833 [VAppImporter] [TaskExecutionService-2] INFO  - Copying system disk file from VC: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_system2.vmdk to: https://172.30.25.139/nfc/52e83f0e-a737-9100-d1a2-6a556b12bd52/disk-17.vmdk

      2013-12-22 11:38:42,384 403 [FileLoader] [TaskExecutionService-1] INFO  - Uploaded file takes 83 seconds

      2013-12-22 11:38:42,384 357 [FileLoader] [TaskExecutionService-1] INFO  - Uploaded file created on server

      2013-12-22 11:38:42,384 840 [VAppImporter] [TaskExecutionService-1] INFO  - Uploaded file: sva_system1.vmdk

      2013-12-22 11:38:42,384 820 [VAppImporter] [TaskExecutionService-1] INFO  - Checking local VMFS datastore for: /vsa-ovf/sva_system2.vmdk

      2013-12-22 11:38:42,462 506 [VAppImporter] [TaskExecutionService-1] INFO  - Host host-40's VMFS datastore pochesxi2-ds01 state is red

      2013-12-22 11:38:42,633 833 [VAppImporter] [TaskExecutionService-1] INFO  - Copying system disk file from VC: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_system2.vmdk to: https://172.30.25.143/nfc/52b03852-5e4e-d2a1-a095-49c387c6fb12/disk-17.vmdk

      2013-12-22 11:38:42,883 403 [FileLoader] [TaskExecutionService-2] INFO  - Uploaded file takes 1 seconds

      2013-12-22 11:38:42,883 357 [FileLoader] [TaskExecutionService-2] INFO  - Uploaded file created on server

      2013-12-22 11:38:42,883 840 [VAppImporter] [TaskExecutionService-2] INFO  - Uploaded file: sva_system2.vmdk

      2013-12-22 11:38:42,898 626 [VAppImporter] [TaskExecutionService-2] INFO  - Uploading of the entity is successful

      2013-12-22 11:38:43,755 403 [FileLoader] [TaskExecutionService-1] INFO  - Uploaded file takes 1 seconds

      2013-12-22 11:38:43,771 357 [FileLoader] [TaskExecutionService-1] INFO  - Uploaded file created on server

      2013-12-22 11:38:43,771 840 [VAppImporter] [TaskExecutionService-1] INFO  - Uploaded file: sva_system2.vmdk

      2013-12-22 11:38:43,786 626 [VAppImporter] [TaskExecutionService-1] INFO  - Uploading of the entity is successful

      2013-12-22 11:38:45,734 403 [FileLoader] [TaskExecutionService-3] INFO  - Uploaded file takes 69 seconds

      2013-12-22 11:38:45,750 357 [FileLoader] [TaskExecutionService-3] INFO  - Uploaded file created on server

      2013-12-22 11:38:45,750 840 [VAppImporter] [TaskExecutionService-3] INFO  - Uploaded file: sva_system1.vmdk

      2013-12-22 11:38:45,750 820 [VAppImporter] [TaskExecutionService-3] INFO  - Checking local VMFS datastore for: /vsa-ovf/sva_system2.vmdk

      2013-12-22 11:38:45,827 506 [VAppImporter] [TaskExecutionService-3] INFO  - Host host-28's VMFS datastore pochesxi0-ds01 state is red

      2013-12-22 11:38:46,030 833 [VAppImporter] [TaskExecutionService-3] INFO  - Copying system disk file from VC: file:///C:/Program Files/VMware/Infrastructure/tomcat/webapps/VSAManager/ovf/sva_system2.vmdk to: https://172.30.25.135/nfc/52628490-35c4-1c04-e69f-3a8f3dc2beb0/disk-17.vmdk

      2013-12-22 11:38:46,310 928 [VAppImporter] [Thread-335] INFO  - LeaseProgressUpdater thread breaks

      2013-12-22 11:38:47,043 403 [FileLoader] [TaskExecutionService-3] INFO  - Uploaded file takes 1 seconds

      2013-12-22 11:38:47,058 357 [FileLoader] [TaskExecutionService-3] INFO  - Uploaded file created on server

      2013-12-22 11:38:47,058 840 [VAppImporter] [TaskExecutionService-3] INFO  - Uploaded file: sva_system2.vmdk

      2013-12-22 11:38:47,074 626 [VAppImporter] [TaskExecutionService-3] INFO  - Uploading of the entity is successful

      2013-12-22 11:38:47,557 928 [VAppImporter] [Thread-337] INFO  - LeaseProgressUpdater thread breaks

      2013-12-22 11:38:47,572 406 [VAppImporter] [TaskExecutionService-1] INFO  - Deploy VM VSA-0 took 3588s

      2013-12-22 11:38:47,635 406 [VAppImporter] [TaskExecutionService-2] INFO  - Deploy VM VSA-1 took 3588s

      2013-12-22 11:38:50,907 928 [VAppImporter] [Thread-339] INFO  - LeaseProgressUpdater thread breaks

      2013-12-22 11:38:51,764 406 [VAppImporter] [TaskExecutionService-3] INFO  - Deploy VM VSA-2 took 3593s

      2013-12-22 11:38:52,776 281 [SVAVMConfigurator] [TaskExecutionService-4] INFO  - Configuring the VM resources for the newly created VM

      2013-12-22 11:38:53,103 281 [SVAVMConfigurator] [TaskExecutionService-5] INFO  - Configuring the VM resources for the newly created VM

      2013-12-22 11:38:53,135 974 [SVAVMConfigurator] [TaskExecutionService-4] INFO  - Reconfigured VM to mark all disks as independent

      2013-12-22 11:38:53,166 974 [SVAVMConfigurator] [TaskExecutionService-5] INFO  - Reconfigured VM to mark all disks as independent

      2013-12-22 11:38:53,337 281 [SVAVMConfigurator] [TaskExecutionService-6] INFO  - Configuring the VM resources for the newly created VM

      2013-12-22 11:38:53,368 974 [SVAVMConfigurator] [TaskExecutionService-6] INFO  - Reconfigured VM to mark all disks as independent

      2013-12-22 11:38:56,313 326 [SVAVMConfigurator] [TaskExecutionService-4] INFO  - Configuring CPU/MEM allocation and page sharing for VM VSA-0 completed

      2013-12-22 11:38:56,329 327 [SVAVMConfigurator] [TaskExecutionService-4] INFO  - marking all non system disk as independent for VSA-0 completed

      2013-12-22 11:38:56,329 235 [Utils] [TaskExecutionService-4] INFO  - Retrieve dev.property: network.config=true

      2013-12-22 11:38:57,622 326 [SVAVMConfigurator] [TaskExecutionService-5] INFO  - Configuring CPU/MEM allocation and page sharing for VM VSA-1 completed

      2013-12-22 11:38:57,637 327 [SVAVMConfigurator] [TaskExecutionService-5] INFO  - marking all non system disk as independent for VSA-1 completed

      2013-12-22 11:38:57,637 235 [Utils] [TaskExecutionService-5] INFO  - Retrieve dev.property: network.config=true

      2013-12-22 11:38:57,669 326 [SVAVMConfigurator] [TaskExecutionService-6] INFO  - Configuring CPU/MEM allocation and page sharing for VM VSA-2 completed

      2013-12-22 11:38:57,684 327 [SVAVMConfigurator] [TaskExecutionService-6] INFO  - marking all non system disk as independent for VSA-2 completed

      2013-12-22 11:38:57,684 235 [Utils] [TaskExecutionService-6] INFO  - Retrieve dev.property: network.config=true

      2013-12-22 11:39:11,099 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:39:41,089 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:39:49,679 235 [Utils] [TaskExecutionService-4] INFO  - Retrieve dev.property: svaservice.detect.time=3

      2013-12-22 11:39:49,679 479 [SVAVMConfigurator] [TaskExecutionService-4] INFO  - Detecting VSA service waiting time is 3 min(s).

      2013-12-22 11:39:50,942 235 [Utils] [TaskExecutionService-5] INFO  - Retrieve dev.property: svaservice.detect.time=3

      2013-12-22 11:39:50,942 479 [SVAVMConfigurator] [TaskExecutionService-5] INFO  - Detecting VSA service waiting time is 3 min(s).

      2013-12-22 11:39:51,067 235 [Utils] [TaskExecutionService-6] INFO  - Retrieve dev.property: svaservice.detect.time=3

      2013-12-22 11:39:51,067 479 [SVAVMConfigurator] [TaskExecutionService-6] INFO  - Detecting VSA service waiting time is 3 min(s).

      2013-12-22 11:39:54,699 501 [SVAVMConfigurator] [TaskExecutionService-4] INFO  - Ping.exe VM 172.30.25.144 successfully

      2013-12-22 11:39:54,715 53 [MessagingService] [TaskExecutionService-4] INFO  - Axis2 attachement temp dir is C:\Program Files\VMware\Infrastructure\tomcat\temp

      2013-12-22 11:39:55,978 501 [SVAVMConfigurator] [TaskExecutionService-5] INFO  - Ping.exe VM 172.30.25.140 successfully

      2013-12-22 11:40:00,639 525 [SVAVMConfigurator] [TaskExecutionService-4] INFO  - Successfully pinged VSA service: 172.30.25.144

      Ping Message: Ping from SVA Domain. @Sat Dec 21 16:00:00 PST 2013

      Platform PING response @Sat Dec 21 16:00:00 PST 2013

       

       

      2013-12-22 11:40:00,686 116 [KeystoreX509TrustManager] [TaskExecutionService-4] INFO  - Loading from trust store: C:/ProgramData/VMware/VSA Manager/security/keystore.jks

      2013-12-22 11:40:00,748 1056 [SessionService] [TaskExecutionService-4] INFO  - Posting VC Security events for list size: 1

      2013-12-22 11:40:00,811 74 [EventService] [TaskExecutionService-4] INFO  - Event SecurityCertificatesAccepted posted successfully

      2013-12-22 11:40:00,811 570 [SVAVMConfigurator] [TaskExecutionService-4] INFO  - Successfully login to SVA: 172.30.25.144 Certificate: [

      [

        Version: V3

        Subject: CN=sva, O=VMware

        Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

       

       

        Key:  Sun RSA public key, 1024 bits

        modulus: 117168053425402510616550351057701685997913416530361287361049108844752607121068724932591148936375223425126578570074820902816664161895572146988453515000824105846394640557150601834338684848290236099370808104237033044639204049587210883576352835289998404957536209592751992199989033206948852347924097443749825482507

        public exponent: 65537

        Validity: [From: Sun Dec 22 03:41:00 PST 2013,

                     To: Tue Nov 28 03:41:00 PST 2113]

        Issuer: CN=sva, O=VMware

        SerialNumber: [    52b6cfcc]

       

       

      ]

        Algorithm: [SHA1withRSA]

        Signature:

      0000: 53 33 B4 59 0A BA A1 9A   26 90 A2 BC 17 07 EE BA  S3.Y....&.......

      0010: F7 6F D9 82 83 89 09 C8   97 B6 4E BE 52 96 6E 39  .o........N.R.n9

      0020: EA DC AC ED FC 47 35 D6   71 B3 DB 7F 85 4D 35 80  .....G5.q....M5.

      0030: 3A 8A 2F 57 15 93 A7 62   10 35 2F D0 B6 FE 77 49  :./W...b.5/...wI

      0040: AA 52 54 7C 73 BF 83 6B   80 BB 81 7E AD DA F1 F1  .RT.s..k........

      0050: A8 96 EE 31 7F 45 09 B0   1C 8A 3F BC 60 BE D6 16  ...1.E....?.`...

      0060: 0A 4F 4D 9B 57 11 FC C5   6B 31 5F 1E 7D 55 DA 93  .OM.W...k1_..U..

      0070: 30 20 20 06 FA 49 9E 10   3E 3B D8 FB BE 75 FA B0  0  ..I..>;...u..

       

       

      ]

      2013-12-22 11:40:01,996 525 [SVAVMConfigurator] [TaskExecutionService-5] INFO  - Successfully pinged VSA service: 172.30.25.140

      Ping Message: Ping from SVA Domain. @Sun Dec 22 16:00:00 PST 2013

      Platform PING response @Sun Dec 22 16:00:00 PST 2013

       

       

      2013-12-22 11:40:02,089 116 [KeystoreX509TrustManager] [TaskExecutionService-5] INFO  - Loading from trust store: C:/ProgramData/VMware/VSA Manager/security/keystore.jks

      2013-12-22 11:40:02,167 1056 [SessionService] [TaskExecutionService-5] INFO  - Posting VC Security events for list size: 1

      2013-12-22 11:40:02,229 74 [EventService] [TaskExecutionService-5] INFO  - Event SecurityCertificatesAccepted posted successfully

      2013-12-22 11:40:02,245 570 [SVAVMConfigurator] [TaskExecutionService-5] INFO  - Successfully login to SVA: 172.30.25.140 Certificate: [

      [

        Version: V3

        Subject: CN=sva, O=VMware

        Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

       

       

        Key:  Sun RSA public key, 1024 bits

        modulus: 133505184176230018034038128601902606372846221567163798727596908827581187321532813183747201224728106069751808055554370441494876212534495188149647994325648951355309252960530450002261663664716773145824602596432507356133962520555051024437985640671733964952344424409758746129613391647924621791595689857292339856683

        public exponent: 65537

        Validity: [From: Sun Dec 22 23:41:34 PST 2013,

                     To: Tue Nov 28 23:41:34 PST 2113]

        Issuer: CN=sva, O=VMware

        SerialNumber: [    52b7e92e]

       

       

      ]

        Algorithm: [SHA1withRSA]

        Signature:

      0000: A5 73 18 F0 C5 6F 56 97   5F F3 86 99 B5 F9 C4 DC  .s...oV._.......

      0010: 22 F0 9D EC 5C 17 4A 4F   EA 96 9B 24 5C 46 41 81  "...\.JO...$\FA.

      0020: 5B 45 9E CA F5 57 79 DA   3E 40 CC 53 15 05 ED 88  [E...Wy.>@.S....

      0030: EB DF 4A BF 6B 57 CA 91   F5 F1 21 80 6F 7D 3F 68  ..J.kW....!.o.?h

      0040: E9 08 45 2E 81 E3 CF B8   E6 15 27 F9 DC 92 65 A0  ..E.......'...e.

      0050: 5A 5F FD EB 64 03 68 66   EE 04 69 8E 87 4A 8F D6  Z_..d.hf..i..J..

      0060: 6B CF D5 DE 57 C8 85 FA   CC C2 56 37 62 B1 96 8B  k...W.....V7b...

      0070: 3C 8C 40 A1 B6 D6 9B BA   CE 8B C5 54 76 49 79 A4  <.@........TvIy.

       

       

      ]

      2013-12-22 11:40:02,744 235 [Utils] [TaskExecutionService-4] INFO  - Retrieve dev.property: vm.config=true

      2013-12-22 11:40:02,744 805 [SVAVMConfigurator] [TaskExecutionService-4] INFO  - Disabled VM flag is true

      2013-12-22 11:40:04,147 235 [Utils] [TaskExecutionService-5] INFO  - Retrieve dev.property: vm.config=true

      2013-12-22 11:40:04,147 805 [SVAVMConfigurator] [TaskExecutionService-5] INFO  - Disabled VM flag is true

      2013-12-22 11:40:11,241 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:40:41,274 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:40:57,462 1479 [ClusterService] [SanityService-5] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:41:11,279 126 [SVAManager] [tomcat-http--31] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:41:41,442 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:42:11,380 126 [SVAManager] [tomcat-http--31] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:42:41,339 126 [SVAManager] [tomcat-http--31] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:43:11,308 126 [SVAManager] [tomcat-http--31] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:43:27,005 498 [SVAVMConfigurator] [TaskExecutionService-6] ERROR - Cannot access VSA VM 172.30.25.136

      2013-12-22 11:43:27,005 553 [SVAVMConfigurator] [TaskExecutionService-6] ERROR - Failed to ping VSA service on VM 172.30.25.136

      2013-12-22 11:43:27,005 348 [SVAVMConfigurator] [TaskExecutionService-6] ERROR - VSA service is not running.

      2013-12-22 11:43:27,036 645 [SVAVMConfigurator] [TaskExecutionService-6] ERROR - Customize VM on host host-28 failed

      java.lang.Exception: Failed to ping VSA service on VM 172.30.25.136

        at com.vmware.sva.manager.config.SVAVMConfigurator.connectToSVAService(SVAVMConfigurator.java:554)

        at com.vmware.sva.manager.config.SVAVMConfigurator.attemptSVAConnect(SVAVMConfigurator.java:346)

        at com.vmware.sva.manager.config.SVAVMConfigurator.initializeVM(SVAVMConfigurator.java:274)

        at com.vmware.sva.manager.config.SVAVMConfigurator.customizeSvaVM(SVAVMConfigurator.java:708)

        at com.vmware.sva.manager.config.SVAVMConfigurator.call(SVAVMConfigurator.java:643)

        at com.vmware.sva.manager.config.SVAVMConfigurator.call(SVAVMConfigurator.java:80)

        at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)

        at java.util.concurrent.FutureTask.run(FutureTask.java:138)

        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)

        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)

        at java.lang.Thread.run(Thread.java:662)

      2013-12-22 11:43:27,052 313 [VCUserSession] [pool-109-thread-1] ERROR - Customize VM on host host-28failed.

      java.util.concurrent.ExecutionException: java.lang.Exception: Failed to ping VSA service on VM 172.30.25.136

        at java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:222)

        at java.util.concurrent.FutureTask.get(FutureTask.java:83)

        at com.vmware.sva.manager.dao.VCUserSession.installSvaVms(VCUserSession.java:306)

        at com.vmware.sva.manager.jobservices.tasks.TaskInstallVSAVMs.execute(TaskInstallVSAVMs.java:47)

        at com.vmware.sva.manager.jobservices.impl.DefaultTaskRunner.handleTaskStart(DefaultTaskRunner.java:70)

        at com.vmware.sva.manager.jobservices.impl.DefaultTaskRunner.call(DefaultTaskRunner.java:33)

        at com.vmware.sva.manager.jobservices.impl.SyncTaskGroup.execute(SyncTaskGroup.java:46)

        at com.vmware.sva.manager.jobservices.impl.DefaultJobRunner.run(DefaultJobRunner.java:60)

        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)

        at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)

        at java.util.concurrent.FutureTask.run(FutureTask.java:138)

        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)

        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)

        at java.lang.Thread.run(Thread.java:662)

      Caused by: java.lang.Exception: Failed to ping VSA service on VM 172.30.25.136

        at com.vmware.sva.manager.config.SVAVMConfigurator.connectToSVAService(SVAVMConfigurator.java:554)

        at com.vmware.sva.manager.config.SVAVMConfigurator.attemptSVAConnect(SVAVMConfigurator.java:346)

        at com.vmware.sva.manager.config.SVAVMConfigurator.initializeVM(SVAVMConfigurator.java:274)

        at com.vmware.sva.manager.config.SVAVMConfigurator.customizeSvaVM(SVAVMConfigurator.java:708)

        at com.vmware.sva.manager.config.SVAVMConfigurator.call(SVAVMConfigurator.java:643)

        at com.vmware.sva.manager.config.SVAVMConfigurator.call(SVAVMConfigurator.java:80)

        ... 5 more

      2013-12-22 11:43:27,052 315 [VCUserSession] [pool-109-thread-1] ERROR - Cancelling the running customization tasks

      2013-12-22 11:43:27,052 235 [Utils] [pool-109-thread-1] INFO  - Retrieve dev.property: vm.rollback=true

      2013-12-22 11:43:27,052 333 [VCUserSession] [pool-109-thread-1] INFO  - Deleting all the installed VM

      2013-12-22 11:43:41,288 126 [SVAManager] [tomcat-http--31] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:44:06,002 89 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task exception. TaskID:InstallsVSAVMs

      2013-12-22 11:44:06,017 80 [DefaultJobRunner] [pool-109-thread-1] ERROR - Exception caught. Starting rollback for job if rollback support . JobName:ClusterCreation-datacenter-21

      java.util.concurrent.ExecutionException: java.lang.Exception: Failed to ping VSA service on VM 172.30.25.136

        at java.util.concurrent.FutureTask$Sync.innerGet(FutureTask.java:222)

        at java.util.concurrent.FutureTask.get(FutureTask.java:83)

        at com.vmware.sva.manager.dao.VCUserSession.installSvaVms(VCUserSession.java:306)

        at com.vmware.sva.manager.jobservices.tasks.TaskInstallVSAVMs.execute(TaskInstallVSAVMs.java:47)

        at com.vmware.sva.manager.jobservices.impl.DefaultTaskRunner.handleTaskStart(DefaultTaskRunner.java:70)

        at com.vmware.sva.manager.jobservices.impl.DefaultTaskRunner.call(DefaultTaskRunner.java:33)

        at com.vmware.sva.manager.jobservices.impl.SyncTaskGroup.execute(SyncTaskGroup.java:46)

        at com.vmware.sva.manager.jobservices.impl.DefaultJobRunner.run(DefaultJobRunner.java:60)

        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)

        at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)

        at java.util.concurrent.FutureTask.run(FutureTask.java:138)

        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)

        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)

        at java.lang.Thread.run(Thread.java:662)

      Caused by: java.lang.Exception: Failed to ping VSA service on VM 172.30.25.136

        at com.vmware.sva.manager.config.SVAVMConfigurator.connectToSVAService(SVAVMConfigurator.java:554)

        at com.vmware.sva.manager.config.SVAVMConfigurator.attemptSVAConnect(SVAVMConfigurator.java:346)

        at com.vmware.sva.manager.config.SVAVMConfigurator.initializeVM(SVAVMConfigurator.java:274)

        at com.vmware.sva.manager.config.SVAVMConfigurator.customizeSvaVM(SVAVMConfigurator.java:708)

        at com.vmware.sva.manager.config.SVAVMConfigurator.call(SVAVMConfigurator.java:643)

        at com.vmware.sva.manager.config.SVAVMConfigurator.call(SVAVMConfigurator.java:80)

        ... 5 more

      2013-12-22 11:44:06,017 109 [DefaultJobRunner] [pool-109-thread-1] INFO  - Rollback started for job. JobName:ClusterCreation-datacenter-21

      2013-12-22 11:44:06,017 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:InstallsVSAVMs

      2013-12-22 11:44:06,017 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:InstallsVSAVMs

      2013-12-22 11:44:06,017 235 [Utils] [pool-109-thread-1] INFO  - Retrieve dev.property: vm.rollback=true

      2013-12-22 11:44:06,095 304 [CleanupUtils] [pool-109-thread-1] INFO  - Powering off and deleting all SVA VMs

      2013-12-22 11:44:06,095 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:InstallsVSAVMs

      2013-12-22 11:44:06,095 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:InstallsVSAVMs

      2013-12-22 11:44:06,095 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:ConfigureHA

      2013-12-22 11:44:06,095 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:ConfigureHA

      2013-12-22 11:44:06,095 235 [Utils] [pool-109-thread-1] INFO  - Retrieve dev.property: ha.config=true

      2013-12-22 11:44:11,288 126 [SVAManager] [tomcat-http--31] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:44:41,261 126 [SVAManager] [tomcat-http--26] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:45:11,252 126 [SVAManager] [tomcat-http--31] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:45:41,293 126 [SVAManager] [tomcat-http--31] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:45:57,530 1479 [ClusterService] [SanityService-5] INFO  - No Hosts have been added/removed since: 13-12-22 10:35:56

      2013-12-22 11:46:11,303 126 [SVAManager] [tomcat-http--23] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:46:11,852 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:ConfigureHA

      2013-12-22 11:46:11,852 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:ConfigureHA

      2013-12-22 11:46:11,852 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:NetworkConfig

      2013-12-22 11:46:11,852 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:NetworkConfig

      2013-12-22 11:46:11,852 235 [Utils] [pool-109-thread-1] INFO  - Retrieve dev.property: network.config=true

      2013-12-22 11:46:11,852 57 [TaskNetworkConfig] [pool-109-thread-1] INFO  - Networking was not configured. Thus, don't revert to greenfield networking.

      2013-12-22 11:46:11,852 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:false. TaskID:NetworkConfig

      2013-12-22 11:46:11,852 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:NetworkConfig

      2013-12-22 11:46:11,852 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:AddTagsToHosts

      2013-12-22 11:46:11,852 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:AddTagsToHosts

      2013-12-22 11:46:12,199 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:AddTagsToHosts

      2013-12-22 11:46:12,199 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:AddTagsToHosts

      2013-12-22 11:46:12,199 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:Run2NLV

      2013-12-22 11:46:12,199 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:Run2NLV

      2013-12-22 11:46:12,199 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:Run2NLV

      2013-12-22 11:46:12,199 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:Run2NLV

      2013-12-22 11:46:12,199 122 [DefaultJobRunner] [pool-109-thread-1] INFO  - TaskGroup rolled back successfully.TaskGroupName:ClusterCreation-PostHostReboot

      2013-12-22 11:46:12,199 83 [AsyncTaskGroup] [pool-109-thread-1] INFO  - Cancelling running tasks for taskGroup. TaskGroupName:Set Hosts VMFS Heap Size

      2013-12-22 11:46:12,215 116 [DefaultTaskRunner] [pool-110-thread-5] INFO  - Notifying listeners of task rollback. TaskID:SetVMFSHeapSizehost-31

      2013-12-22 11:46:12,215 136 [DefaultTaskRunner] [pool-110-thread-5] INFO  - Starting rollback of task. TaskID:SetVMFSHeapSizehost-31

      2013-12-22 11:46:12,215 116 [DefaultTaskRunner] [pool-110-thread-4] INFO  - Notifying listeners of task rollback. TaskID:SetVMFSHeapSizehost-40

      2013-12-22 11:46:12,215 136 [DefaultTaskRunner] [pool-110-thread-4] INFO  - Starting rollback of task. TaskID:SetVMFSHeapSizehost-40

      2013-12-22 11:46:12,215 138 [DefaultTaskRunner] [pool-110-thread-5] INFO  - Task rollback finished with result:true. TaskID:SetVMFSHeapSizehost-31

      2013-12-22 11:46:12,215 140 [DefaultTaskRunner] [pool-110-thread-5] INFO  - Notifying listeners of task rollback end. TaskID:SetVMFSHeapSizehost-31

      2013-12-22 11:46:12,215 116 [DefaultTaskRunner] [pool-110-thread-6] INFO  - Notifying listeners of task rollback. TaskID:SetVMFSHeapSizehost-28

      2013-12-22 11:46:12,215 136 [DefaultTaskRunner] [pool-110-thread-6] INFO  - Starting rollback of task. TaskID:SetVMFSHeapSizehost-28

      2013-12-22 11:46:12,215 138 [DefaultTaskRunner] [pool-110-thread-4] INFO  - Task rollback finished with result:true. TaskID:SetVMFSHeapSizehost-40

      2013-12-22 11:46:12,215 140 [DefaultTaskRunner] [pool-110-thread-4] INFO  - Notifying listeners of task rollback end. TaskID:SetVMFSHeapSizehost-40

      2013-12-22 11:46:12,215 138 [DefaultTaskRunner] [pool-110-thread-6] INFO  - Task rollback finished with result:true. TaskID:SetVMFSHeapSizehost-28

      2013-12-22 11:46:12,215 140 [DefaultTaskRunner] [pool-110-thread-6] INFO  - Notifying listeners of task rollback end. TaskID:SetVMFSHeapSizehost-28

      2013-12-22 11:46:12,215 122 [DefaultJobRunner] [pool-109-thread-1] INFO  - TaskGroup rolled back successfully.TaskGroupName:Set Hosts VMFS Heap Size

      2013-12-22 11:46:12,215 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:Audit2N-LV

      2013-12-22 11:46:12,215 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:Audit2N-LV

      2013-12-22 11:46:12,215 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:Audit2N-LV

      2013-12-22 11:46:12,215 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:Audit2N-LV

      2013-12-22 11:46:12,215 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:NetworkConfigValidation

      2013-12-22 11:46:12,215 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:NetworkConfigValidation

      2013-12-22 11:46:12,215 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:NetworkConfigValidation

      2013-12-22 11:46:12,215 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:NetworkConfigValidation

      2013-12-22 11:46:12,215 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:AuditHostGroup

      2013-12-22 11:46:12,215 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:AuditHostGroup

      2013-12-22 11:46:12,215 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:AuditHostGroup

      2013-12-22 11:46:12,215 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:AuditHostGroup

      2013-12-22 11:46:12,215 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:ClusterExists

      2013-12-22 11:46:12,215 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:ClusterExists

      2013-12-22 11:46:12,215 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:ClusterExists

      2013-12-22 11:46:12,215 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:ClusterExists

      2013-12-22 11:46:12,215 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:LogParams

      2013-12-22 11:46:12,215 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:LogParams

      2013-12-22 11:46:12,215 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:LogParams

      2013-12-22 11:46:12,215 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:LogParams

      2013-12-22 11:46:12,215 116 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback. TaskID:TaskInitCreateCluster

      2013-12-22 11:46:12,215 136 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Starting rollback of task. TaskID:TaskInitCreateCluster

      2013-12-22 11:46:12,278 196 [MappingService] [pool-109-thread-1] INFO  - Removing context for datacenter-21

      2013-12-22 11:46:12,278 199 [MappingService] [pool-109-thread-1] INFO  - Removing SVA mapping for c173b289-84e8-419c-abfd-62f7d0e5c2f2

      2013-12-22 11:46:12,278 199 [MappingService] [pool-109-thread-1] INFO  - Removing SVA mapping for 4813977e-999f-4af0-a9f7-7c6736285b58

      2013-12-22 11:46:12,562 171 [SvaSolutionLicenseManager] [pool-109-thread-1] INFO  - ReleaseLicense - Updating license count to : 0

      2013-12-22 11:46:12,578 83 [TaskInitCreateCluster] [pool-109-thread-1] INFO  - Released license for datacenter: datacenter-21

      2013-12-22 11:46:12,578 138 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Task rollback finished with result:true. TaskID:TaskInitCreateCluster

      2013-12-22 11:46:12,578 140 [DefaultTaskRunner] [pool-109-thread-1] INFO  - Notifying listeners of task rollback end. TaskID:TaskInitCreateCluster

      2013-12-22 11:46:12,578 122 [DefaultJobRunner] [pool-109-thread-1] INFO  - TaskGroup rolled back successfully.TaskGroupName:ClusterCreation-PreHostReboot

      2013-12-22 11:46:12,578 84 [DefaultJobRunner] [pool-109-thread-1] ERROR - Rollback finished successfully for job. JobName:ClusterCreation-datacenter-21

      2013-12-22 11:46:12,578 92 [DefaultJobRunner] [pool-109-thread-1] INFO  - Notifying listeners of job exception for job: ClusterCreation-datacenter-21

      2013-12-22 11:46:41,410 126 [SVAManager] [tomcat-http--23] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:47:11,382 126 [SVAManager] [tomcat-http--23] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:47:41,364 126 [SVAManager] [tomcat-http--23] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:48:11,350 126 [SVAManager] [tomcat-http--23] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:48:41,925 126 [SVAManager] [tomcat-http--23] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:49:11,785 126 [SVAManager] [tomcat-http--23] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:49:41,697 126 [SVAManager] [tomcat-http--25] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:50:11,619 126 [SVAManager] [tomcat-http--25] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:50:41,590 126 [SVAManager] [tomcat-http--25] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:50:57,773 1505 [ClusterService] [SanityService-5] INFO  - Host added/removed from/to DC: datacenter-21, which does not contain a VSA Cluster.

      2013-12-22 11:51:11,589 126 [SVAManager] [tomcat-http--23] INFO  - VSA Manager dummyPing invoked.

      2013-12-22 11:51:41,653 126 [SVAManager] [tomcat-http--39] INFO  - VSA Manager dummyPing invoked.