VMware Horizon Community
JohnFLi
Enthusiast
Enthusiast

App Volumes not getting applied to instant clone

I have no idea where this is getting posted, nor even where to look to see if anybody else has had this issue.

My company has several ESXi hosts, etc and so forth.

I have the trial of Horizon 7 and app volumes.

I have created a instant-clone pool which seems to work fine.

I setup and installed App Volumes.

Did all the steps to create an AppStack to push out Notepadd++.   (should be simple enough)

installed it on the provisional system, completed, rebooted, etc, etc....

all seems good.

I then assign the appStack to a user.

That user then logs onto one of the instant clones......

No Notepad++.

I don't see any mention of it even trying to apply.

zero mention of it at C:\Program Files (x86)\CloudVolumes\Manager\log

any ideas one what to look at?

I tried contacting support, but becasue i am using the trial, they told me to F off.

Reply
0 Kudos
15 Replies
Ray_handels
Virtuoso
Virtuoso

Do you actually see it connecting to the appvolumes manager server? Normally in the logging it should say soemthing like cannot connect to Appvolumes manager or some sort.

Did you provide your Appvolumes manager with a trusted certificate or are you still using the default? If so, try to disable Certificate checking.

Disable SSL Certificate Validation in App Volumes Agent After Installation

Reply
0 Kudos
JohnFLi
Enthusiast
Enthusiast

Thank you for your reply.

I did as you suggested.

Still didn't get anything.

Deleted the appStack

re-made the appstack, assigned it to 1 person.

logged into the clone as that user, still nothing.

Looked in the  C:\Program Files (x86)\CloudVolumes\Manager\log\production000001.log file, don't see anything related.

in App Volumes manager, under  Directory/Users     I can see the test user I assigned it to,   It shows that it has 1 app assigned to it, under log ins, it shows a zero.

Any thoughts on where to look for any errors??

Reply
0 Kudos
Ray_handels
Virtuoso
Virtuoso

Other than the log file on the agent not really. Could you try and post a snap of the logfile in which you see the error? Are you able to ping the appvolumes manager from the client?

Reply
0 Kudos
JohnFLi
Enthusiast
Enthusiast

I don't find any logs on the machine that is supposed to have the application applied to.

I don't get any error message.

They can ping back and forth all day long   (they are on the same network)

Reply
0 Kudos
Ray_handels
Virtuoso
Virtuoso

Could you please try and look into the C:\Program Files (x86)\CloudVolumes\Agent\Logs\svservice.log. In that log file you actually see what's happening when you log in.

Without logging it is quite impossible to see what is going wrong without looking at an environment.

And looking at your post, did you even install the agent on the instant clone machine?

Reply
0 Kudos
JohnFLi
Enthusiast
Enthusiast

perhaps that is the root of the problem:

"C:\Program Files (x86)\CloudVolumes\Agent\Logs\svservice.log."

the "C:\Program Files (x86)\CloudVolumes\ Agent" only exists on the system that I use to computer that I use to create the app stack and provision.

DO i need to install the agent on the base image as well?

(if so, I didn't see that part on the limited documentation for app volumes)

Reply
0 Kudos
JohnFLi
Enthusiast
Enthusiast

Ok, i'm a little further now......

Now, when I log into the clone, i get a message box saying "Error from manager <ip address> (error code 400): unable to locate the machine making this request on the hypervisor      Virtualization is disabled"

The system is joined to the domain

they can ping back and forth with each other.

it is in the correct OU

from the clone, i can telnet at port 80 and 443

the clones ip address is (10.100.5.154 and the apps Volume Manager is 10.100.5.142)

2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3444] Message: "Error from Manager "10.100.5.142" (error code 400):

Unable to locate the machine making this request on the hypervisor

Virtualization is disabled" (hToken 00000000000003E0)

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3444] RunExecutableAsUser: Path "C:\Program Files (x86)\CloudVolumes\Agent\svservice.exe"

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3444] RunExecutableAsUser: CommandLine svservice.exe message "Error from Manager "10.100.5.142" (error code 400):

Unable to locate the machine making this request on the hypervisor

Virtualization is disabled"

