VMware Horizon Community
tschuegy
Enthusiast
Enthusiast
‎03-03-2017 11:16 AM

App Volumes 2.12 - Win10 1607 - Slow login

We have Windows 10 1607 as golden Image and use AppVolumes 2.12. Writable volumes are disabled. We use UEM for user settings synchronisation. Whith no AppStacks attached, we have a logon time of about 30-40 seconds. After we applied one or two appstacks, the logon time will grow up to about 1 minute. In the svservice.log file i can see e delay of several seconds.

AppVolumes_SlowLogin.PNG

Why do we have such a delay?

In this two appstacks i have modified for thesting the snapvol.cfg file and disabled the recursive registry. But that not really helped us in our environment.

What can i do to perform the logon? What is causing the slow logon?

Thx

Tschuegy

Reply
11 Replies
tschuegy
Enthusiast
Enthusiast
‎03-07-2017 12:07 PM

Here are new facts and log files

logon

Logontime

AppStacks

comments

20:17 – 07.03.2017

00:40

No appstacks

20:24 – 07.03.2017

00:41

"

20:31 – 07.03.2017

00:40

"

20:33 – 07.03.2017

00:42

"

20:35 – 07.03.2017

00:40

"

20:38 – 07.03.2017

01:45

2

About 1 minute longer login

20:44 – 07.03.2017

01:31

"

"

Here are the svservice.log for the last logon attempt:

[2017-03-07 19:44:14.384 UTC] [svservice:P1200:T1940] OnLogon called (Session ID 1, Handle 000002A1B3422660, Params 000000A7BA3FEC20, Context 0000000000000000)


[2017-03-07 19:44:14.384 UTC] [svservice:P1200:T1940] OnLogon: Domain\Username (NameSamCompatible)


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] OnLogon: CN=Testuser,OU=Cluster01,OU=Testusers,OU=Accounts,OU=Domain,DC=Domain-net,DC=ch (NameFullyQualifiedDN)


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] OnLogon: Username@Domain-net.ch (NameUserPrincipal)


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] IsDomainJoinedComputer: NetGetJoinInformation() success, domain name Domain and type is 3


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] GetUserComputerInfo: user:"Username" computer:"computername" userdomain:"Domain" computerdomain: "Domain"


[2017-03-07 19:44:14.510 UTC] [svservice:1940] GetUserProfileDirectoryW failed: error code 2


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] OnLogon: skipping scripts because filtering is inactive


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] HttpUserLogin: called 0 logged in (user login)


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] svdriver is running


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] Initializing HTTP connection


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] HttpInitializeConnection: Asynchronous volume mounting is: Disabled.


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] Choosing a manager out of 1 configured manager(s)


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] User login URL: /user-login?name=svservice&uuid=421fa5a9-51ee-3919-e0bf-0e491bf8318c&status=0&osver=10.0.14393&sp=0.0&suite=256&product=1&arch=9&proc=2&agentver=2.12.0.32U&username=Username&domain=Domain&userdomain=Domain&workstation=computername&machinesid=S-1-5-21-3734549950-481944328-2256618682


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] [0] Connecting to avserver.Domain-net.ch:80 using HTTP (attempt 1)


[2017-03-07 19:44:14.510 UTC] [svservice:P1200:T1940] WinHttpSendRequestWithSSLCertValidation: SSL certificate validation is disabled.


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] WinHttpSendRequestWithSSLCertValidation: SSL Certificate validation succeeded.


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] HttpReadAllAvailableData: Error 0 in WinHttpQueryDataAvailable: dwSize 0


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] HttpInitializeRequest: Manager status 200 response (459 bytes): *2*#2#LOGIN Domain\Username


MOUNTED-READ;Storage1\cloudvolumes\apps\Applications1.vmdk;{4bd505f7-bd12-427e-aaa5-949ba6941d05}


MOUNTED-READ;Storage1\cloudvolumes\apps\Applications2.vmdk;{1886aaf2-bfe0-4a23-94b1-3d769e49b025}


DISABLE-APP;{0e0f9258-34cf-453b-b0dd-eefa12074255};{4bd505f7-bd12-427e-aaa5-949ba6941d05}