CreateProcessWithTokenAndEnvBlockW() standard user or UAC turned off, continue...

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3784] Waiting 0 second(s) for a new volume

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3784] Activate filtering (called by DelayActivateWorker)

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T1316] MeasureTime::RecordCenter: Start recording  GUID:{52877c63-0000-0000-0000-501f00000000} Type:0

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T1316] Preload volume event (startup): "\Device\HarddiskVolume2" GUID {52877c63-0000-0000-0000-501f00000000} Hive  (1 logged in, SystemVolume 1, VolumeType 0)

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T1316] Sending reply to SVCMD_ID_NEW_VOLUME_PRE (Message 3, Size 24)

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3444] Successfully launched (elevated privileges): svservice.exe message "Error from Manager "10.100.5.142" (error code 400):

Unable to locate the machine making this request on the hypervisor

Virtualization is disabled" (wait 0 ms), pid=4000 tid=2976

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3444] Successfully launched: svservice.exe message "Error from Manager "10.100.5.142" (error code 400):

Unable to locate the machine making this request on the hypervisor

Virtualization is disabled" (wait 0 ms)

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3444] HttpFileShareRequest WinHttp over SSL is disabled. Log collection to file share not supported.

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3444] handleFileShareStr: File share info received from manager is empty.

[2018-09-14 19:39:05.369 UTC] [svservice:P1104:T3444] OnStartShell: Error Failed to Start DCT Logger

[2018-09-14 19:39:05.415 UTC] [svservice:P4000:T2976] *** Started

[2018-09-14 20:05:02.213 UTC] [svservice:P1104:T5048] OnLock called (Session ID 1, Handle 000002915AE84DC0, Params 000000E6E0DFE9F8, Context 0000000000000000)

[2018-09-14 20:06:20.722 UTC] [svservice:P1104:T5052] OnUnlock called (Session ID 1, Handle 000002915AE84DC0, Params 000000E6E0EFE4F8, Context 0000000000000000)

Reply
0 Kudos
Ray_handels
Virtuoso
Virtuoso

Seems as if the VDI machine (and yes, you need to install the agent on the GI but you found that out already) cannot connect to the manager.

I'd suggest going for DNS names when you install the agent and it asks what manager to you, I think you need to do reverse DNS on it for it to work, not 100% sure though.

Reply
0 Kudos
JohnFLi
Enthusiast
Enthusiast

got a little farther i think.

I changed useing the IP address to using the FQDN:

[2018-09-17 17:53:26.869 UTC] [svservice:P1136:T3440] ReadOutVolsFromString: NewVolumes 1 TotalVolumes 1

[2018-09-17 17:53:26.869 UTC] [svservice:P1136:T3440] ReadOutVolsFromString: Manager has asynchronous loading of volumes turned on

[2018-09-17 17:53:26.869 UTC] [svservice:P1136:T3440] ReadOutVolsFromString: volume path:"g1ptesx14-localstorage-1\cloudvolumes\apps\Notepad_7.5.8.vmdk" guid:"{1bf47220-e928-4ece-a1d7-c4a6aa9aaa1e}" type:"MOUNTED-READ"

[2018-09-17 17:53:26.869 UTC] [svservice:P1136:T3440] SvdPushReorderEntry: Sent reorder entry to filter {1bf47220-e928-4ece-a1d7-c4a6aa9aaa1e} (UserVolume: 1; Writable: 0)

[2018-09-17 17:53:26.869 UTC] [svservice:P1136:T3440] ReadOutVolsFromString: add AppStack to load order list:"{1bf47220-e928-4ece-a1d7-c4a6aa9aaa1e}"(1)

[2018-09-17 17:53:26.869 UTC] [svservice:P1136:T3440] ReadOutVolsFromString: invalid command:"ASYNC"

[2018-09-17 17:53:26.869 UTC] [svservice:P1136:T3440] LogonMount: agent:0 manager:1 volume(s) to attach

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T3440] Activate filtering (called by WaitVolumesLoading)

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T3440] WaitVolumesLoading: waiting for 1 total volume(s), 0 processed, max wait 180

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T3440] WaitVolumesLoading: Wait for first volume only is 1

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T3440] Releasing user logon, (Writable present: 0; Writable processed: 0)

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T3440] SvdSetReorderCounter: set to 0

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T3440] HttpUserLogin: succeeded (user login)

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T3440] OnLogon : succeeded

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T1408] MeasureTime::RecordCenter: Start recording  GUID:{52877c63-0000-0000-0000-501f00000000} Type:0

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T1408] Preload volume event (startup): "\Device\HarddiskVolume2" GUID {52877c63-0000-0000-0000-501f00000000} Hive  (0 logged in, SystemVolume 1, VolumeType 0)

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T1408] Sending reply to SVCMD_ID_NEW_VOLUME_PRE (Message 3, Size 24)

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T1860] WaitVolumesLoadingWorker: waiting for 1 total volume(s), 0 processed, max wait 180