DISABLE-APP;{977dcfb8-63fd-48d2-bc86-96887f92d4b5};{1886aaf2-bfe0-4a23-94b1-3d769e49b025}


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] HttpUserLogin: succeeded (user login)


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] ReadOutVolsFromString: NewVolumes 2 TotalVolumes 2


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] ReadOutVolsFromString: volume path:"Storage1\cloudvolumes\apps\Applications1.vmdk" guid:"{4bd505f7-bd12-427e-aaa5-949ba6941d05}" type:"mounted-read"


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] ReadOutVolsFromString: add AppStack to load order list:"{4bd505f7-bd12-427e-aaa5-949ba6941d05}"(1)


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] ReadOutVolsFromString: volume path:"Storage1\cloudvolumes\apps\Applications2.vmdk" guid:"{1886aaf2-bfe0-4a23-94b1-3d769e49b025}" type:"mounted-read"


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] ReadOutVolsFromString: add AppStack to load order list:"{1886aaf2-bfe0-4a23-94b1-3d769e49b025}"(2)


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] SvdSetReorderCounter: set to 2


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] LogonMount: agent:0 manager:2 volume(s) to attach


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] Activate filtering (called by WaitVolumesLoading)


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] WaitVolumesLoading: waiting for 2 total volume(s), 0 processed, max wait 180


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] WaitVolumesLoading: Wait for first volume only is 1


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1460] MeasureTime::RecordCenter: Start recording  GUID:{01a636e7-0000-0000-0000-200000000000} Type:0


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1460] Preload volume event (startup): "\Device\HarddiskVolume1" GUID {01a636e7-0000-0000-0000-200000000000} Hive  (0 logged in, SystemVolume 1, VolumeType 0)


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1460] Sending reply to SVCMD_ID_NEW_VOLUME_PRE (Message 1, Size 24)


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] Found 3 SCSI adapter(s)


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T1940] Found 4 disk(s)


[2017-03-07 19:44:16.916 UTC] [svservice:P1200:T3436] WaitVolumesLoadingWorker: waiting for 2 total volume(s), 0 processed, max wait 180


[2017-03-07 19:44:17.056 UTC] [svservice:P1200:T1940] Found 4 disk interface(s)


[2017-03-07 19:44:17.056 UTC] [svservice:P1200:T1940] : GetDevicePathFromClass:SetupDiGetDeviceRegistryProperty [\Device\HarddiskVolume2]


[2017-03-07 19:44:17.056 UTC] [svservice:P1200:T1940] [Volume.Dev0] Generic volume: \Device\HarddiskVolume2


[2017-03-07 19:44:17.056 UTC] [svservice:P1200:T1940] : GetDevicePathFromClass:SetupDiGetDeviceRegistryProperty [\Device\HarddiskVolume1]


[2017-03-07 19:44:17.056 UTC] [svservice:P1200:T1940] [Volume.Dev1] Generic volume: \Device\HarddiskVolume1


[2017-03-07 19:44:17.056 UTC] [svservice:P1200:T1940] : GetDevicePathFromClass:SetupDiGetDeviceRegistryProperty [\Device\HarddiskVolume3]


[2017-03-07 19:44:17.088 UTC] [svservice:P1200:T1460] MeasureTime::RecordCenter: Start recording  GUID:{00002951-0000-0000-0000-100000000000} Type:0


[2017-03-07 19:44:17.088 UTC] [svservice:P1200:T1460] Preload volume event (startup): "\Device\HarddiskVolume3" GUID {00002951-0000-0000-0000-100000000000} Hive  (0 logged in, SystemVolume 0, VolumeType 0)


[2017-03-07 19:44:17.103 UTC] [svservice:P1200:T1940] [Volume.Dev2] Appvolume path: \Device\HarddiskVolume3


[2017-03-07 19:44:17.103 UTC] [svservice:P1200:T1940] Checking for scripts on "\Device\HarddiskVolume3" (event logon)


[2017-03-07 19:44:17.103 UTC] [svservice:P1200:T1940] : GetDevicePathFromClass:SetupDiGetDeviceRegistryProperty [\Device\HarddiskVolume4]


[2017-03-07 19:44:17.119 UTC] [svservice:P1200:T1460] Worker: read meta ID:("{4bd505f7-bd12-427e-aaa5-949ba6941d05}") success!


[2017-03-07 19:44:17.119 UTC] [svservice:P1200:T1460] Detected appstack volume. Path: \Device\HarddiskVolume3 GUID: {00002951-0000-0000-0000-100000000000}


[2017-03-07 19:44:17.119 UTC] [svservice:P1200:T1460] PreCheckVolume: Volume \Device\HarddiskVolume3 checking {4bd505f7-bd12-427e-aaa5-949ba6941d05}


[2017-03-07 19:44:17.119 UTC] [svservice:P1200:T1460] PreCheckVolume: Volume free space is 21219074048 bytes and total space is 21471686656 bytes


[2017-03-07 19:44:17.119 UTC] [svservice:P1200:T1460] Read registry value VolAttachMessageOn (value is 0)


[2017-03-07 19:44:17.119 UTC] [svservice:1460] DelRegConfig(ProvisioningInProgress) error


[2017-03-07 19:44:17.119 UTC] [svservice:P1200:T1460] PreCheckVolume: modify appstack: "FALSE"


[2017-03-07 19:44:17.119 UTC] [svservice:P1200:T1460] Worker: Attached volumes count is now 1 of 2


[2017-03-07 19:44:17.119 UTC] [svservice:P1200:T1460] All volumes attached, processing...


[2017-03-07 19:44:17.135 UTC] [svservice:P1200:T1460] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.385 UTC] [svservice:P1200:T1460] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.400 UTC] [svservice:P1200:T1460] Checking for scripts on "\Device\HarddiskVolume3" (event prestartup)


[2017-03-07 19:44:17.400 UTC] [svservice:P1200:T1460] Sending reply to SVCMD_ID_NEW_VOLUME_PRE (Message 2, Size 24)


[2017-03-07 19:44:17.400 UTC] [svservice:P1200:T1460] MeasureTime::RecordCenter: Start recording  GUID:{00007951-0000-0000-0000-100000000000} Type:0


[2017-03-07 19:44:17.400 UTC] [svservice:P1200:T1460] Preload volume event (startup): "\Device\HarddiskVolume4" GUID {00007951-0000-0000-0000-100000000000} Hive  (0 logged in, SystemVolume 0, VolumeType 0)


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1940] [Volume.Dev3] Appvolume path: \Device\HarddiskVolume4


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1940] Checking for scripts on "\Device\HarddiskVolume4" (event logon)


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1940] Found 4 volume(s)


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1940] Found 4 volume interface(s)


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1940] OnLogon: succeeded


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1460] Worker: read meta ID:("{1886aaf2-bfe0-4a23-94b1-3d769e49b025}") success!


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1460] Detected appstack volume. Path: \Device\HarddiskVolume4 GUID: {00007951-0000-0000-0000-100000000000}


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1460] PreCheckVolume: Volume \Device\HarddiskVolume4 checking {1886aaf2-bfe0-4a23-94b1-3d769e49b025}


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1460] PreCheckVolume: Volume free space is 20631502848 bytes and total space is 21471686656 bytes


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1460] Read registry value VolAttachMessageOn (value is 0)


[2017-03-07 19:44:17.416 UTC] [svservice:1460] DelRegConfig(ProvisioningInProgress) error


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1460] PreCheckVolume: modify appstack: "FALSE"


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1460] Worker: Attached volumes count is now 2 of 2


[2017-03-07 19:44:17.416 UTC] [svservice:P1200:T1460] Creating mount point at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.432 UTC] [svservice:P1200:T1460] "\\?\Volume{00007951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.432 UTC] [svservice:P1200:T1460] Checking for scripts on "\Device\HarddiskVolume4" (event prestartup)


[2017-03-07 19:44:17.432 UTC] [svservice:P1200:T1460] Sending reply to SVCMD_ID_NEW_VOLUME_PRE (Message 3, Size 24)


[2017-03-07 19:44:17.728 UTC] [svservice:P1200:T1460] MeasureTime::RecordCenter: Start recording  GUID:{01a636e7-0000-0000-0000-200000000000} Type:1


[2017-03-07 19:44:17.728 UTC] [svservice:P1200:T1460] New volume event (startup): "\Device\HarddiskVolume1" GUID {01a636e7-0000-0000-0000-200000000000} Hive \REGISTRY\MACHINE\SnapVolumes-{01a636e7-0000-0000-0000-200000000000} (0 logged in, SystemVolume 1, VolumeType 1, Hide 0)