[2018-09-17 17:53:27.916 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 1 second(s) so far)

[2018-09-17 17:53:28.932 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 2 second(s) so far)

[2018-09-17 17:53:29.947 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 3 second(s) so far)

[2018-09-17 17:53:30.963 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 4 second(s) so far)

[2018-09-17 17:53:31.979 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 5 second(s) so far)

[2018-09-17 17:53:32.338 UTC] [svservice:P1136:T3440] OnStartShell called (Session ID 1, Handle 0000029AB3D67D90, Params 000000F313EFE798, Context 0000000000000000)

[2018-09-17 17:53:32.338 UTC] [svservice:P1136:T3440] OnStartShell: <domain name edited here>\testuser.test (NameSamCompatible)

[2018-09-17 17:53:32.338 UTC] [svservice:P1136:T3440] HttpFileShareRequest WinHttp over SSL is disabled. Log collection to file share not supported.

[2018-09-17 17:53:32.338 UTC] [svservice:P1136:T3440] handleFileShareStr: File share info received from manager is empty.

[2018-09-17 17:53:32.338 UTC] [svservice:P1136:T3440] OnStartShell: Error Failed to Start DCT Logger

[2018-09-17 17:53:32.994 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 6 second(s) so far)

[2018-09-17 17:53:34.010 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 7 second(s) so far)

[2018-09-17 17:53:35.041 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 8 second(s) so far)

[2018-09-17 17:53:36.057 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 9 second(s) so far)

[2018-09-17 17:53:37.072 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 10 second(s) so far)

[2018-09-17 17:53:38.104 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 11 second(s) so far)

[2018-09-17 17:53:39.119 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 12 second(s) so far)

[2018-09-17 17:53:40.135 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 13 second(s) so far)

[2018-09-17 17:53:41.150 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 14 second(s) so far)

[2018-09-17 17:53:42.213 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 15 second(s) so far)

[2018-09-17 17:53:43.244 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 16 second(s) so far)

[2018-09-17 17:53:44.260 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 17 second(s) so far)

[2018-09-17 17:53:45.275 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 18 second(s) so far)

[2018-09-17 17:53:46.291 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 19 second(s) so far)

[2018-09-17 17:53:47.307 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 20 second(s) so far)

[2018-09-17 17:53:48.322 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 21 second(s) so far)

[2018-09-17 17:53:49.338 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 22 second(s) so far)

[2018-09-17 17:53:50.354 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 23 second(s) so far)

[2018-09-17 17:53:51.369 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 24 second(s) so far)

[2018-09-17 17:53:52.385 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 25 second(s) so far)

[2018-09-17 17:53:53.400 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 26 second(s) so far)

[2018-09-17 17:53:54.416 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 27 second(s) so far)

[2018-09-17 17:53:55.432 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 28 second(s) so far)

[2018-09-17 17:53:56.447 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 29 second(s) so far)

[2018-09-17 17:53:57.463 UTC] [svservice:P1136:T1860] Waiting for 1 new volumes before resetting order (0 processed, waited 30 second(s) so far)

[2018-09-17 17:53:57.463 UTC] [svservice:P1136:T1860] [0] Connecting to g1vtgbi02.<domain name edited here>.local:80 using HTTP (attempt 1)

[2018-09-17 17:53:57.463 UTC] [svservice:P1136:T1860] WinHttpSendRequestWithSSLCertValidation: SSL certificate validation is disabled.

[2018-09-17 17:53:57.463 UTC] [svservice:P1136:T1860] WinHttpSendRequestWithSSLCertValidation: WinHttpSetOption(WINHTTP_OPTION_SECURITY_FLAGS) succeeded.

[2018-09-17 17:53:57.525 UTC] [svservice:P1136:T1860] WinHttpSendRequestWithSSLCertValidation: WinHttpSendRequest succeeded.