[2017-03-07 19:44:17.728 UTC] [svservice:P1200:T1460] Sending reply to SVCMD_ID_NEW_VOLUME (Message 4)


[2017-03-07 19:44:17.728 UTC] [svservice:P1200:T1460] MeasureTime::RecordCenter: End recording  GUID:{01a636e7-0000-0000-0000-200000000000} Type:1


[2017-03-07 19:44:17.728 UTC] [svservice:P1200:T1460] MeasureTime::RecordCenter: Start recording  GUID:{00007951-0000-0000-0000-100000000000} Type:2


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] Starting services on a volume "\Device\HarddiskVolume1" (GUID {01a636e7-0000-0000-0000-200000000000}, SystemVolume 1)


Reading services from "\REGISTRY\MACHINE\SnapVolumes-{01a636e7-0000-0000-0000-200000000000}\MACHINE\System\ControlSet001\Services"


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] Checking for boot drivers


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] Checking for system drivers


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] Checking for auto-start services


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] MeasureTime::RecordCenter: Start recording  GUID:{00002951-0000-0000-0000-100000000000} Type:2


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] New volume event (mounted): "\Device\HarddiskVolume3" GUID {00002951-0000-0000-0000-100000000000} Hive \REGISTRY\MACHINE\SnapVolumes-{00002951-0000-0000-0000-100000000000} (0 logged in, SystemVolume 0, VolumeType 2, Hide 0)


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] UpdateMountedVolume "\Device\HarddiskVolume3" SnapvolType 2 Hide 0 Updated 1


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1460] New volume event (mounted): "\Device\HarddiskVolume4" GUID {00007951-0000-0000-0000-100000000000} Hive \REGISTRY\MACHINE\SnapVolumes-{00007951-0000-0000-0000-100000000000} (0 logged in, SystemVolume 0, VolumeType 2, Hide 0)


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] Checking for scripts on "\Device\HarddiskVolume3" (event startup)


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1460] UpdateMountedVolume "\Device\HarddiskVolume4" SnapvolType 2 Hide 0 Updated 1


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1460] Checking for scripts on "\Device\HarddiskVolume4" (event startup)


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1456] RunScript: Launching "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\startup.bat" (event startup, CreationFlag 0x08000020, wait -1 ms)


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1460] Creating mount point at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1460] "\\?\Volume{00007951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.744 UTC] [svservice:P1200:T1460] RunScript: Launching "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\startup.bat" (event startup, CreationFlag 0x08000020, wait -1 ms)


[2017-03-07 19:44:17.760 UTC] [svservice:P1200:T1456] Successfully launched: \SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\startup.bat (wait -1 ms)


[2017-03-07 19:44:17.760 UTC] [svservice:P1200:T1460] Successfully launched: \SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\startup.bat (wait -1 ms)


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] Finished waiting for "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\startup.bat" (WaitStatus 0, ExitCode 0)


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] Checking for scripts on "\Device\HarddiskVolume3" (event logon)


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] DriverStore not found on \Device\HarddiskVolume3


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] Sending reply to SVCMD_ID_NEW_VOLUME (Message 7)


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] MeasureTime::RecordCenter: End recording  GUID:{00002951-0000-0000-0000-100000000000} Type:2


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] Starting services on a volume "\Device\HarddiskVolume3" (GUID {00002951-0000-0000-0000-100000000000}, SystemVolume 0)


Reading services from "\REGISTRY\MACHINE\SnapVolumes-{00002951-0000-0000-0000-100000000000}\MACHINE\System\ControlSet001\Services"


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] Checking for boot drivers


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] Checking for system drivers


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] Checking for auto-start services


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] Worker: running scripts after starting services (0 logged in)


[2017-03-07 19:44:17.853 UTC] [svservice:P1200:T1456] Checking for scripts on "\Device\HarddiskVolume3" (event startup_postsvc)


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1456] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1456] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1456] RunScript: Launching "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\startup_postsvc.bat" (event startup_postsvc, CreationFlag 0x08000020, wait -1 ms)


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Finished waiting for "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\startup.bat" (WaitStatus 0, ExitCode 0)


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Checking for scripts on "\Device\HarddiskVolume4" (event logon)


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] DriverStore not found on \Device\HarddiskVolume4


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Sending reply to SVCMD_ID_NEW_VOLUME (Message 5)


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] MeasureTime::RecordCenter: End recording  GUID:{00007951-0000-0000-0000-100000000000} Type:2


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Starting services on a volume "\Device\HarddiskVolume4" (GUID {00007951-0000-0000-0000-100000000000}, SystemVolume 0)