[2018-09-17 17:53:57.525 UTC] [svservice:P1136:T1860] HttpReadAllAvailableData: Error 0 in WinHttpQueryDataAvailable: dwSize 0

[2018-09-17 17:53:57.525 UTC] [svservice:P1136:T1860] HttpInitializeRequest: Manager status 200 response (9 bytes): !FAILURE!

[2018-09-17 17:53:57.525 UTC] [svservice:P1136:T1860] SendMountConfirmationRequest: Volume mount-confirmation failed. Volumes will not be mounted.

Reply
0 Kudos
Ray_handels
Virtuoso
Virtuoso

Yes, i saw this in another thread as well. I do understand that you want this to be fixed but if possible please try and post in one thread and just point to this one (or vice versa off course Smiley Happy).

When looking at the logging, these line means it can connect to the Appvolumes Manager. You can also see that because the manager reports that 1 appstack needs to be attached. So your connection from agent to manager is working.

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T3440] HttpUserLogin: succeeded (user login)

[2018-09-17 17:53:26.900 UTC] [svservice:P1136:T3440] OnLogon : succeeded

Looking at this line, it seems as if the manager says it cannot attach the appstack to the machine, it could be many reasons. Could you please try and check manager log to see why it does not attach?

[2018-09-17 17:53:57.525 UTC] [svservice:P1136:T1860] HttpInitializeRequest: Manager status 200 response (9 bytes): !FAILURE!

[2018-09-17 17:53:57.525 UTC] [svservice:P1136:T1860] SendMountConfirmationRequest: Volume mount-confirmation failed. Volumes will not be mounted.

Reasons I can think of is that the VDI cannot connect to the datastore the Appstacks are located on or the password for the VSphere manager is incorrect and it cannot connect to it.

The issue we had once is that certificate validation between Appvolumes manager and Vsphere was enabled and Vsphere did not have a trusted certificate. To check this go to Appvolumes Manager, then go to Configuration --> Machine Manager, open up the manager and to the right you should see a certificate option. It could be that you need to manually accept the untrusted certificate!!!.

Also depending on the version, every time you restart appvolumes manager on Vsphere you need to re accept it.

Reply
0 Kudos
JohnFLi
Enthusiast
Enthusiast

Sorry about posting in another spot.....I was just trying to expand my options.    I will keep it to this thread.

This time when I logged into the clone (I say this time, because it does change from time to time) the error says:

"Error from Manager <FQDN on the manager machine>(error code 401) Invalid session cookie: Session key does not match any active sessions"

Another message I get when trying is : "Error from Manager <FQDN on the manager machine>(error code 400) Unable to locate the machine making this request on the hypervisor"

One thing I just noticed in one of the logs: " Establishing verified SSL connection to vSphere at "https://root@g1ptesx14.<domain name>.local" without certificate authorities due to missing file: "C:/Program Files (x86)/CloudVolumes/Manager/config/cacert.pem

Here is a partial of production000005.log

[2018-09-19 15:39:10 UTC]      P5080R5  INFO Started GET "/activity/system_messages" for 127.0.0.1 at 2018-09-19 08:39:10 -0700
[2018-09-19 15:39:10 UTC]      P5080R5  INFO Processing by ActivityController#system_messages as */*
[2018-09-19 15:39:10 UTC]      P5080R5  INFO  Referred by https://g1vtgbi02.<domain name>.local/activity?fresh=1537370585262 using Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.3; WOW64; Trident/7.0; .NET4.0E; .NET4.0C; .NET CLR 3.5.30729; .NET CLR 2.0.50727; .NET CLR 3.0.30729)
[2018-09-19 15:39:10 UTC]      P5080R5  INFO Current Request roles: ["Administrators"], user: John username (Admin) (4)
[2018-09-19 15:39:10 UTC]      P5080R5  INFO   Rendered activity/system_messages.html.erb (0.0ms)
[2018-09-19 15:39:10 UTC]      P5080R5  INFO Completed 200 OK in 9ms (Views: 0.5ms | ActiveRecord: 4.0ms)
[2018-09-19 15:39:10 UTC]      P5080R5  INFO