Reading services from "\REGISTRY\MACHINE\SnapVolumes-{00007951-0000-0000-0000-100000000000}\MACHINE\System\ControlSet001\Services"


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Service "DeviceAssociationService" is already registered (reconfiguring)


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Checking for boot drivers


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Checking for system drivers


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Checking for auto-start services


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Worker: running scripts after starting services (0 logged in)


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Checking for scripts on "\Device\HarddiskVolume4" (event startup_postsvc)


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] Creating mount point at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] "\\?\Volume{00007951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1460] RunScript: Launching "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\startup_postsvc.bat" (event startup_postsvc, CreationFlag 0x08000020, wait -1 ms)


[2017-03-07 19:44:17.869 UTC] [svservice:P1200:T1456] Successfully launched: \SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\startup_postsvc.bat (wait -1 ms)


[2017-03-07 19:44:17.885 UTC] [svservice:P1200:T1460] Successfully launched: \SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\startup_postsvc.bat (wait -1 ms)


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1456] Finished waiting for "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\startup_postsvc.bat" (WaitStatus 0, ExitCode 0)


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1456] Checking for scripts on "\Device\HarddiskVolume3" (event logon_postsvc)


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T3436] Waiting for 2 new volumes before resetting order (2 processed, waited 1 second(s) so far)


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T3436] ResetVolumeReordering: resetting reorder counter (called by WaitVolumesLoadingWorker)


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T3436] ResetVolumeReordering: SvdResetReorderList success!


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T3436] SvdSetReorderCounter: set to 0


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1460] Finished waiting for "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\startup_postsvc.bat" (WaitStatus 0, ExitCode 0)


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1460] Checking for scripts on "\Device\HarddiskVolume4" (event logon_postsvc)


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1460] Sending reply to SVCMD_ID_LAST_VOLUME (Message 10)


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1460] SetRegConfigDWord(VolReady, 1) success


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1460] Checking for scripts on "\Device\HarddiskVolume4" (event allvolattached)


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1460] Creating mount point at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1460] "\\?\Volume{00007951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:44:17.931 UTC] [svservice:1460] RunScript: "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\allvolattached.bat" has size zero


[2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1460] All volumes finished processing


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] OnStartShell called (Session ID 1, Handle 000002A1B3422660, Params 000000A7BA3FEC68, Context 0000000000000000)


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] OnStartShell: Domain\Username (NameSamCompatible)


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] Found 3 SCSI adapter(s)


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] Found 4 disk(s)


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] Found 4 disk interface(s)


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] : GetDevicePathFromClass:SetupDiGetDeviceRegistryProperty [\Device\HarddiskVolume2]


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] [Volume.Dev0] Generic volume: \Device\HarddiskVolume2


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] : GetDevicePathFromClass:SetupDiGetDeviceRegistryProperty [\Device\HarddiskVolume1]


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] [Volume.Dev1] Generic volume: \Device\HarddiskVolume1


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] : GetDevicePathFromClass:SetupDiGetDeviceRegistryProperty [\Device\HarddiskVolume3]


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] [Volume.Dev2] Appvolume path: \Device\HarddiskVolume3


[2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] Checking for scripts on "\Device\HarddiskVolume3" (event shellstart)


[2017-03-07 19:45:13.620 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:13.620 UTC] [svservice:P1200:T1940] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:13.620 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:13.636 UTC] [svservice:P1200:T1940] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:13.636 UTC] [svservice:P1200:T1940] RunScript: user script (event shellstart) on "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\" go process.


[2017-03-07 19:45:13.636 UTC] [svservice:P1200:T1940] RunExecutableAsUser: Path "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\shellstart.bat"


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