[2018-09-19 15:39:10 UTC]       P556R7  INFO Started GET "/cv_api/system_messages?_=1537370585479" for 127.0.0.1 at 2018-09-19 08:39:10 -0700
[2018-09-19 15:39:10 UTC]       P556R7  INFO Processing by CvApi::SystemMessagesController#index as JSON
[2018-09-19 15:39:10 UTC]       P556R7  INFO   Parameters: {"_"=>"1537370585479"}
[2018-09-19 15:39:10 UTC]       P556R7  INFO  Referred by https://g1vtgbi02.<domain name>.local/activity?fresh=1537370585262 using Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 6.3; WOW64; Trident/7.0; .NET4.0E; .NET4.0C; .NET CLR 3.5.30729; .NET CLR 2.0.50727; .NET CLR 3.0.30729)
[2018-09-19 15:39:10 UTC]       P556R7  INFO Current Request roles: ["Administrators"], user: John username (Admin) (4)
[2018-09-19 15:39:11 UTC]       P556R7  INFO   Rendered cv_api/system_messages/index.jsonify (239.0ms)
[2018-09-19 15:39:11 UTC]       P556R7  INFO Completed 200 OK in 248ms (Views: 224.6ms | ActiveRecord: 19.0ms)
[2018-09-19 15:39:11 UTC]       P556R7  INFO

[2018-09-19 15:41:03 UTC]       P556R3  WARN    vCenter: Waited 180 seconds attempting to locate VM with IP "10.100.5.136" - giving up
[2018-09-19 15:41:03 UTC]       P556R3  INFO        Cvo: Enqueue job: Find VM #<Thread:0x0000000004491a48>
[2018-09-19 15:41:03 UTC]       P556R3  INFO        Cvo: Onlining "Computer <<domain name>\WIN10LTSB-003$>" with 13 previous login(s) - last login: 2018-09-19 15:26:51 UTC
[2018-09-19 15:41:03 UTC]       P556R3  INFO        Cvo: "Computer <<domain name>\WIN10LTSB-003$>" is coming online on VM "" with ip:"10.100.5.136"
[2018-09-19 15:41:03 UTC]       P556R3  INFO        Cvo: No machine provided to prepare_vm
[2018-09-19 15:41:03 UTC]       P556R3 ERROR   RvSphere: Fault in disable_background_vm_operations #<Thread:0x0000000004491a48> (exception NoMethodError): undefined method `identifier' for nil:NilClass
[2018-09-19 15:41:03 UTC]       P556R3  INFO   RvSphere: Inspecting Array (22400640) (from log block)
[
    [0] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/cvo/hypervisor/vcenter_adapter.rb:260:in `block in disable_background_vm_operations'",
    [1] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/cvo/hypervisor/vcenter_adapter.rb:2290:in `block (2 levels) in connected_request'",
    [2] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/cvo/hypervisor/vcenter_adapter.rb:2283:in `block in with_retry'",
    [3] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/rv_sphere/error_handler.rb:40:in `with_retry'",
    [4] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/cvo/hypervisor/vcenter_adapter.rb:2282:in `with_retry'",
    [5] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/cvo/hypervisor/vcenter_adapter.rb:2289:in `block in connected_request'",
    [6] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/cvo/hypervisor/base_adapter.rb:859:in `block in connected_request'",
    [7] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/cvo/hypervisor/base_adapter.rb:823:in `catch_exceptions'",
    [8] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/cvo/hypervisor/base_adapter.rb:857:in `connected_request'",
    [9] "C:/Program Files (x86)/CloudVolumes/Manager/app/models/cvo/hypervisor/vcenter_adapter.rb:2288:in `connected_request'"
]
[2018-09-19 15:41:03 UTC]       P556R3 ERROR        Cvo: Failed to disable vMotion: undefined method `identifier' for nil:NilClass
[2018-09-19 15:41:03 UTC]       P556R3  INFO        Cvo: Unsuccessful mount result appended to response: Volumes were not mounted due to an error on the server. Please try logging in again, or contact your administrator.
[2018-09-19 15:41:03 UTC]       P556R3  INFO   Rendered text template (0.0ms)
[2018-09-19 15:41:03 UTC]       P556R3  INFO Completed 400 Bad Request in 185580ms (Views: 0.7ms | ActiveRecord: 179.3ms)
[2018-09-19 15:41:03 UTC]       P556R3  INFO

[2018-09-19 15:41:05 UTC]  P2980DJ2877  INFO 2018-09-19T08:41:05-0700: [Worker(host:G1VTGBI02 pid:2980)] Job FindComputerVmJob#perform (id=2878) RUNNING
[2018-09-19 15:41:05 UTC]  P2980DJ2878  INFO        Cvo: Starting job: Find VM #<Thread:0x0000000000a3bf38>
[2018-09-19 15:41:05 UTC]  P2980DJ2878  INFO        Cvo: Hypervisor Pool: Acquired 'esx' adapter 1 of 25 for 'esx-334910699' in 0.0 seconds
[2018-09-19 15:41:05 UTC]  P2980DJ2878  INFO   RvSphere: Connecting to vSphere "root@g1ptesx14.<domain name>.local" on "#<Thread:0x0000000000a3bf38>"
[2018-09-19 15:41:05 UTC]  P2980DJ2878  INFO   RvSphere: Establishing verified SSL connection to vSphere at "https://root@g1ptesx14.<domain name>.local" without certificate authorities due to missing file: "C:/Program Files (x86)/CloudVolumes/Manager/config/cacert.pem"
[2018-09-19 15:41:06 UTC]  P2980DJ2878  INFO        Cvo: Validating SSL certificate for "g1ptesx14.<domain name>.local": Trusted by an administrator
[2018-09-19 15:41:06 UTC]  P2980DJ2878  INFO        Cvo: Validated SSL certificate for "g1ptesx14.<domain name>.local". Validation Result = true, Validation Status = 2.
[2018-09-19 15:41:06 UTC]  P2980DJ2878  INFO        Cvo: Size of AVM SSL Fingerprint cache is 1
[2018-09-19 15:41:06 UTC]  P2980DJ2878  INFO        Cvo: Size of AVM SSL Fingerprint cache is 1
[2018-09-19 15:41:06 UTC]  P2980DJ2878  INFO        Cvo: Size of AVM SSL Fingerprint cache is 1
[2018-09-19 15:41:06 UTC]  P2980DJ2878  INFO   RvSphere: Connection to vSphere at "root@g1ptesx14.<domain name>.local" on "#<Thread:0x0000000000a3bf38>" was successful and took 1269.8ms
[2018-09-19 15:41:06 UTC]  P2980DJ2878  INFO   RvSphere: vSphere Server: "VMware ESXi 6.0.0 build-5224934" Instance: "" License: "VMware ESX Server"
[2018-09-19 15:41:06 UTC]  P2980DJ2878  INFO        Cvo: Size of the AVM SSL Fingerprint cache is 0
[2018-09-19 15:41:06 UTC]  P2980DJ2878  INFO    vCenter: Unable to find a VM by the provided vmpath "/TestDev/vm/Win10 Virtual Desktop v2/17Sept2018/Win10LTSB-003"

pastedImage_1.png

Reply
0 Kudos
Ray_handels
Virtuoso
Virtuoso

I'm sorry but I'm at a loss. Installing it here was very straightforward and attaching appstacks was really easy but I can't seem to find what is going on there in your environment without looking at it more closely.

Cvo: Unsuccessful mount result appended to response: Volumes were not mounted due to an error on the server. Please try logging in again, or contact your administrator.

For me it seems as it there is something wrong with the VMWare ESx but that's about as much as I can say. Maybe someone else has an idea??

Reply
0 Kudos
JohnFLi
Enthusiast
Enthusiast

It's probably why support doesn't want to touch it.   lol

One thing that I did try and was able to do.....

On the provisioning machine (after going back to a snapshot before installing an app), i was able to attach the appstack.   its very nice when it does work at least.

Reply
0 Kudos
Ray_handels
Virtuoso
Virtuoso

I'd suggest trying to rebuild the environment and (if you did not do this before) use the manual step by step,

To be honest it is quite straight forward the installation of Appvolumes itself.

You just install the manager, set up AD, vsphere and a database, upload templates, create an appstack and just assign it. After that it does get a little bit more complicated. It's kinda like easy to learn hard to master.

Reply
0 Kudos
jamorris27
Contributor
Contributor

Hi, I'm curious to if you ever solved this issue. Seems to me you may be having a network issue - which if you're using a firewall, load balancer or something of the sort, you will definitely need to make provisions to open ports and prepare your environment for App Volumes.

Reply
0 Kudos