[2017-03-07 19:45:14.152 UTC] [svservice:P1200:T1940] Successfully launched (elevated privileges): \SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\shellstart.bat (wait -1 ms), pid=552 tid=4792


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] Finished waiting for "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\shellstart.bat": CreationFlag 0x08000420, WaitStatus 0x00000000, ExitCode 0 (0x00)


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] Successfully launched: \SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\shellstart.bat (wait -1 ms)


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] RunScript: user script (event windows_install_fonts) on "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\" go process.


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] EnumAndLaunchRunKeyValues: No autorun values in \REGISTRY\MACHINE\SnapVolumes-{00002951-0000-0000-0000-100000000000}\MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion\Run


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] EnumAndLaunchRunKeyValues: No autorun values in \REGISTRY\MACHINE\SnapVolumes-{00002951-0000-0000-0000-100000000000}\MACHINE\SOFTWARE\Wow6432Node\Microsoft\Windows\CurrentVersion\Run


[2017-03-07 19:45:14.433 UTC] [svservice:P1200:T1940] Checking for scripts on "\Device\HarddiskVolume3" (event allvolattached_shellstarted)


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T1940] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T1940] "\\?\Volume{00002951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T1940] RunScript: user script (event allvolattached_shellstarted) on "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\" go process.


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T1940] RunExecutableAsUser: Path "\SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\allvolattached_shellstarted.bat"


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


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T2208] ReplicateFonts_Install: No fonts in {00002951-0000-0000-0000-100000000000}


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T1940] Successfully launched (elevated privileges): \SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\allvolattached_shellstarted.bat (wait 0 ms), pid=1060 tid=792


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T1940] Successfully launched: \SnapVolumesTemp\MountPoints\{00002951-0000-0000-0000-100000000000}\allvolattached_shellstarted.bat (wait 0 ms)


[2017-03-07 19:45:14.449 UTC] [svservice:P1200:T1940] Refreshing desktop (hToken 0000000000000578)


[2017-03-07 19:45:14.683 UTC] [svservice:P1200:T1940] RunExecutableAsUser: Path "C:\Program Files (x86)\CloudVolumes\Agent\svservice.exe"


[2017-03-07 19:45:14.683 UTC] [svservice:P1200:T1940] RunExecutableAsUser: CommandLine svservice.exe refresh


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


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] Successfully launched (elevated privileges): svservice.exe refresh (wait 0 ms), pid=2108 tid=4504


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] Successfully launched: svservice.exe refresh (wait 0 ms)


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] Checking for svcapture on "\Device\HarddiskVolume3" (event svcapture)


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] RunScript: creating thread to check provisioning state


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] : GetDevicePathFromClass:SetupDiGetDeviceRegistryProperty [\Device\HarddiskVolume4]


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] [Volume.Dev3] Appvolume path: \Device\HarddiskVolume4


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] Checking for scripts on "\Device\HarddiskVolume4" (event shellstart)


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] "\\?\Volume{00007951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] "\\?\Volume{00007951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] RunScript: user script (event shellstart) on "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\" go process.


[2017-03-07 19:45:15.120 UTC] [svservice:P1200:T1940] RunExecutableAsUser: Path "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\shellstart.bat"


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


[2017-03-07 19:45:15.136 UTC] [svservice:P1200:T1940] Successfully launched (elevated privileges): \SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\shellstart.bat (wait -1 ms), pid=3016 tid=5436


[2017-03-07 19:45:15.261 UTC] [svservice:P2108:T4504] *** Started


[2017-03-07 19:45:15.277 UTC] [svservice:P2108:T4504] Refreshing desktop


[2017-03-07 19:45:15.277 UTC] [svservice:P2108:T4504] Refreshing Environment


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] Finished waiting for "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\shellstart.bat": CreationFlag 0x08000420, WaitStatus 0x00000000, ExitCode 0 (0x00)


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] Successfully launched: \SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\shellstart.bat (wait -1 ms)


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] "\\?\Volume{00007951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] RunScript: user script (event windows_install_fonts) on "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\" go process.


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] "\\?\Volume{00007951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] EnumAndLaunchRunKeyValues: No autorun values in \REGISTRY\MACHINE\SnapVolumes-{00007951-0000-0000-0000-100000000000}\MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion\Run


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] Creating mount point at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] "\\?\Volume{00007951-0000-0000-0000-100000000000}\" mounted at "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\"


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] EnumAndLaunchRunKeyValues: No autorun values in \REGISTRY\MACHINE\SnapVolumes-{00007951-0000-0000-0000-100000000000}\MACHINE\SOFTWARE\Wow6432Node\Microsoft\Windows\CurrentVersion\Run


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T1940] Refreshing desktop (hToken 0000000000000578)


[2017-03-07 19:45:15.495 UTC] [svservice:P1200:T6056] ReplicateFonts_Install: No fonts in {00007951-0000-0000-0000-100000000000}


[2017-03-07 19:45:15.511 UTC] [svservice:P1200:T1940] RunExecutableAsUser: Path "C:\Program Files (x86)\CloudVolumes\Agent\svservice.exe"


[2017-03-07 19:45:15.511 UTC] [svservice:P1200:T1940] RunExecutableAsUser: CommandLine svservice.exe refresh


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


[2017-03-07 19:45:15.527 UTC] [svservice:P1200:T1940] Successfully launched (elevated privileges): svservice.exe refresh (wait 0 ms), pid=5656 tid=4716


[2017-03-07 19:45:15.527 UTC] [svservice:P1200:T1940] Successfully launched: svservice.exe refresh (wait 0 ms)


[2017-03-07 19:45:15.527 UTC] [svservice:P1200:T1940] Checking for svcapture on "\Device\HarddiskVolume4" (event svcapture)


[2017-03-07 19:45:15.527 UTC] [svservice:P1200:T1940] RunScript: creating thread to check provisioning state


[2017-03-07 19:45:15.527 UTC] [svservice:P1200:T1940] Found 4 volume(s)


[2017-03-07 19:45:15.527 UTC] [svservice:P1200:T1940] Found 4 volume interface(s)


[2017-03-07 19:45:15.527 UTC] [svservice:1940] HttpFileShareRequest WinHttp over SSL is disabled. Log collection to file share not supported.


[2017-03-07 19:45:15.527 UTC] [svservice:P1200:T1940] handleFileShareStr: File share info received from manager is empty.


[2017-03-07 19:45:15.527 UTC] [svservice:P1200:T1940] OnStartShell: Error Failed to Start DCT Logger


[2017-03-07 19:45:15.652 UTC] [svservice:P5656:T4716] *** Started


[2017-03-07 19:45:15.652 UTC] [svservice:P5656:T4716] Refreshing desktop


[2017-03-07 19:45:15.667 UTC] [svservice:P5656:T4716] Refreshing Environment


[2017-03-07 19:45:18.902 UTC] [svservice:P2108:T4504] Finished refreshing environment successfully


[2017-03-07 19:45:20.699 UTC] [svservice:P5656:T4716] Finished refreshing environment successfully

The gap of about 1 minute is betwen line 156 and 157. Can somebody explain me the 1 minute longer logon time?

Thx

Tschuegy

Reply
Sravan_k
Expert
Expert
‎03-09-2017 02:59 PM

Hi Tschuegy,

Have you created profile's for applications in UEM? if so make sure please enable direct-flex for each and every application 

and also please consider system resource allocation (CPU and memory)  

Reply
0 Kudos
tschuegy
Enthusiast
Enthusiast
‎03-10-2017 10:54 AM

Hi Kumar

Yes we have created profiles for applications in UEM. But we have also the default profiles which are synced at logon/logoff. And we have several Java applications that whe must sync at logon/logoff because the javaw.exe could run for another application. Your solution is not really a solution, because the two VMware products (AppVolumes and UEM) have to be coexistent on the same client. It can not be that this products only works with direct-flex.

Whe have actually more than 512GB RAM (DDR4) and 22 cores per CPU with a double CPU Server. 8 equivalent servers are in the production environment and we have a CPU ration of about 3:1. That cannot be the problem. Actually we have stopped the AppVolume produkt implementation at the moment. Without AppVolumes we have a logontime from about 30 to 40 seconds with UEM. All applications that we had in AppStacks are recreated with ThinApp 5.2.2 and that works for us very well.

We consider severall strange behavior with AppVolumes/Writable volumes:

- Printers are not mapped or the default printer is not set. Several user are telling that they cannot print even the printer is mapped correctly. For me, printerhandling is extremly buggy with appvolumes

- Start menu in Win10 not working properly with appvolumes

- User has dataloss after a few logins (5-50 logins) with writable volumes

- Extremly slow login compared to no AppStacks attached

- Several Excel sheets does not work properly. The embedded macros are not working. Without AppStacks, all the excel sheet are working again

- With writable volume attached, the hole system affected from the filter driver. After a few days the writable volume grow up to more than 3GB. At a deeper research i can see the c:\windows\assambly with an amount of over 700MB's. Writable volumes are cool, but we only used it for profile management. With soooooooo many bugs, sorry we cant use it in production environment

The conclusion: We standby the appvolumes and make incidents to VMware and address out problems. All our problems must be resolved before we can try it in our lab.

Tschuegy

Reply
Gagan201110141
Enthusiast
Enthusiast
‎03-13-2017 11:02 AM

I want to note I am having %100 the same issue but even slower times as I have 40 + gig of Software I want to use with App Vol's

But even with 4 + apps my log in times are 6 + minutes with Windows 10

There are a bunch of people with these same issue and no solution so far.

Reply
0 Kudos
Sravan_k
Expert
Expert
‎03-13-2017 07:02 PM

Yeah, Even I agree Tschuegy, a-path from this I am also experiencing one issue with UEM that is even I am not enabling my "direct-flex" for few applications, user settings are writing to user profiles.

one other issue with UEM is, one of my application is writing when user launch the application but not when user close the application    

Reply
0 Kudos
mellicott
Enthusiast
Enthusiast
‎03-14-2017 02:20 AM

one other issue with UEM is, one of my application is writing when user launch the application but not when user close the application 

Hi Kumar,

If your application has spawned other processes then UEM will wait until these are finished before writing data away.  For these, I use the logon/logoff option rather than direct flex.

Horizon Client 5.4.3 Appvol Manager 2.18 Appvol Client 2.18.6 UEM 9.10
Reply
Sravan_k
Expert
Expert
‎03-14-2017 06:31 PM

Hi Mellicott,

I am not familiar with logon/logoff option, can you please give me some reference so that it will help me 

Reply
0 Kudos
mellicott
Enthusiast
Enthusiast
‎03-15-2017 01:33 AM

Hi Kumar,

Really simple, just don't specify a directflex executable, then the processing is processed on logon/logoff.  We've done this for Java settings we want, because of the multitude of ways Java can be initiated.

Horizon Client 5.4.3 Appvol Manager 2.18 Appvol Client 2.18.6 UEM 9.10
Reply
MasergyTX
Contributor
Contributor
‎03-15-2017 05:11 AM

I am having the same issue. I was able to cut down the login on Windows 10 Ent  Build 1607 using mandatory profiles. With 4 AppStacks, it was taking before about 4 to 6 minutes running on SSD (Install Clone), after the mandatory profile in Windows 10, is about 2 to 3, Still does not matter if everything is on SSD, Using UEM 9,1, AppVol 2.12 and View 7,.03,

Reply
0 Kudos
barakni
VMware Employee
VMware Employee
‎03-27-2017 03:25 AM

In regards to the login you can see from these 2 lines:

  1. [2017-03-07 19:44:17.931 UTC] [svservice:P1200:T1460] All volumes finished processing 
  2. [2017-03-07 19:45:13.605 UTC] [svservice:P1200:T1940] OnStartShell called (Session ID 1, Handle 000002A1B3422660, Params 000000A7BA3FEC68, Context 0000000000000000) 

that actually the AV mount finished after few seconds but something is stuck on your login process, my recommendation (other than open an SR to make sure it is not a bug)

1. check if you are using latest templates, 2.12, or migrated templates (and appstacks) from 2.11. try to use the latest

2. recapture on 2.12 if possible

3. try the VolWaitTimeOut parameter mentioned in the admin guide, you can safely change it from the default 3min. (always consult VMware GSS with advanced parameters)

4. if you have ssl enabled, try to disable it as troubleshooting steps.

in general this line

  1. [2017-03-07 19:44:17.931 UTC] [svservice:1460] RunScript: "\SnapVolumesTemp\MountPoints\{00007951-0000-0000-0000-100000000000}\allvolattached.bat" has size zero 

seems weird to me, might be problem with the allvolattached script, but you can always explore to see what it the problem.

hope this helps

Reply
0 Kudos
Gagan201110141
Enthusiast
Enthusiast
‎04-07-2017 04:16 PM

Give my fix a try

WINDOWS 10 SLOW LOGIN FIX For VMWARE app Volumes! 2.12!

Reply
0 Kudos