11 Replies Latest reply on Aug 26, 2016 9:42 AM by Atul_

    View Planner 3.6 VMs will not Register IPs

    gcopeny Novice

      It Looks like one other person had this issue but never received a response. The VMs will not register during this Multi-VM-remote-PCoIP run in View Planner 3.6. Any ideas?

       

      Here is the log file:

       

      2016-08-19 11:33:03,632 DEBUG View Planner instance: Internal error. Please make sure sfcbd is running.

       

      2016-08-19 11:33:03,731 DEBUG View Planner is running in Flexible mode

      2016-08-19 11:33:03,732 DEBUG Creating an instance of View Interface.

      2016-08-19 11:33:03,753 INFO Starting the Twisted server on the appliance.

      2016-08-19 11:33:03,755 DEBUG Creating an instance of Appliance Interface.

      2016-08-19 11:33:03,756 INFO Starting the Web interface on the appliance.

      2016-08-19 11:33:03,762 DEBUG Creating an instance of Web Interface.

      2016-08-19 11:34:01,413 DEBUG Getting all the run profiles from the database.

      2016-08-19 11:34:01,413 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 11:34:01,449 DEBUG Getting all the test names and their start times from the database.

      2016-08-19 11:34:01,450 INFO Running query: SELECT distinct start_time,test_name from testinfo order by start_time DESC

      2016-08-19 11:34:01,453 DEBUG Getting all the workload profiles from the database.

      2016-08-19 11:34:01,453 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 11:34:01,462 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 11:34:01,462 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 11:34:01,463 DEBUG key: VCuser, value: s25775d

      2016-08-19 11:34:01,463 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 11:34:01,463 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 11:34:01,464 DEBUG Getting all the workload profiles from the database.

      2016-08-19 11:34:01,464 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 11:34:01,471 DEBUG Returning View configuration parameters: {'protocol': 'RDP', 'Viewserver': 'dhcs0702v', 'Viewuser': 's25775d', 'adserver': '127.0.0.1', 'addomainname': 'dummy', 'Viewpasswd': '*******'}

      2016-08-19 11:34:01,472 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 11:34:01,473 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 11:34:01,473 DEBUG key: VCuser, value: s25775d

      2016-08-19 11:34:01,473 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 11:34:01,473 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 11:34:01,475 DEBUG Getting all the workload profiles from the database.

      2016-08-19 11:34:01,475 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 11:34:01,477 DEBUG Getting all the new custom applications by parsing the workload config file.

      2016-08-19 11:34:01,583 DEBUG Loading the run profile from the database: Single-VM-local-2

      2016-08-19 11:34:01,583 INFO Running query: SELECT distinct * from runprofile where name = "Single-VM-local-2"

      2016-08-19 11:34:01,607 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 3

      2016-08-19 11:34:01,609 INFO Running query: SELECT * from ADgroupprofile where grp_id = 1

      2016-08-19 11:34:01,611 DEBUG Returning profile: {'deskprefix': 'GoldenDesktop', 'rampuptime': 5L, 'run_id': 3L, 'extrainfo': None, 'numofvms': 1L, 'mode': 0L, 'adgroups': [{'schedlist': None, 'protocol': 0L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 1L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': ''}], 'desktype': 0L, 'name': 'Single-VM-local-2'}

      2016-08-19 11:34:01,611 INFO Running query: SELECT max(test_id),min(test_id) from testinfo where test_name = "Single-VM-local-2" and start_time = "2016-08-17 16:49:05"

      2016-08-19 11:34:01,612 INFO Running query: select count(distinct linfo_FK) from localresults where linfo_FK >= 1 and linfo_FK <= 1

      2016-08-19 11:34:01,614 INFO Running query: select count(distinct info_FK) from remoteresults where info_FK >= 1 and info_FK <= 1

      2016-08-19 11:34:01,616 INFO Running query: select count(distinct lerrinfo_FK) from localerrorinfo where lerrinfo_FK >= 1 and lerrinfo_FK <= 1

      2016-08-19 11:34:01,618 INFO Running query: select count(distinct errinfo_FK) from remoteerrorinfo where errinfo_FK >= 1 and errinfo_FK <= 1

      2016-08-19 11:34:01,620 DEBUG Test Status (name: Single-VM-local-2 start_time: 2016-08-17 16:49:05): 0 uploaded (0 errors)

      2016-08-19 11:34:01,622 DEBUG Getting the detailed information about the workload profile: StorageAppsProfile_5i

      2016-08-19 11:34:01,622 INFO Running query: SELECT distinct * from workloadconfig where name = "StorageAppsProfile_5i"

      2016-08-19 11:34:01,622 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StorageAppsProfile_5i', 'hosttime': 1, 'random': 1, 'Iterations': 5L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,7ZIP,PPT,OUTLOOK,VIDEO'}

      2016-08-19 11:34:07,248 DEBUG Testing the View connection

      2016-08-19 11:34:37,547 INFO View agent check service output: error connecting to dhcs0702v

      Failed

      2016-08-19 11:34:37,547 INFO View agent check return code: -1

      2016-08-19 11:44:35,352 DEBUG Getting all the test names and their start times from the database.

      2016-08-19 11:44:35,353 INFO Running query: SELECT distinct start_time,test_name from testinfo order by start_time DESC

      2016-08-19 11:44:35,355 DEBUG Getting all the workload profiles from the database.

      2016-08-19 11:44:35,355 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 11:44:35,357 DEBUG Returning View configuration parameters: {'protocol': 'RDP', 'Viewserver': 'dhcs0702v', 'Viewuser': 's25775d', 'adserver': '127.0.0.1', 'addomainname': 'dummy', 'Viewpasswd': '*******'}

      2016-08-19 11:44:35,358 DEBUG Getting all the workload profiles from the database.

      2016-08-19 11:44:35,358 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 11:44:35,359 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 11:44:35,359 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 11:44:35,359 DEBUG key: VCuser, value: s25775d

      2016-08-19 11:44:35,360 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 11:44:35,360 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 11:44:35,361 DEBUG Getting all the run profiles from the database.

      2016-08-19 11:44:35,361 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 11:44:35,371 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 11:44:35,372 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 11:44:35,372 DEBUG key: VCuser, value: s25775d

      2016-08-19 11:44:35,372 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 11:44:35,372 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 11:44:35,373 DEBUG Getting all the workload profiles from the database.

      2016-08-19 11:44:35,373 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 11:44:35,381 DEBUG Getting all the new custom applications by parsing the workload config file.

      2016-08-19 11:44:35,382 DEBUG Loading the run profile from the database: Single-VM-local-2

      2016-08-19 11:44:35,382 INFO Running query: SELECT distinct * from runprofile where name = "Single-VM-local-2"

      2016-08-19 11:44:35,383 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 3

      2016-08-19 11:44:35,384 INFO Running query: SELECT * from ADgroupprofile where grp_id = 1

      2016-08-19 11:44:35,385 DEBUG Returning profile: {'deskprefix': 'GoldenDesktop', 'rampuptime': 5L, 'run_id': 3L, 'extrainfo': None, 'numofvms': 1L, 'mode': 0L, 'adgroups': [{'schedlist': None, 'protocol': 0L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 1L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': ''}], 'desktype': 0L, 'name': 'Single-VM-local-2'}

      2016-08-19 11:44:35,385 INFO Running query: SELECT max(test_id),min(test_id) from testinfo where test_name = "Single-VM-local-2" and start_time = "2016-08-17 16:49:05"

      2016-08-19 11:44:35,385 INFO Running query: select count(distinct linfo_FK) from localresults where linfo_FK >= 1 and linfo_FK <= 1

      2016-08-19 11:44:35,386 INFO Running query: select count(distinct info_FK) from remoteresults where info_FK >= 1 and info_FK <= 1

      2016-08-19 11:44:35,387 INFO Running query: select count(distinct lerrinfo_FK) from localerrorinfo where lerrinfo_FK >= 1 and lerrinfo_FK <= 1

      2016-08-19 11:44:35,388 INFO Running query: select count(distinct errinfo_FK) from remoteerrorinfo where errinfo_FK >= 1 and errinfo_FK <= 1

      2016-08-19 11:44:35,388 DEBUG Test Status (name: Single-VM-local-2 start_time: 2016-08-17 16:49:05): 0 uploaded (0 errors)

      2016-08-19 11:44:35,390 DEBUG Getting the detailed information about the workload profile: StorageAppsProfile_5i

      2016-08-19 11:44:35,390 INFO Running query: SELECT distinct * from workloadconfig where name = "StorageAppsProfile_5i"

      2016-08-19 11:44:35,391 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StorageAppsProfile_5i', 'hosttime': 1, 'random': 1, 'Iterations': 5L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,7ZIP,PPT,OUTLOOK,VIDEO'}

      2016-08-19 12:10:34,563 DEBUG Getting all the workload profiles from the database.

      2016-08-19 12:10:34,563 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 12:32:04,210 DEBUG Saving the run profile to the database with parameters: {u'name': u'Single-VM-remote-PCoIP', u'rampuptime': u'5', u'numofvms': u'1', u'mode': u'2', u'adgroups': [{u'protocol': u'2', u'name': u'Test', u'wrk_FK': u'StandardBenchmarkProfile_1i', u'poolname': u'', u'percent': u'100', u'connections_per_client': u'1', u'rdshtype': u'0', u'clientprefix': u'GoldenClient'}], u'desktype': u'0', u'deskprefix': u'GoldenDesktop'}

      2016-08-19 12:32:04,210 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 12:32:04,211 INFO Running query: INSERT INTO runprofile (name, numofvms, mode, deskprefix, rampuptime, desktype) VALUES ("Single-VM-remote-PCoIP", 1, 2, "GoldenDesktop", 5, 0)

      2016-08-19 12:32:04,227 INFO Running query: SELECT distinct * from runprofile where name = "Single-VM-remote-PCoIP"

      2016-08-19 12:32:04,228 INFO Running query: SELECT * from ADgroupprofile where name = "Test" and wrk_FK = "StandardBenchmarkProfile_1i" and percent = 100 and protocol = 2 and clientprefix = "GoldenClient" and connections_per_client = 1 and rdshtype = 0 and poolname = ""

      2016-08-19 12:32:04,228 INFO Running query: INSERT INTO ADgroupprofile (name, wrk_FK, percent, protocol, clientprefix, connections_per_client, rdshtype, poolname) values ("Test", "StandardBenchmarkProfile_1i", 100, 2, "GoldenClient", 1, 0, "")

      2016-08-19 12:32:04,230 INFO Running query: SELECT * from ADgroupprofile where name = "Test" and wrk_FK = "StandardBenchmarkProfile_1i" and percent = 100 and protocol = 2 and clientprefix = "GoldenClient" and connections_per_client = 1 and rdshtype = 0 and poolname = ""

      2016-08-19 12:32:04,230 INFO Running query: INSERT INTO runpivot (grp_FK, run_FK) values (2, 4)

      2016-08-19 12:32:04,242 DEBUG Getting all the run profiles from the database.

      2016-08-19 12:32:04,243 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 12:32:54,743 DEBUG Testing the View connection

      2016-08-19 12:33:25,040 INFO View agent check service output: error connecting to dhcs0702v

      Failed

      2016-08-19 12:33:25,040 INFO View agent check return code: -1

      2016-08-19 12:33:25,042 DEBUG Testing the View connection

      2016-08-19 12:33:55,348 INFO View agent check service output: error connecting to dhcs0702v

      Failed

      2016-08-19 12:33:55,349 INFO View agent check return code: -1

      2016-08-19 12:33:55,350 DEBUG Testing the View connection

      2016-08-19 12:34:25,620 INFO View agent check service output: error connecting to dhcs0702v

      Failed

      2016-08-19 12:34:25,621 INFO View agent check return code: -1

      2016-08-19 12:34:25,623 DEBUG Testing the View connection

      2016-08-19 12:34:55,897 INFO View agent check service output: error connecting to dhcs0702v

      Failed

      2016-08-19 12:34:55,898 INFO View agent check return code: -1

      2016-08-19 12:57:20,854 DEBUG Getting all the test names and their start times from the database.

      2016-08-19 12:57:20,854 INFO Running query: SELECT distinct start_time,test_name from testinfo order by start_time DESC

      2016-08-19 12:57:20,856 DEBUG Getting all the workload profiles from the database.

      2016-08-19 12:57:20,856 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 12:57:20,858 DEBUG Returning View configuration parameters: {'protocol': 'RDP', 'Viewserver': 'dhcs0702v', 'Viewuser': 's25775d', 'adserver': '127.0.0.1', 'addomainname': 'dummy', 'Viewpasswd': '*******'}

      2016-08-19 12:57:20,859 DEBUG Getting all the run profiles from the database.

      2016-08-19 12:57:20,859 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 12:57:20,860 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 12:57:20,860 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 12:57:20,861 DEBUG key: VCuser, value: s25775d

      2016-08-19 12:57:20,861 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 12:57:20,861 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 12:57:20,862 DEBUG Getting all the workload profiles from the database.

      2016-08-19 12:57:20,862 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 12:57:20,869 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 12:57:20,869 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 12:57:20,869 DEBUG key: VCuser, value: s25775d

      2016-08-19 12:57:20,869 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 12:57:20,870 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 12:57:20,871 DEBUG Getting all the workload profiles from the database.

      2016-08-19 12:57:20,871 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 12:57:20,875 DEBUG Loading the run profile from the database: Single-VM-local-2

      2016-08-19 12:57:20,875 INFO Running query: SELECT distinct * from runprofile where name = "Single-VM-local-2"

      2016-08-19 12:57:20,876 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 3

      2016-08-19 12:57:20,877 INFO Running query: SELECT * from ADgroupprofile where grp_id = 1

      2016-08-19 12:57:20,877 DEBUG Returning profile: {'deskprefix': 'GoldenDesktop', 'rampuptime': 5L, 'run_id': 3L, 'extrainfo': None, 'numofvms': 1L, 'mode': 0L, 'adgroups': [{'schedlist': None, 'protocol': 0L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 1L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': ''}], 'desktype': 0L, 'name': 'Single-VM-local-2'}

      2016-08-19 12:57:20,878 INFO Running query: SELECT max(test_id),min(test_id) from testinfo where test_name = "Single-VM-local-2" and start_time = "2016-08-17 16:49:05"

      2016-08-19 12:57:20,878 INFO Running query: select count(distinct linfo_FK) from localresults where linfo_FK >= 1 and linfo_FK <= 1

      2016-08-19 12:57:20,879 INFO Running query: select count(distinct info_FK) from remoteresults where info_FK >= 1 and info_FK <= 1

      2016-08-19 12:57:20,880 INFO Running query: select count(distinct lerrinfo_FK) from localerrorinfo where lerrinfo_FK >= 1 and lerrinfo_FK <= 1

      2016-08-19 12:57:20,881 INFO Running query: select count(distinct errinfo_FK) from remoteerrorinfo where errinfo_FK >= 1 and errinfo_FK <= 1

      2016-08-19 12:57:20,881 DEBUG Test Status (name: Single-VM-local-2 start_time: 2016-08-17 16:49:05): 0 uploaded (0 errors)

      2016-08-19 12:57:20,883 DEBUG Getting all the new custom applications by parsing the workload config file.

      2016-08-19 12:57:20,885 DEBUG Getting the detailed information about the workload profile: StorageAppsProfile_5i

      2016-08-19 12:57:20,885 INFO Running query: SELECT distinct * from workloadconfig where name = "StorageAppsProfile_5i"

      2016-08-19 12:57:20,885 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StorageAppsProfile_5i', 'hosttime': 1, 'random': 1, 'Iterations': 5L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,7ZIP,PPT,OUTLOOK,VIDEO'}

      2016-08-19 13:22:00,615 INFO New client registration: 192.168.155.168

      2016-08-19 13:22:00,615 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.168

      2016-08-19 13:22:06,404 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 13:22:06,404 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 13:22:06,404 DEBUG key: VCuser, value: s25775d

      2016-08-19 13:22:06,404 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 13:22:06,404 DEBUG Creating an instance of vCenter Interface

      2016-08-19 13:22:06,404 DEBUG vCenter info s25775d ******** 192.168.151.180

      2016-08-19 13:22:06,624 INFO Request received to set perfcounter for 192.168.155.168

      2016-08-19 13:22:27,141 INFO displayName : GoldenClient found for ip address: 192.168.155.168 while setting perf counter

      2016-08-19 13:22:27,321 DEBUG Getting the configuration option monitor_control.pseudo_perfctr in VM GoldenClient

      2016-08-19 13:22:27,354 INFO Found value for performance counter to be true

      2016-08-19 13:22:27,357 INFO VM state is powered on, powering off

      2016-08-19 13:22:52,548 DEBUG Changing the Video RAM for VM GoldenClient: 36032 KB

      2016-08-19 13:22:53,623 INFO Video RAM changed in VM GoldenClient

      2016-08-19 13:22:54,658 INFO Creating a snapshot after ViewPlanner agent installation for VM: GoldenClient

      2016-08-19 13:22:55,693 INFO Powering on VM - GoldenClient after creating snapshot

      2016-08-19 13:22:55,706 DEBUG Testing the View connection

      2016-08-19 13:22:55,986 INFO View agent check service output: Success

      2016-08-19 13:22:55,986 INFO View agent check return code: 0

      2016-08-19 13:23:20,940 INFO New client registration: 192.168.155.168

      2016-08-19 13:23:20,940 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.168

      2016-08-19 13:29:21,920 INFO ============================================================

      2016-08-19 13:29:21,920 INFO Executing the run profile from the database: Single-VM-remote-PCoIP

      2016-08-19 13:29:21,921 INFO ============================================================

      2016-08-19 13:29:21,921 INFO Running query: SELECT distinct * from runprofile where name = "Single-VM-remote-PCoIP"

      2016-08-19 13:29:21,922 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 4

      2016-08-19 13:29:21,923 INFO Running query: SELECT * from ADgroupprofile where grp_id = 2

      2016-08-19 13:29:21,924 DEBUG Running profile Single-VM-remote-PCoIP with parameters: {'deskprefix': 'GoldenDesktop', 'rampuptime': 5L, 'run_id': 4L, 'extrainfo': None, 'numofvms': 1L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 2L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'GoldenClient'}], 'desktype': 0L, 'name': 'Single-VM-remote-PCoIP'}

      2016-08-19 13:29:21,924 DEBUG Resetting all variables in shared_data.

      2016-08-19 13:29:21,924 DEBUG Resetting all variables in shared_data [Done].

      2016-08-19 13:29:21,924 INFO Setting the checkpoint index of upload/connections: 5

      2016-08-19 13:29:21,924 DEBUG Setting all past tests state to Done

      2016-08-19 13:29:21,925 INFO Running query: UPDATE testinfo SET test_state = "Done"

      2016-08-19 13:29:21,936 DEBUG Setting all hosts to busy

      2016-08-19 13:29:21,936 INFO Running query: UPDATE hostinfo SET state = "Old"

      2016-08-19 13:29:21,952 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 13:29:21,952 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 13:29:21,952 DEBUG key: VCuser, value: s25775d

      2016-08-19 13:29:21,952 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 13:29:21,953 DEBUG Creating an instance of vCenter Interface

      2016-08-19 13:29:21,953 DEBUG vCenter info s25775d ******** 192.168.151.180

      2016-08-19 13:29:22,119 DEBUG Connecting to the View server agent.

      2016-08-19 13:29:22,120 INFO adding callback function for getting the remote root object for ipaddr: dhcs0702v

      2016-08-19 13:29:22,139 WARNING ViewAPI not supported on this View server.

      2016-08-19 13:29:22,140 DEBUG Connecting to the AD agent

      2016-08-19 13:29:22,140 INFO Adding callback function for getting the remote root object for IP address: 127.0.0.1

      2016-08-19 13:29:22,173 DEBUG Starting the runtest thread 2586

      2016-08-19 13:29:22,175 INFO Getting list of hosts from vCenter

      2016-08-19 13:29:22,179 INFO Got AD object from the AD server.

      2016-08-19 13:29:22,180 INFO Got View object from the View server.

      2016-08-19 13:29:22,203 INFO Getting all the datacenters from vCenter

      2016-08-19 13:29:22,214 INFO Getting all the compute resources from vCenter

      2016-08-19 13:29:22,231 INFO Getting all the clusters from vCenter

      2016-08-19 13:29:22,246 INFO Getting all the VMs from the vCenter.

      2016-08-19 13:29:22,419 INFO Getting all the VMs from the vCenter[done].

      2016-08-19 13:29:22,419 INFO AD group: {'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 2L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'GoldenClient'}

      2016-08-19 13:29:22,420 INFO Running query: SELECT distinct * from workloadconfig where name = "StandardBenchmarkProfile_1i"

      2016-08-19 13:29:22,421 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 13:29:22,421 INFO Making AD config file config-test.txt for the Test AD group desktops with workprofile: {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 13:29:22,421 INFO Looking for 1 VMs with prefixes GoldenDesktop in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'template-win2k12R2-std-vl']

      2016-08-19 13:29:22,422 INFO Matching VM Base_Template_Win7Pro64 with prefix: GoldenDesktop

      2016-08-19 13:29:22,422 INFO Matching VM GI-Win7x64 with prefix: GoldenDesktop

      2016-08-19 13:29:22,422 INFO Matching VM GoldenClient with prefix: GoldenDesktop

      2016-08-19 13:29:22,422 INFO Matching VM GoldenDesktop with prefix: GoldenDesktop

      2016-08-19 13:29:22,422 INFO VM GoldenDesktop matched with prefix: GoldenDesktop

      2016-08-19 13:29:22,422 INFO Desktop VMs found: ['GoldenDesktop']

      2016-08-19 13:29:22,422 INFO Looking for 1 VMs with prefixes GoldenClient in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'template-win2k12R2-std-vl']

      2016-08-19 13:29:22,422 INFO Matching VM Base_Template_Win7Pro64 with prefix: GoldenClient

      2016-08-19 13:29:22,423 INFO Matching VM GI-Win7x64 with prefix: GoldenClient

      2016-08-19 13:29:22,423 INFO Matching VM GoldenClient with prefix: GoldenClient

      2016-08-19 13:29:22,423 INFO VM GoldenClient matched with prefix: GoldenClient

      2016-08-19 13:29:22,423 INFO Client VMs found: ['GoldenClient']

      2016-08-19 13:29:22,423 INFO Loading AD Info to the data structures is done with adinfo: [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 2L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'deskvms': ['GoldenDesktop'], 'rdshtype': 0L, 'clientvms': ['GoldenClient'], 'workprofile': {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}, 'clientprefix': 'GoldenClient'}]

      2016-08-19 13:29:22,426 DEBUG Getting the configuration option monitor_control.pseudo_perfctr in VM GoldenDesktop

      2016-08-19 13:29:22,448 INFO The value of performance counter for VM GoldenDesktop: true

      2016-08-19 13:29:22,448 INFO Powering on 1 desktop VMs

      2016-08-19 13:29:22,448 INFO Powering on VMs in Test AD group: ['GoldenDesktop']

      2016-08-19 13:29:22,449 DEBUG Powering on the VM: GoldenDesktop

      2016-08-19 13:29:22,452 DEBUG VM GoldenDesktop is powered on. Rebooting the VM...

      2016-08-19 13:29:23,456 INFO Powering on 1 client VMs

      2016-08-19 13:29:23,457 INFO Powering on VMs in Test AD group: ['GoldenClient']

      2016-08-19 13:29:23,457 DEBUG Powering on the VM: GoldenClient

      2016-08-19 13:29:23,461 DEBUG VM GoldenClient is powered on. Rebooting the VM...

      2016-08-19 13:29:24,534 INFO Operation: poweron_desktops, Time of Start: Fri Aug 19 13:29:22 2016, Total time taken: 1.01

      2016-08-19 13:29:24,534 INFO Operation: poweron_clients, Time of Start: Fri Aug 19 13:29:23 2016, Total time taken: 1.08

      2016-08-19 13:29:24,535 DEBUG Creating 1 users and adding to the AD security group: Test

      2016-08-19 13:29:24,535 INFO ------------------------------------------------------------

      2016-08-19 13:29:24,536 INFO Waiting for the threshold number of VMs to register

      2016-08-19 13:29:24,536 INFO ------------------------------------------------------------

      Peer will receive following PB traceback:

      Unhandled Error

      Traceback (most recent call last):

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 153, in gotItem

        self.callExpressionReceived(item)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 116, in callExpressionReceived

        self.expressionReceived(obj)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 515, in expressionReceived

        method(*sexp[1:])

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 827, in proto_message

        self._recvMessage(self.localObjectForID, requestID, objectID, message, answerRequired, netArgs, netKw)

      --- <exception caught here> ---

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 841, in _recvMessage

        netResult = object.remoteMessageReceived(self, message, netArgs, netKw)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/flavors.py", line 112, in remoteMessageReceived

        raise NoSuchMethod("No such method: remote_%s" % (message,))

      twisted.spread.flavors.NoSuchMethod: No such method: remote_addUsers

      Peer will receive following PB traceback:

      Unhandled Error

      Traceback (most recent call last):

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 153, in gotItem

        self.callExpressionReceived(item)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 116, in callExpressionReceived

        self.expressionReceived(obj)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 515, in expressionReceived

        method(*sexp[1:])

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 827, in proto_message

        self._recvMessage(self.localObjectForID, requestID, objectID, message, answerRequired, netArgs, netKw)

      --- <exception caught here> ---

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 841, in _recvMessage

        netResult = object.remoteMessageReceived(self, message, netArgs, netKw)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/flavors.py", line 112, in remoteMessageReceived

        raise NoSuchMethod("No such method: remote_%s" % (message,))

      twisted.spread.flavors.NoSuchMethod: No such method: remote_findNetbiosDomainName

      Unhandled error in Deferred:

      Unhandled Error

      Traceback from remote host -- Traceback unavailable

      Unhandled error in Deferred:

      Unhandled Error

      Traceback from remote host -- Traceback unavailable

      2016-08-19 13:29:26,922 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 0 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 13:29:55,087 INFO New client registration: 192.168.155.168

      2016-08-19 13:29:55,087 INFO Current registered machines status : [Pairs required: 1] [clients: 1] [servers: 0]

      2016-08-19 13:29:56,921 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 1 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 13:30:02,334 INFO New client registration: 192.168.151.83

      2016-08-19 13:30:02,334 INFO Current registered machines status : [Pairs required: 1] [clients: 2] [servers: 0]

      2016-08-19 13:30:06,927 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 2 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 13:33:02,335 INFO Scanning all the VMs for any required fixes.

      2016-08-19 13:33:02,335 INFO Getting the IP address for VM GoldenDesktop from vCenter

      2016-08-19 13:33:02,391 INFO Got the IP address for VM GoldenDesktop: 192.168.151.83

      2016-08-19 13:33:02,391 INFO Getting the IP address for VM GoldenClient from vCenter

      2016-08-19 13:33:02,460 INFO Got the IP address for VM GoldenClient: 192.168.155.168

      2016-08-19 13:33:02,460 INFO Done scanning VMs.

      2016-08-19 13:33:19,866 DEBUG ======================================= [START] Registration Status Dump ========================================

      2016-08-19 13:33:20,055 INFO Desktop status: VM(GoldenDesktop) IP(192.168.151.83) Not Registered

       

      2016-08-19 13:33:20,242 INFO Clients status:

       

      2016-08-19 13:33:20,242 DEBUG ======================================= [END] Registration Status Dump ========================================

      2016-08-19 13:36:02,461 INFO Scanning all the VMs for any required fixes.

      2016-08-19 13:36:02,461 INFO Done scanning VMs.

      2016-08-19 13:39:02,560 DEBUG There might be issues with the following VMs in this run (Check vCenter Console to look for errors). Continuing the run with other VMs.

      2016-08-19 13:39:02,561 INFO Getting the IP address for VM GoldenDesktop from vCenter

      2016-08-19 13:39:02,571 INFO Got the IP address for VM GoldenDesktop: 192.168.151.83

      2016-08-19 13:39:02,571 INFO Getting the IP address for VM GoldenClient from vCenter

      2016-08-19 13:39:02,580 INFO Got the IP address for VM GoldenClient: 192.168.155.168

      2016-08-19 13:39:02,580 DEBUG IP: 192.168.155.168 --> Name: GoldenClient

      2016-08-19 13:39:02,580 DEBUG IP: 192.168.151.83 --> Name: GoldenDesktop

      2016-08-19 13:39:02,580 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.151.83"

      2016-08-19 13:39:02,581 INFO Running query: UPDATE hostinfo SET is_Client = "0", state = "Unpaired", name = "GoldenDesktop" where IPADDR = "192.168.151.83"

      2016-08-19 13:39:02,589 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.168"

      2016-08-19 13:39:02,590 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.168", 1, "Unpaired", "GoldenClient")

      2016-08-19 13:39:02,591 INFO Registering remaining VMs.

      2016-08-19 13:39:02,592 INFO Threshold met for the number of machines. Starting the benchmark plan.

      2016-08-19 13:39:02,592 INFO Waiting for VMs to settle; sleeping for the ramp up time.

      2016-08-19 13:39:07,597 DEBUG Using View VP Agent to get the pool-desktop map.

      2016-08-19 13:39:07,598 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:08,028 ERROR Error from the remote: Error in mapping prefixes with error: 2004: Error in mapping VM Prefixes to Pools: string indices must be integers, not str

      Traceback:

      Traceback (most recent call last):

        File "d:\build\ob\bora-3657232\performance\viewplanner\ViewAgent\Package\C\listener.py", line 194, in remote_mapPrefixToPool

        File "d:\build\ob\bora-3657232\performance\viewplanner\ViewAgent\Package\C\viewadmin.py", line 142, in mapPrefixToPool

      TypeError: string indices must be integers, not str

       

      Unhandled error in Deferred:

      Unhandled Error

      Traceback from remote host -- Traceback unavailable

      2016-08-19 13:39:10,127 DEBUG Returning the current status: 0 with message Error in mapping prefixes with error: 2004: Error in mapping VM Prefixes to Pools: string indices must be integers, not str

      Traceback:

      Traceback (most recent call last):

        File "d:\build\ob\bora-3657232\performance\viewplanner\ViewAgent\Package\C\listener.py", line 194, in remote_mapPrefixToPool

        File "d:\build\ob\bora-3657232\performance\viewplanner\ViewAgent\Package\C\viewadmin.py", line 142, in mapPrefixToPool

      TypeError: string indices must be integers, not str

      . Please see viewplanner.log for more details

      2016-08-19 13:39:12,603 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:17,608 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:22,614 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:27,619 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:32,624 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:37,630 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:42,635 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:47,641 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:52,646 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:39:57,651 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:02,657 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:07,662 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:12,668 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:17,673 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:22,679 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:27,684 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:32,690 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:37,695 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:42,700 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:47,706 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:52,711 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:40:57,717 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:02,722 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:07,728 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:12,733 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:17,738 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:22,744 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:27,749 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:32,755 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:37,760 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:42,765 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:47,771 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:52,776 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:41:57,782 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:02,787 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:07,793 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:12,798 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:17,803 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:22,808 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:27,814 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:32,819 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:37,825 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:42,830 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:47,835 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:52,841 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:42:57,846 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:02,852 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:07,857 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:12,862 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:17,868 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:22,873 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:27,879 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:32,884 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:37,890 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:42,895 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:47,900 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:52,906 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:43:57,911 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:02,917 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:07,922 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:12,928 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:17,932 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:22,937 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:27,943 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:32,948 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:37,954 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:42,959 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:47,965 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:52,970 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:44:57,973 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:02,978 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:07,984 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:12,989 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:17,994 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:23,000 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:28,005 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:33,011 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:38,012 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:43,017 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:48,021 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:53,026 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:45:58,032 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:03,037 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:08,043 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:13,048 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:18,053 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:23,059 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:28,064 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:33,070 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:38,075 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:43,080 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:48,086 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:53,091 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:46:58,097 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:47:03,100 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:47:08,106 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:47:13,111 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:47:18,116 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:47:23,122 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:47:28,127 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 13:47:33,132 ERROR Error communicating with ViewPlanner agent in View server. Restart the ViewPlanner agent service in View server and try again.

      2016-08-19 14:35:40,644 DEBUG Getting all the hostnames from the vCenter.

      2016-08-19 14:35:40,665 DEBUG Returning hosts: ['dhvm08001.ncsecu.dev', 'dhvm08002.ncsecu.dev']

      2016-08-19 14:36:01,421 DEBUG Provisioning the VMs using vCenter cloning operations with parameters: {u'maxvmperhost': u'20', u'parentvm': u'GoldenClient', u'vmprefix': u'dvdt', u'confType': u'0', u'numofvms': u'5', u'clonetype': u'1', u'reslist': u'dhvm08001.ncsecu.dev,dhvm08002.ncsecu.dev', u'customspec': u'ViewPlanner_Desktops'}

      2016-08-19 14:36:01,421 DEBUG Stopping the runtest thread.

      2016-08-19 14:36:01,421 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 14:36:01,421 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 14:36:01,422 DEBUG key: VCuser, value: s25775d

      2016-08-19 14:36:01,422 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 14:36:01,422 INFO Getting list of hosts from vCenter

      2016-08-19 14:36:01,442 INFO Getting all the datacenters from vCenter

      2016-08-19 14:36:01,453 INFO Getting all the compute resources from vCenter

      2016-08-19 14:36:01,468 INFO Getting all the clusters from vCenter

      2016-08-19 14:36:01,483 DEBUG Starting the provisioning thread

      2016-08-19 14:36:01,483 INFO Returning the provisioning status: 1000

      2016-08-19 14:36:01,484 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 14:36:01,484 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 14:36:01,484 DEBUG key: VCuser, value: s25775d

      2016-08-19 14:36:01,484 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 14:36:01,485 INFO Getting list of hosts from vCenter

      2016-08-19 14:36:01,505 INFO Getting all the datacenters from vCenter

      2016-08-19 14:36:01,515 INFO Getting all the compute resources from vCenter

      2016-08-19 14:36:01,530 INFO Getting all the clusters from vCenter

      2016-08-19 14:36:01,743 DEBUG Host list prepared for provisioning: [u'dhvm08001.ncsecu.dev', u'dhvm08002.ncsecu.dev']

      2016-08-19 14:36:01,743 DEBUG Getting the hostname from the hosts lists with index: 0

      2016-08-19 14:36:01,743 DEBUG Returning the hostname : dhvm08001.ncsecu.dev

      2016-08-19 14:36:01,953 INFO Creating 5 linked clones with prefix dvdt

      2016-08-19 14:36:01,953 DEBUG Calling Clone APIs to create new clone: dvdt1

      2016-08-19 14:36:02,092 DEBUG Calling Clone APIs to create new clone: dvdt2

      2016-08-19 14:36:02,234 DEBUG Calling Clone APIs to create new clone: dvdt3

      2016-08-19 14:36:02,441 DEBUG Calling Clone APIs to create new clone: dvdt4

      2016-08-19 14:36:02,565 DEBUG Calling Clone APIs to create new clone: dvdt5

      2016-08-19 14:36:05,183 DEBUG Returning the current status: -1 with message Initial state

      2016-08-19 14:36:28,263 INFO Operation: Provisioning_Clones, Time of Start: Fri Aug 19 14:36:01 2016, Total time taken: 26.52

      2016-08-19 14:36:28,263 DEBUG Completed provisioning. Checking if all the VMs were created succesfully with prefix: dvdt

      2016-08-19 14:36:28,458 DEBUG Powering on VM: dvdt1

      2016-08-19 14:36:28,468 DEBUG Powering on VM: dvdt2

      2016-08-19 14:36:28,479 DEBUG Powering on VM: dvdt3

      2016-08-19 14:36:28,488 DEBUG Powering on VM: dvdt4

      2016-08-19 14:36:28,525 DEBUG Powering on VM: dvdt5

      2016-08-19 14:36:31,425 INFO Returning the provisioning status: 1

      2016-08-19 14:37:27,054 INFO New client registration: 192.168.155.164

      2016-08-19 14:37:27,055 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.164

      2016-08-19 14:37:27,063 INFO New client registration: 192.168.155.167

      2016-08-19 14:37:27,063 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.167

      2016-08-19 14:37:27,076 INFO New client registration: 192.168.155.166

      2016-08-19 14:37:27,076 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.166

      2016-08-19 14:37:27,125 INFO New client registration: 192.168.155.165

      2016-08-19 14:37:27,125 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.165

      2016-08-19 14:37:27,176 INFO New client registration: 192.168.155.169

      2016-08-19 14:37:27,176 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.169

      2016-08-19 14:39:20,105 INFO New client registration: 192.168.155.167

      2016-08-19 14:39:20,106 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.167

      2016-08-19 14:39:20,122 INFO New client registration: 192.168.155.165

      2016-08-19 14:39:20,122 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.165

      2016-08-19 14:39:29,955 INFO New client registration: 192.168.155.166

      2016-08-19 14:39:29,955 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.166

      2016-08-19 14:39:46,437 INFO New client registration: 192.168.155.164

      2016-08-19 14:39:46,437 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.164

      2016-08-19 14:39:59,059 INFO New client registration: 192.168.155.169

      2016-08-19 14:39:59,059 INFO Workload run not started. ignoring the new registration with ip: 192.168.155.169

      2016-08-19 14:50:11,235 DEBUG Getting all the workload profiles from the database.

      2016-08-19 14:50:11,235 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 14:52:01,411 DEBUG Saving the run profile to the database with parameters: {u'name': u'Multi-VM-remote-PCoIP', u'rampuptime': u'70', u'numofvms': u'5', u'mode': u'2', u'adgroups': [{u'protocol': u'2', u'name': u'Test', u'wrk_FK': u'StandardBenchmarkProfile_1i', u'poolname': u'', u'percent': u'100', u'connections_per_client': u'1', u'rdshtype': u'0', u'clientprefix': u'dtst'}], u'desktype': u'0', u'deskprefix': u'dtst'}

      2016-08-19 14:52:01,412 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 14:52:01,413 INFO Running query: INSERT INTO runprofile (name, numofvms, mode, deskprefix, rampuptime, desktype) VALUES ("Multi-VM-remote-PCoIP", 5, 2, "dtst", 70, 0)

      2016-08-19 14:52:01,419 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP"

      2016-08-19 14:52:01,420 INFO Running query: SELECT * from ADgroupprofile where name = "Test" and wrk_FK = "StandardBenchmarkProfile_1i" and percent = 100 and protocol = 2 and clientprefix = "dtst" and connections_per_client = 1 and rdshtype = 0 and poolname = ""

      2016-08-19 14:52:01,421 INFO Running query: INSERT INTO ADgroupprofile (name, wrk_FK, percent, protocol, clientprefix, connections_per_client, rdshtype, poolname) values ("Test", "StandardBenchmarkProfile_1i", 100, 2, "dtst", 1, 0, "")

      2016-08-19 14:52:01,422 INFO Running query: SELECT * from ADgroupprofile where name = "Test" and wrk_FK = "StandardBenchmarkProfile_1i" and percent = 100 and protocol = 2 and clientprefix = "dtst" and connections_per_client = 1 and rdshtype = 0 and poolname = ""

      2016-08-19 14:52:01,423 INFO Running query: INSERT INTO runpivot (grp_FK, run_FK) values (3, 5)

      2016-08-19 14:52:01,434 DEBUG Getting all the run profiles from the database.

      2016-08-19 14:52:01,434 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 14:52:13,705 INFO ============================================================

      2016-08-19 14:52:13,706 INFO Executing the run profile from the database: Multi-VM-remote-PCoIP

      2016-08-19 14:52:13,706 INFO ============================================================

      2016-08-19 14:52:13,706 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP"

      2016-08-19 14:52:13,707 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 5

      2016-08-19 14:52:13,708 INFO Running query: SELECT * from ADgroupprofile where grp_id = 3

      2016-08-19 14:52:13,708 DEBUG Running profile Multi-VM-remote-PCoIP with parameters: {'deskprefix': 'dtst', 'rampuptime': 70L, 'run_id': 5L, 'extrainfo': None, 'numofvms': 5L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}], 'desktype': 0L, 'name': 'Multi-VM-remote-PCoIP'}

      2016-08-19 14:52:13,709 DEBUG Resetting all variables in shared_data.

      2016-08-19 14:52:13,709 DEBUG Resetting all variables in shared_data [Done].

      2016-08-19 14:52:13,709 INFO Setting the checkpoint index of upload/connections: 4

      2016-08-19 14:52:13,709 DEBUG Setting all past tests state to Done

      2016-08-19 14:52:13,709 INFO Running query: UPDATE testinfo SET test_state = "Done"

      2016-08-19 14:52:13,714 DEBUG Setting all hosts to busy

      2016-08-19 14:52:13,714 INFO Running query: UPDATE hostinfo SET state = "Old"

      2016-08-19 14:52:13,722 DEBUG Connecting to the View server agent.

      2016-08-19 14:52:13,723 INFO adding callback function for getting the remote root object for ipaddr: dhcs0702v

      2016-08-19 14:52:13,723 INFO Got View object from the View server.

      2016-08-19 14:52:13,729 WARNING ViewAPI not supported on this View server.

      2016-08-19 14:52:13,730 DEBUG Connecting to the AD agent

      2016-08-19 14:52:13,730 INFO Adding callback function for getting the remote root object for IP address: 127.0.0.1

      2016-08-19 14:52:13,730 INFO Got AD object from the AD server.

      2016-08-19 14:52:13,759 DEBUG Waiting for the current thread to terminate. IF it does not terminate in 10 seconds, the vdiappd service will be restarted.

      2016-08-19 14:52:13,760 DEBUG Stopping the runtest thread.

      2016-08-19 14:52:13,760 DEBUG Starting the runtest thread 2586

      2016-08-19 14:52:13,763 INFO Getting list of hosts from vCenter

      2016-08-19 14:52:13,766 WARNING There was an error connecting to vCenter. Reconnecting.

      2016-08-19 14:52:13,767 DEBUG Connecting to vCenter again with credentials: s25775d ******** 192.168.151.180

      2016-08-19 14:52:13,963 INFO Getting list of hosts from vCenter

      2016-08-19 14:52:13,995 INFO Getting all the datacenters from vCenter

      2016-08-19 14:52:14,006 INFO Getting all the compute resources from vCenter

      2016-08-19 14:52:14,021 INFO Getting all the clusters from vCenter

      2016-08-19 14:52:14,257 INFO Getting all the datacenters from vCenter

      2016-08-19 14:52:14,267 INFO Getting all the compute resources from vCenter

      2016-08-19 14:52:14,281 INFO Getting all the clusters from vCenter

      2016-08-19 14:52:14,295 INFO Getting all the VMs from the vCenter.

      2016-08-19 14:52:14,599 INFO Getting all the VMs from the vCenter[done].

      2016-08-19 14:52:14,599 INFO AD group: {'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}

      2016-08-19 14:52:14,599 INFO Running query: SELECT distinct * from workloadconfig where name = "StandardBenchmarkProfile_1i"

      2016-08-19 14:52:14,600 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 14:52:14,600 INFO Making AD config file config-test.txt for the Test AD group desktops with workprofile: {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 14:52:14,601 INFO Looking for 5 VMs with prefixes dtst in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdt1', 'dvdt2', 'dvdt3', 'dvdt4', 'dvdt5', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'template-win2k12R2-std-vl']

      2016-08-19 14:52:14,601 INFO Matching VM Base_Template_Win7Pro64 with prefix: dtst

      2016-08-19 14:52:14,601 INFO Matching VM GI-Win7x64 with prefix: dtst

      2016-08-19 14:52:14,601 INFO Matching VM GoldenClient with prefix: dtst

      2016-08-19 14:52:14,602 INFO Matching VM GoldenDesktop with prefix: dtst

      2016-08-19 14:52:14,602 INFO Matching VM W7x64-Clean with prefix: dtst

      2016-08-19 14:52:14,602 INFO Matching VM cp-parent-0061be90-6728-4d90-80d2-266320fdf818 with prefix: dtst

      2016-08-19 14:52:14,602 INFO Matching VM cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1 with prefix: dtst

      2016-08-19 14:52:14,602 INFO Matching VM cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43 with prefix: dtst

      2016-08-19 14:52:14,602 INFO Matching VM cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6 with prefix: dtst

      2016-08-19 14:52:14,602 INFO Matching VM dappvolprov02 with prefix: dtst

      2016-08-19 14:52:14,602 INFO Matching VM dcmp0701v with prefix: dtst

      2016-08-19 14:52:14,603 INFO Matching VM dcvp0703v with prefix: dtst

      2016-08-19 14:52:14,603 INFO Matching VM dcvp0704v with prefix: dtst

      2016-08-19 14:52:14,603 INFO Matching VM dhcs0701v with prefix: dtst

      2016-08-19 14:52:14,603 INFO Matching VM dhcs0702v with prefix: dtst

      2016-08-19 14:52:14,603 INFO Matching VM dhdb0341v with prefix: dtst

      2016-08-19 14:52:14,603 INFO Matching VM dhds99001v with prefix: dtst

      2016-08-19 14:52:14,603 INFO Matching VM dhds99002v with prefix: dtst

      2016-08-19 14:52:14,603 INFO Matching VM dhvp0701v with prefix: dtst

      2016-08-19 14:52:14,603 INFO Matching VM dpsc0702v with prefix: dtst

      2016-08-19 14:52:14,604 INFO Matching VM dsrm0401v with prefix: dtst

      2016-08-19 14:52:14,604 INFO Matching VM dsrm0702v with prefix: dtst

      2016-08-19 14:52:14,604 INFO Matching VM dtds0704v with prefix: dtst

      2016-08-19 14:52:14,604 INFO Matching VM dtst0701v with prefix: dtst

      2016-08-19 14:52:14,604 INFO VM dtst0701v matched with prefix: dtst

      2016-08-19 14:52:14,604 INFO Matching VM dtst0702v with prefix: dtst

      2016-08-19 14:52:14,604 INFO VM dtst0702v matched with prefix: dtst

      2016-08-19 14:52:14,604 INFO Matching VM dvav0701v with prefix: dtst

      2016-08-19 14:52:14,604 INFO Matching VM dvav0702v with prefix: dtst

      2016-08-19 14:52:14,605 INFO Matching VM dvcr0702v with prefix: dtst

      2016-08-19 14:52:14,605 INFO Matching VM dvdt01v with prefix: dtst

      2016-08-19 14:52:14,605 INFO Matching VM dvdt02v with prefix: dtst

      2016-08-19 14:52:14,605 INFO Matching VM dvdt03v with prefix: dtst

      2016-08-19 14:52:14,605 INFO Matching VM dvdt1 with prefix: dtst

      2016-08-19 14:52:14,605 INFO Matching VM dvdt2 with prefix: dtst

      2016-08-19 14:52:14,605 INFO Matching VM dvdt3 with prefix: dtst

      2016-08-19 14:52:14,605 INFO Matching VM dvdt4 with prefix: dtst

      2016-08-19 14:52:14,605 INFO Matching VM dvdt5 with prefix: dtst

      2016-08-19 14:52:14,606 INFO Matching VM dvdtjm001v with prefix: dtst

      2016-08-19 14:52:14,606 INFO Matching VM dvdtjm002v with prefix: dtst

      2016-08-19 14:52:14,606 INFO Matching VM dvdtjm003v with prefix: dtst

      2016-08-19 14:52:14,606 INFO Matching VM dvdtjm004v with prefix: dtst

      2016-08-19 14:52:14,606 INFO Matching VM dvdtjm005v with prefix: dtst

      2016-08-19 14:52:14,606 INFO Matching VM dvsm0704v with prefix: dtst

      2016-08-19 14:52:14,606 INFO Matching VM goldImage-xdadm-VDI-Team with prefix: dtst

      2016-08-19 14:52:14,606 INFO Matching VM template-win2k12R2-std-vl with prefix: dtst

      2016-08-19 14:52:14,606 WARNING Not enough VMs found based on the prefixes: dtst

      2016-08-19 14:52:14,607 INFO Desktop VMs found: ['dtst0701v', 'dtst0702v']

      2016-08-19 14:52:14,607 INFO Looking for 5 VMs with prefixes dtst in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdt1', 'dvdt2', 'dvdt3', 'dvdt4', 'dvdt5', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'template-win2k12R2-std-vl']

      2016-08-19 14:52:14,607 INFO Matching VM Base_Template_Win7Pro64 with prefix: dtst

      2016-08-19 14:52:14,607 INFO Matching VM GI-Win7x64 with prefix: dtst

      2016-08-19 14:52:14,607 INFO Matching VM GoldenClient with prefix: dtst

      2016-08-19 14:52:14,607 INFO Matching VM GoldenDesktop with prefix: dtst

      2016-08-19 14:52:14,607 INFO Matching VM W7x64-Clean with prefix: dtst

      2016-08-19 14:52:14,607 INFO Matching VM cp-parent-0061be90-6728-4d90-80d2-266320fdf818 with prefix: dtst

      2016-08-19 14:52:14,607 INFO Matching VM cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1 with prefix: dtst

      2016-08-19 14:52:14,608 INFO Matching VM cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43 with prefix: dtst

      2016-08-19 14:52:14,608 INFO Matching VM cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6 with prefix: dtst

      2016-08-19 14:52:14,608 INFO Matching VM dappvolprov02 with prefix: dtst

      2016-08-19 14:52:14,608 INFO Matching VM dcmp0701v with prefix: dtst

      2016-08-19 14:52:14,608 INFO Matching VM dcvp0703v with prefix: dtst

      2016-08-19 14:52:14,608 INFO Matching VM dcvp0704v with prefix: dtst

      2016-08-19 14:52:14,608 INFO Matching VM dhcs0701v with prefix: dtst

      2016-08-19 14:52:14,608 INFO Matching VM dhcs0702v with prefix: dtst

      2016-08-19 14:52:14,608 INFO Matching VM dhdb0341v with prefix: dtst

      2016-08-19 14:52:14,609 INFO Matching VM dhds99001v with prefix: dtst

      2016-08-19 14:52:14,609 INFO Matching VM dhds99002v with prefix: dtst

      2016-08-19 14:52:14,609 INFO Matching VM dhvp0701v with prefix: dtst

      2016-08-19 14:52:14,609 INFO Matching VM dpsc0702v with prefix: dtst

      2016-08-19 14:52:14,609 INFO Matching VM dsrm0401v with prefix: dtst

      2016-08-19 14:52:14,609 INFO Matching VM dsrm0702v with prefix: dtst

      2016-08-19 14:52:14,609 INFO Matching VM dtds0704v with prefix: dtst

      2016-08-19 14:52:14,609 INFO Matching VM dvav0701v with prefix: dtst

      2016-08-19 14:52:14,609 INFO Matching VM dvav0702v with prefix: dtst

      2016-08-19 14:52:14,610 INFO Matching VM dvcr0702v with prefix: dtst

      2016-08-19 14:52:14,610 INFO Matching VM dvdt01v with prefix: dtst

      2016-08-19 14:52:14,610 INFO Matching VM dvdt02v with prefix: dtst

      2016-08-19 14:52:14,610 INFO Matching VM dvdt03v with prefix: dtst

      2016-08-19 14:52:14,610 INFO Matching VM dvdt1 with prefix: dtst

      2016-08-19 14:52:14,610 INFO Matching VM dvdt2 with prefix: dtst

      2016-08-19 14:52:14,610 INFO Matching VM dvdt3 with prefix: dtst

      2016-08-19 14:52:14,610 INFO Matching VM dvdt4 with prefix: dtst

      2016-08-19 14:52:14,610 INFO Matching VM dvdt5 with prefix: dtst

      2016-08-19 14:52:14,611 INFO Matching VM dvdtjm001v with prefix: dtst

      2016-08-19 14:52:14,611 INFO Matching VM dvdtjm002v with prefix: dtst

      2016-08-19 14:52:14,611 INFO Matching VM dvdtjm003v with prefix: dtst

      2016-08-19 14:52:14,611 INFO Matching VM dvdtjm004v with prefix: dtst

      2016-08-19 14:52:14,611 INFO Matching VM dvdtjm005v with prefix: dtst

      2016-08-19 14:52:14,611 INFO Matching VM dvsm0704v with prefix: dtst

      2016-08-19 14:52:14,611 INFO Matching VM goldImage-xdadm-VDI-Team with prefix: dtst

      2016-08-19 14:52:14,611 INFO Matching VM template-win2k12R2-std-vl with prefix: dtst

      2016-08-19 14:52:14,611 WARNING Not enough VMs found based on the prefixes: dtst

      2016-08-19 14:52:14,612 INFO Client VMs found: []

      2016-08-19 14:52:14,612 WARNING There were not enough desktops found: (expected: 5, found: 2)

      2016-08-19 14:52:14,612 WARNING There were not enough clients found: (expected: 5, found: 0)

      2016-08-19 14:52:14,612 INFO Loading AD Info to the data structures is done with adinfo: [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'deskvms': ['dtst0701v', 'dtst0702v'], 'rdshtype': 0L, 'clientvms': [], 'workprofile': {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}, 'clientprefix': 'dtst'}]

      2016-08-19 14:52:14,615 DEBUG Getting the configuration option monitor_control.pseudo_perfctr in VM dtst0701v

      2016-08-19 14:52:14,638 INFO The value of performance counter for VM dtst0701v: None

      2016-08-19 14:52:14,638 INFO ============================================================

      2016-08-19 14:52:14,639 INFO Error: The pseudo perf counter is not enabled in the desktop. Please run checkconfig.pyc <desk> to fix this issue.

      2016-08-19 14:52:14,639 INFO ============================================================

      2016-08-19 14:52:18,713 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 0 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 14:54:13,502 DEBUG Getting all the workload profiles from the database.

      2016-08-19 14:54:13,502 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 14:54:13,505 DEBUG Loading the run profile from the database: Multi-VM-remote-PCoIP

      2016-08-19 14:54:13,505 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP"

      2016-08-19 14:54:13,506 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 5

      2016-08-19 14:54:13,506 INFO Running query: SELECT * from ADgroupprofile where grp_id = 3

      2016-08-19 14:54:13,507 DEBUG Returning profile: {'deskprefix': 'dtst', 'rampuptime': 70L, 'run_id': 5L, 'extrainfo': None, 'numofvms': 5L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}], 'desktype': 0L, 'name': 'Multi-VM-remote-PCoIP'}

      2016-08-19 14:54:53,564 DEBUG Saving the run profile to the database with parameters: {u'name': u'Multi-VM-remote-PCoIP-2', u'rampuptime': u'64', u'numofvms': u'2', u'mode': u'2', u'adgroups': [{u'protocol': u'2', u'name': u'Test', u'wrk_FK': u'StandardBenchmarkProfile_1i', u'poolname': u'', u'percent': u'100', u'connections_per_client': u'1', u'rdshtype': u'0', u'clientprefix': u'dtst'}], u'desktype': u'0', u'deskprefix': u'dtst'}

      2016-08-19 14:54:53,565 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 14:54:53,566 INFO Running query: INSERT INTO runprofile (name, numofvms, mode, deskprefix, rampuptime, desktype) VALUES ("Multi-VM-remote-PCoIP-2", 2, 2, "dtst", 64, 0)

      2016-08-19 14:54:53,570 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 14:54:53,571 INFO Running query: SELECT * from ADgroupprofile where name = "Test" and wrk_FK = "StandardBenchmarkProfile_1i" and percent = 100 and protocol = 2 and clientprefix = "dtst" and connections_per_client = 1 and rdshtype = 0 and poolname = ""

      2016-08-19 14:54:53,571 INFO Running query: INSERT INTO runpivot (grp_FK, run_FK) values (3, 6)

      2016-08-19 14:54:53,582 DEBUG Getting all the run profiles from the database.

      2016-08-19 14:54:53,583 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 14:55:00,387 INFO ============================================================

      2016-08-19 14:55:00,388 INFO Executing the run profile from the database: Multi-VM-remote-PCoIP-2

      2016-08-19 14:55:00,388 INFO ============================================================

      2016-08-19 14:55:00,388 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 14:55:00,390 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 6

      2016-08-19 14:55:00,390 INFO Running query: SELECT * from ADgroupprofile where grp_id = 3

      2016-08-19 14:55:00,391 DEBUG Running profile Multi-VM-remote-PCoIP-2 with parameters: {'deskprefix': 'dtst', 'rampuptime': 64L, 'run_id': 6L, 'extrainfo': None, 'numofvms': 2L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}], 'desktype': 0L, 'name': 'Multi-VM-remote-PCoIP-2'}

      2016-08-19 14:55:00,391 DEBUG Resetting all variables in shared_data.

      2016-08-19 14:55:00,391 DEBUG Resetting all variables in shared_data [Done].

      2016-08-19 14:55:00,392 INFO Setting the checkpoint index of upload/connections: 5

      2016-08-19 14:55:00,392 DEBUG Setting all past tests state to Done

      2016-08-19 14:55:00,392 INFO Running query: UPDATE testinfo SET test_state = "Done"

      2016-08-19 14:55:00,397 DEBUG Setting all hosts to busy

      2016-08-19 14:55:00,397 INFO Running query: UPDATE hostinfo SET state = "Old"

      2016-08-19 14:55:00,404 DEBUG Connecting to the View server agent.

      2016-08-19 14:55:00,405 INFO adding callback function for getting the remote root object for ipaddr: dhcs0702v

      2016-08-19 14:55:00,405 INFO Got View object from the View server.

      2016-08-19 14:55:00,411 WARNING ViewAPI not supported on this View server.

      2016-08-19 14:55:00,411 DEBUG Connecting to the AD agent

      2016-08-19 14:55:00,412 INFO Adding callback function for getting the remote root object for IP address: 127.0.0.1

      2016-08-19 14:55:00,412 INFO Got AD object from the AD server.

      2016-08-19 14:55:00,444 DEBUG Waiting for the current thread to terminate. IF it does not terminate in 10 seconds, the vdiappd service will be restarted.

      2016-08-19 14:55:00,445 DEBUG Stopping the runtest thread.

      2016-08-19 14:55:00,445 DEBUG Starting the runtest thread 2586

      2016-08-19 14:55:00,447 INFO Getting list of hosts from vCenter

      2016-08-19 14:55:00,474 INFO Getting all the datacenters from vCenter

      2016-08-19 14:55:00,484 INFO Getting all the compute resources from vCenter

      2016-08-19 14:55:00,500 INFO Getting all the clusters from vCenter

      2016-08-19 14:55:00,517 INFO Getting all the VMs from the vCenter.

      2016-08-19 14:55:00,717 INFO Getting all the VMs from the vCenter[done].

      2016-08-19 14:55:00,718 INFO AD group: {'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}

      2016-08-19 14:55:00,718 INFO Running query: SELECT distinct * from workloadconfig where name = "StandardBenchmarkProfile_1i"

      2016-08-19 14:55:00,719 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 14:55:00,719 INFO Making AD config file config-test.txt for the Test AD group desktops with workprofile: {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 14:55:00,720 INFO Looking for 2 VMs with prefixes dtst in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'cp-template-f0efea15-1f35-4270-88b5-569e46f52566', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdt1', 'dvdt2', 'dvdt3', 'dvdt4', 'dvdt5', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'template-win2k12R2-std-vl']

      2016-08-19 14:55:00,720 INFO Matching VM Base_Template_Win7Pro64 with prefix: dtst

      2016-08-19 14:55:00,720 INFO Matching VM GI-Win7x64 with prefix: dtst

      2016-08-19 14:55:00,720 INFO Matching VM GoldenClient with prefix: dtst

      2016-08-19 14:55:00,720 INFO Matching VM GoldenDesktop with prefix: dtst

      2016-08-19 14:55:00,720 INFO Matching VM W7x64-Clean with prefix: dtst

      2016-08-19 14:55:00,720 INFO Matching VM cp-parent-0061be90-6728-4d90-80d2-266320fdf818 with prefix: dtst

      2016-08-19 14:55:00,721 INFO Matching VM cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1 with prefix: dtst

      2016-08-19 14:55:00,721 INFO Matching VM cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43 with prefix: dtst

      2016-08-19 14:55:00,721 INFO Matching VM cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6 with prefix: dtst

      2016-08-19 14:55:00,721 INFO Matching VM cp-template-f0efea15-1f35-4270-88b5-569e46f52566 with prefix: dtst

      2016-08-19 14:55:00,721 INFO Matching VM dappvolprov02 with prefix: dtst

      2016-08-19 14:55:00,721 INFO Matching VM dcmp0701v with prefix: dtst

      2016-08-19 14:55:00,721 INFO Matching VM dcvp0703v with prefix: dtst

      2016-08-19 14:55:00,721 INFO Matching VM dcvp0704v with prefix: dtst

      2016-08-19 14:55:00,721 INFO Matching VM dhcs0701v with prefix: dtst

      2016-08-19 14:55:00,722 INFO Matching VM dhcs0702v with prefix: dtst

      2016-08-19 14:55:00,722 INFO Matching VM dhdb0341v with prefix: dtst

      2016-08-19 14:55:00,722 INFO Matching VM dhds99001v with prefix: dtst

      2016-08-19 14:55:00,722 INFO Matching VM dhds99002v with prefix: dtst

      2016-08-19 14:55:00,722 INFO Matching VM dhvp0701v with prefix: dtst

      2016-08-19 14:55:00,722 INFO Matching VM dpsc0702v with prefix: dtst

      2016-08-19 14:55:00,722 INFO Matching VM dsrm0401v with prefix: dtst

      2016-08-19 14:55:00,723 INFO Matching VM dsrm0702v with prefix: dtst

      2016-08-19 14:55:00,723 INFO Matching VM dtds0704v with prefix: dtst

      2016-08-19 14:55:00,723 INFO Matching VM dtst0701v with prefix: dtst

      2016-08-19 14:55:00,723 INFO VM dtst0701v matched with prefix: dtst

      2016-08-19 14:55:00,723 INFO Matching VM dtst0702v with prefix: dtst

      2016-08-19 14:55:00,723 INFO VM dtst0702v matched with prefix: dtst

      2016-08-19 14:55:00,723 INFO Desktop VMs found: ['dtst0701v', 'dtst0702v']

      2016-08-19 14:55:00,724 INFO Looking for 2 VMs with prefixes dtst in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'cp-template-f0efea15-1f35-4270-88b5-569e46f52566', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdt1', 'dvdt2', 'dvdt3', 'dvdt4', 'dvdt5', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'template-win2k12R2-std-vl']

      2016-08-19 14:55:00,724 INFO Matching VM Base_Template_Win7Pro64 with prefix: dtst

      2016-08-19 14:55:00,724 INFO Matching VM GI-Win7x64 with prefix: dtst

      2016-08-19 14:55:00,724 INFO Matching VM GoldenClient with prefix: dtst

      2016-08-19 14:55:00,724 INFO Matching VM GoldenDesktop with prefix: dtst

      2016-08-19 14:55:00,724 INFO Matching VM W7x64-Clean with prefix: dtst

      2016-08-19 14:55:00,724 INFO Matching VM cp-parent-0061be90-6728-4d90-80d2-266320fdf818 with prefix: dtst

      2016-08-19 14:55:00,724 INFO Matching VM cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1 with prefix: dtst

      2016-08-19 14:55:00,725 INFO Matching VM cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43 with prefix: dtst

      2016-08-19 14:55:00,725 INFO Matching VM cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6 with prefix: dtst

      2016-08-19 14:55:00,725 INFO Matching VM cp-template-f0efea15-1f35-4270-88b5-569e46f52566 with prefix: dtst

      2016-08-19 14:55:00,725 INFO Matching VM dappvolprov02 with prefix: dtst

      2016-08-19 14:55:00,725 INFO Matching VM dcmp0701v with prefix: dtst

      2016-08-19 14:55:00,725 INFO Matching VM dcvp0703v with prefix: dtst

      2016-08-19 14:55:00,725 INFO Matching VM dcvp0704v with prefix: dtst

      2016-08-19 14:55:00,725 INFO Matching VM dhcs0701v with prefix: dtst

      2016-08-19 14:55:00,726 INFO Matching VM dhcs0702v with prefix: dtst

      2016-08-19 14:55:00,726 INFO Matching VM dhdb0341v with prefix: dtst

      2016-08-19 14:55:00,726 INFO Matching VM dhds99001v with prefix: dtst

      2016-08-19 14:55:00,726 INFO Matching VM dhds99002v with prefix: dtst

      2016-08-19 14:55:00,726 INFO Matching VM dhvp0701v with prefix: dtst

      2016-08-19 14:55:00,726 INFO Matching VM dpsc0702v with prefix: dtst

      2016-08-19 14:55:00,726 INFO Matching VM dsrm0401v with prefix: dtst

      2016-08-19 14:55:00,726 INFO Matching VM dsrm0702v with prefix: dtst

      2016-08-19 14:55:00,726 INFO Matching VM dtds0704v with prefix: dtst

      2016-08-19 14:55:00,727 INFO Matching VM dvav0701v with prefix: dtst

      2016-08-19 14:55:00,727 INFO Matching VM dvav0702v with prefix: dtst

      2016-08-19 14:55:00,727 INFO Matching VM dvcr0702v with prefix: dtst

      2016-08-19 14:55:00,727 INFO Matching VM dvdt01v with prefix: dtst

      2016-08-19 14:55:00,727 INFO Matching VM dvdt02v with prefix: dtst

      2016-08-19 14:55:00,727 INFO Matching VM dvdt03v with prefix: dtst

      2016-08-19 14:55:00,727 INFO Matching VM dvdt1 with prefix: dtst

      2016-08-19 14:55:00,728 INFO Matching VM dvdt2 with prefix: dtst

      2016-08-19 14:55:00,728 INFO Matching VM dvdt3 with prefix: dtst

      2016-08-19 14:55:00,728 INFO Matching VM dvdt4 with prefix: dtst

      2016-08-19 14:55:00,728 INFO Matching VM dvdt5 with prefix: dtst

      2016-08-19 14:55:00,728 INFO Matching VM dvdtjm001v with prefix: dtst

      2016-08-19 14:55:00,728 INFO Matching VM dvdtjm002v with prefix: dtst

      2016-08-19 14:55:00,728 INFO Matching VM dvdtjm003v with prefix: dtst

      2016-08-19 14:55:00,728 INFO Matching VM dvdtjm004v with prefix: dtst

      2016-08-19 14:55:00,729 INFO Matching VM dvdtjm005v with prefix: dtst

      2016-08-19 14:55:00,729 INFO Matching VM dvsm0704v with prefix: dtst

      2016-08-19 14:55:00,729 INFO Matching VM goldImage-xdadm-VDI-Team with prefix: dtst

      2016-08-19 14:55:00,729 INFO Matching VM template-win2k12R2-std-vl with prefix: dtst

      2016-08-19 14:55:00,729 WARNING Not enough VMs found based on the prefixes: dtst

      2016-08-19 14:55:00,729 INFO Client VMs found: []

      2016-08-19 14:55:00,729 WARNING There were not enough clients found: (expected: 2, found: 0)

      2016-08-19 14:55:00,730 INFO Loading AD Info to the data structures is done with adinfo: [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'deskvms': ['dtst0701v', 'dtst0702v'], 'rdshtype': 0L, 'clientvms': [], 'workprofile': {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}, 'clientprefix': 'dtst'}]

      2016-08-19 14:55:00,733 DEBUG Getting the configuration option monitor_control.pseudo_perfctr in VM dtst0701v

      2016-08-19 14:55:00,755 INFO The value of performance counter for VM dtst0701v: None

      2016-08-19 14:55:00,755 INFO ============================================================

      2016-08-19 14:55:00,756 INFO Error: The pseudo perf counter is not enabled in the desktop. Please run checkconfig.pyc <desk> to fix this issue.

      2016-08-19 14:55:00,756 INFO ============================================================

      2016-08-19 15:16:00,669 INFO ============================================================

      2016-08-19 15:16:00,669 INFO Executing the run profile from the database: Multi-VM-remote-PCoIP-2

      2016-08-19 15:16:00,669 INFO ============================================================

      2016-08-19 15:16:00,670 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 15:16:00,671 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 6

      2016-08-19 15:16:00,671 INFO Running query: SELECT * from ADgroupprofile where grp_id = 3

      2016-08-19 15:16:00,672 DEBUG Running profile Multi-VM-remote-PCoIP-2 with parameters: {'deskprefix': 'dtst', 'rampuptime': 64L, 'run_id': 6L, 'extrainfo': None, 'numofvms': 2L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}], 'desktype': 0L, 'name': 'Multi-VM-remote-PCoIP-2'}

      2016-08-19 15:16:00,672 DEBUG Resetting all variables in shared_data.

      2016-08-19 15:16:00,672 DEBUG Resetting all variables in shared_data [Done].

      2016-08-19 15:16:00,673 INFO Setting the checkpoint index of upload/connections: 5

      2016-08-19 15:16:00,673 DEBUG Setting all past tests state to Done

      2016-08-19 15:16:00,673 INFO Running query: UPDATE testinfo SET test_state = "Done"

      2016-08-19 15:16:00,679 DEBUG Setting all hosts to busy

      2016-08-19 15:16:00,680 INFO Running query: UPDATE hostinfo SET state = "Old"

      2016-08-19 15:16:00,687 DEBUG Connecting to the View server agent.

      2016-08-19 15:16:00,688 INFO adding callback function for getting the remote root object for ipaddr: dhcs0702v

      2016-08-19 15:16:00,688 INFO Got View object from the View server.

      2016-08-19 15:16:00,697 WARNING ViewAPI not supported on this View server.

      2016-08-19 15:16:00,697 DEBUG Connecting to the AD agent

      2016-08-19 15:16:00,698 INFO Adding callback function for getting the remote root object for IP address: 127.0.0.1

      2016-08-19 15:16:00,698 INFO Got AD object from the AD server.

      2016-08-19 15:16:00,726 DEBUG Waiting for the current thread to terminate. IF it does not terminate in 10 seconds, the vdiappd service will be restarted.

      2016-08-19 15:16:00,727 DEBUG Stopping the runtest thread.

      2016-08-19 15:16:00,728 DEBUG Starting the runtest thread 2586

      2016-08-19 15:16:00,730 INFO Getting list of hosts from vCenter

      2016-08-19 15:16:00,732 WARNING There was an error connecting to vCenter. Reconnecting.

      2016-08-19 15:16:00,733 DEBUG Connecting to vCenter again with credentials: s25775d ******** 192.168.151.180

      2016-08-19 15:16:01,055 INFO Getting list of hosts from vCenter

      2016-08-19 15:16:01,082 INFO Getting all the datacenters from vCenter

      2016-08-19 15:16:01,092 INFO Getting all the compute resources from vCenter

      2016-08-19 15:16:01,106 INFO Getting all the clusters from vCenter

      2016-08-19 15:16:01,347 INFO Getting all the datacenters from vCenter

      2016-08-19 15:16:01,357 INFO Getting all the compute resources from vCenter

      2016-08-19 15:16:01,373 INFO Getting all the clusters from vCenter

      2016-08-19 15:16:01,388 INFO Getting all the VMs from the vCenter.

      2016-08-19 15:16:01,584 INFO Getting all the VMs from the vCenter[done].

      2016-08-19 15:16:01,585 INFO AD group: {'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}

      2016-08-19 15:16:01,585 INFO Running query: SELECT distinct * from workloadconfig where name = "StandardBenchmarkProfile_1i"

      2016-08-19 15:16:01,586 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 15:16:01,586 INFO Making AD config file config-test.txt for the Test AD group desktops with workprofile: {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 15:16:01,587 INFO Looking for 2 VMs with prefixes dtst in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdt1', 'dvdt2', 'dvdt3', 'dvdt4', 'dvdt5', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'replica-48855172-4d64-4f48-9e95-cb4428a8e522', 'template-win2k12R2-std-vl']

      2016-08-19 15:16:01,587 INFO Matching VM Base_Template_Win7Pro64 with prefix: dtst

      2016-08-19 15:16:01,587 INFO Matching VM GI-Win7x64 with prefix: dtst

      2016-08-19 15:16:01,587 INFO Matching VM GoldenClient with prefix: dtst

      2016-08-19 15:16:01,587 INFO Matching VM GoldenDesktop with prefix: dtst

      2016-08-19 15:16:01,587 INFO Matching VM W7x64-Clean with prefix: dtst

      2016-08-19 15:16:01,587 INFO Matching VM cp-parent-0061be90-6728-4d90-80d2-266320fdf818 with prefix: dtst

      2016-08-19 15:16:01,587 INFO Matching VM cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1 with prefix: dtst

      2016-08-19 15:16:01,588 INFO Matching VM cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43 with prefix: dtst

      2016-08-19 15:16:01,588 INFO Matching VM cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6 with prefix: dtst

      2016-08-19 15:16:01,588 INFO Matching VM dappvolprov02 with prefix: dtst

      2016-08-19 15:16:01,588 INFO Matching VM dcmp0701v with prefix: dtst

      2016-08-19 15:16:01,588 INFO Matching VM dcvp0703v with prefix: dtst

      2016-08-19 15:16:01,588 INFO Matching VM dcvp0704v with prefix: dtst

      2016-08-19 15:16:01,588 INFO Matching VM dhcs0701v with prefix: dtst

      2016-08-19 15:16:01,588 INFO Matching VM dhcs0702v with prefix: dtst

      2016-08-19 15:16:01,589 INFO Matching VM dhdb0341v with prefix: dtst

      2016-08-19 15:16:01,589 INFO Matching VM dhds99001v with prefix: dtst

      2016-08-19 15:16:01,589 INFO Matching VM dhds99002v with prefix: dtst

      2016-08-19 15:16:01,589 INFO Matching VM dhvp0701v with prefix: dtst

      2016-08-19 15:16:01,589 INFO Matching VM dpsc0702v with prefix: dtst

      2016-08-19 15:16:01,589 INFO Matching VM dsrm0401v with prefix: dtst

      2016-08-19 15:16:01,589 INFO Matching VM dsrm0702v with prefix: dtst

      2016-08-19 15:16:01,589 INFO Matching VM dtds0704v with prefix: dtst

      2016-08-19 15:16:01,589 INFO Matching VM dtst0701v with prefix: dtst

      2016-08-19 15:16:01,590 INFO VM dtst0701v matched with prefix: dtst

      2016-08-19 15:16:01,590 INFO Matching VM dtst0702v with prefix: dtst

      2016-08-19 15:16:01,590 INFO VM dtst0702v matched with prefix: dtst

      2016-08-19 15:16:01,590 INFO Desktop VMs found: ['dtst0701v', 'dtst0702v']

      2016-08-19 15:16:01,590 INFO Looking for 2 VMs with prefixes dtst in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdt1', 'dvdt2', 'dvdt3', 'dvdt4', 'dvdt5', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'replica-48855172-4d64-4f48-9e95-cb4428a8e522', 'template-win2k12R2-std-vl']

      2016-08-19 15:16:01,590 INFO Matching VM Base_Template_Win7Pro64 with prefix: dtst

      2016-08-19 15:16:01,590 INFO Matching VM GI-Win7x64 with prefix: dtst

      2016-08-19 15:16:01,590 INFO Matching VM GoldenClient with prefix: dtst

      2016-08-19 15:16:01,591 INFO Matching VM GoldenDesktop with prefix: dtst

      2016-08-19 15:16:01,591 INFO Matching VM W7x64-Clean with prefix: dtst

      2016-08-19 15:16:01,591 INFO Matching VM cp-parent-0061be90-6728-4d90-80d2-266320fdf818 with prefix: dtst

      2016-08-19 15:16:01,591 INFO Matching VM cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1 with prefix: dtst

      2016-08-19 15:16:01,591 INFO Matching VM cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43 with prefix: dtst

      2016-08-19 15:16:01,591 INFO Matching VM cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6 with prefix: dtst

      2016-08-19 15:16:01,591 INFO Matching VM dappvolprov02 with prefix: dtst

      2016-08-19 15:16:01,591 INFO Matching VM dcmp0701v with prefix: dtst

      2016-08-19 15:16:01,591 INFO Matching VM dcvp0703v with prefix: dtst

      2016-08-19 15:16:01,592 INFO Matching VM dcvp0704v with prefix: dtst

      2016-08-19 15:16:01,592 INFO Matching VM dhcs0701v with prefix: dtst

      2016-08-19 15:16:01,592 INFO Matching VM dhcs0702v with prefix: dtst

      2016-08-19 15:16:01,592 INFO Matching VM dhdb0341v with prefix: dtst

      2016-08-19 15:16:01,592 INFO Matching VM dhds99001v with prefix: dtst

      2016-08-19 15:16:01,592 INFO Matching VM dhds99002v with prefix: dtst

      2016-08-19 15:16:01,592 INFO Matching VM dhvp0701v with prefix: dtst

      2016-08-19 15:16:01,592 INFO Matching VM dpsc0702v with prefix: dtst

      2016-08-19 15:16:01,592 INFO Matching VM dsrm0401v with prefix: dtst

      2016-08-19 15:16:01,593 INFO Matching VM dsrm0702v with prefix: dtst

      2016-08-19 15:16:01,593 INFO Matching VM dtds0704v with prefix: dtst

      2016-08-19 15:16:01,593 INFO Matching VM dvav0701v with prefix: dtst

      2016-08-19 15:16:01,593 INFO Matching VM dvav0702v with prefix: dtst

      2016-08-19 15:16:01,593 INFO Matching VM dvcr0702v with prefix: dtst

      2016-08-19 15:16:01,593 INFO Matching VM dvdt01v with prefix: dtst

      2016-08-19 15:16:01,593 INFO Matching VM dvdt02v with prefix: dtst

      2016-08-19 15:16:01,593 INFO Matching VM dvdt03v with prefix: dtst

      2016-08-19 15:16:01,594 INFO Matching VM dvdt1 with prefix: dtst

      2016-08-19 15:16:01,594 INFO Matching VM dvdt2 with prefix: dtst

      2016-08-19 15:16:01,594 INFO Matching VM dvdt3 with prefix: dtst

      2016-08-19 15:16:01,594 INFO Matching VM dvdt4 with prefix: dtst

      2016-08-19 15:16:01,594 INFO Matching VM dvdt5 with prefix: dtst

      2016-08-19 15:16:01,594 INFO Matching VM dvdtjm001v with prefix: dtst

      2016-08-19 15:16:01,594 INFO Matching VM dvdtjm002v with prefix: dtst

      2016-08-19 15:16:01,594 INFO Matching VM dvdtjm003v with prefix: dtst

      2016-08-19 15:16:01,594 INFO Matching VM dvdtjm004v with prefix: dtst

      2016-08-19 15:16:01,595 INFO Matching VM dvdtjm005v with prefix: dtst

      2016-08-19 15:16:01,595 INFO Matching VM dvsm0704v with prefix: dtst

      2016-08-19 15:16:01,595 INFO Matching VM goldImage-xdadm-VDI-Team with prefix: dtst

      2016-08-19 15:16:01,595 INFO Matching VM replica-48855172-4d64-4f48-9e95-cb4428a8e522 with prefix: dtst

      2016-08-19 15:16:01,595 INFO Matching VM template-win2k12R2-std-vl with prefix: dtst

      2016-08-19 15:16:01,595 WARNING Not enough VMs found based on the prefixes: dtst

      2016-08-19 15:16:01,595 INFO Client VMs found: []

      2016-08-19 15:16:01,595 WARNING There were not enough clients found: (expected: 2, found: 0)

      2016-08-19 15:16:01,596 INFO Loading AD Info to the data structures is done with adinfo: [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'deskvms': ['dtst0701v', 'dtst0702v'], 'rdshtype': 0L, 'clientvms': [], 'workprofile': {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}, 'clientprefix': 'dtst'}]

      2016-08-19 15:16:01,598 DEBUG Getting the configuration option monitor_control.pseudo_perfctr in VM dtst0701v

      2016-08-19 15:16:01,621 INFO The value of performance counter for VM dtst0701v: None

      2016-08-19 15:16:01,621 INFO ============================================================

      2016-08-19 15:16:01,621 INFO Error: The pseudo perf counter is not enabled in the desktop. Please run checkconfig.pyc <desk> to fix this issue.

      2016-08-19 15:16:01,621 INFO ============================================================

      2016-08-19 15:16:13,870 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:16:13,870 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:16:13,872 DEBUG Loading the run profile from the database: Multi-VM-remote-PCoIP-2

      2016-08-19 15:16:13,872 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 15:16:13,873 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 6

      2016-08-19 15:16:13,873 INFO Running query: SELECT * from ADgroupprofile where grp_id = 3

      2016-08-19 15:16:13,874 DEBUG Returning profile: {'deskprefix': 'dtst', 'rampuptime': 64L, 'run_id': 6L, 'extrainfo': None, 'numofvms': 2L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}], 'desktype': 0L, 'name': 'Multi-VM-remote-PCoIP-2'}

      2016-08-19 15:17:45,756 DEBUG Saving the run profile to the database with parameters: {u'name': u'Multi-VM-remote-PCoIP-3', u'rampuptime': u'70', u'numofvms': u'5', u'mode': u'2', u'adgroups': [{u'protocol': u'2', u'name': u'Test', u'wrk_FK': u'StandardBenchmarkProfile_1i', u'poolname': u'', u'percent': u'100', u'connections_per_client': u'1', u'rdshtype': u'0', u'clientprefix': u'dtst'}], u'desktype': u'0', u'deskprefix': u'dtst'}

      2016-08-19 15:17:45,756 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 15:17:45,757 INFO Running query: INSERT INTO runprofile (name, numofvms, mode, deskprefix, rampuptime, desktype) VALUES ("Multi-VM-remote-PCoIP-3", 5, 2, "dtst", 70, 0)

      2016-08-19 15:17:45,762 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:17:45,763 INFO Running query: SELECT * from ADgroupprofile where name = "Test" and wrk_FK = "StandardBenchmarkProfile_1i" and percent = 100 and protocol = 2 and clientprefix = "dtst" and connections_per_client = 1 and rdshtype = 0 and poolname = ""

      2016-08-19 15:17:45,764 INFO Running query: INSERT INTO runpivot (grp_FK, run_FK) values (3, 7)

      2016-08-19 15:17:45,775 DEBUG Getting all the run profiles from the database.

      2016-08-19 15:17:45,775 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 15:17:55,352 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:17:55,352 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:17:55,354 DEBUG Loading the run profile from the database: Multi-VM-remote-PCoIP-3

      2016-08-19 15:17:55,354 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:17:55,355 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 7

      2016-08-19 15:17:55,356 INFO Running query: SELECT * from ADgroupprofile where grp_id = 3

      2016-08-19 15:17:55,356 DEBUG Returning profile: {'deskprefix': 'dtst', 'rampuptime': 70L, 'run_id': 7L, 'extrainfo': None, 'numofvms': 5L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}], 'desktype': 0L, 'name': 'Multi-VM-remote-PCoIP-3'}

      2016-08-19 15:18:17,359 INFO ============================================================

      2016-08-19 15:18:17,359 INFO Executing the run profile from the database: Multi-VM-remote-PCoIP-3

      2016-08-19 15:18:17,359 INFO ============================================================

      2016-08-19 15:18:17,359 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:18:17,360 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 7

      2016-08-19 15:18:17,361 INFO Running query: SELECT * from ADgroupprofile where grp_id = 3

      2016-08-19 15:18:17,362 DEBUG Running profile Multi-VM-remote-PCoIP-3 with parameters: {'deskprefix': 'dtst', 'rampuptime': 70L, 'run_id': 7L, 'extrainfo': None, 'numofvms': 5L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}], 'desktype': 0L, 'name': 'Multi-VM-remote-PCoIP-3'}

      2016-08-19 15:18:17,362 DEBUG Resetting all variables in shared_data.

      2016-08-19 15:18:17,362 DEBUG Resetting all variables in shared_data [Done].

      2016-08-19 15:18:17,362 INFO Setting the checkpoint index of upload/connections: 4

      2016-08-19 15:18:17,362 DEBUG Setting all past tests state to Done

      2016-08-19 15:18:17,362 INFO Running query: UPDATE testinfo SET test_state = "Done"

      2016-08-19 15:18:17,368 DEBUG Setting all hosts to busy

      2016-08-19 15:18:17,368 INFO Running query: UPDATE hostinfo SET state = "Old"

      2016-08-19 15:18:17,375 DEBUG Connecting to the View server agent.

      2016-08-19 15:18:17,376 INFO adding callback function for getting the remote root object for ipaddr: dhcs0702v

      2016-08-19 15:18:17,376 INFO Got View object from the View server.

      2016-08-19 15:18:17,381 WARNING ViewAPI not supported on this View server.

      2016-08-19 15:18:17,382 DEBUG Connecting to the AD agent

      2016-08-19 15:18:17,382 INFO Adding callback function for getting the remote root object for IP address: 127.0.0.1

      2016-08-19 15:18:17,382 INFO Got AD object from the AD server.

      2016-08-19 15:18:17,412 DEBUG Waiting for the current thread to terminate. IF it does not terminate in 10 seconds, the vdiappd service will be restarted.

      2016-08-19 15:18:17,412 DEBUG Stopping the runtest thread.

      2016-08-19 15:18:17,413 DEBUG Starting the runtest thread 2586

      2016-08-19 15:18:17,414 INFO Getting list of hosts from vCenter

      2016-08-19 15:18:17,451 INFO Getting all the datacenters from vCenter

      2016-08-19 15:18:17,466 INFO Getting all the compute resources from vCenter

      2016-08-19 15:18:17,481 INFO Getting all the clusters from vCenter

      2016-08-19 15:18:17,496 INFO Getting all the VMs from the vCenter.

      2016-08-19 15:18:17,697 INFO Getting all the VMs from the vCenter[done].

      2016-08-19 15:18:17,697 INFO AD group: {'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dtst'}

      2016-08-19 15:18:17,697 INFO Running query: SELECT distinct * from workloadconfig where name = "StandardBenchmarkProfile_1i"

      2016-08-19 15:18:17,698 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 15:18:17,698 INFO Making AD config file config-test.txt for the Test AD group desktops with workprofile: {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 15:18:17,699 INFO Looking for 5 VMs with prefixes dtst in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdt1', 'dvdt2', 'dvdt3', 'dvdt4', 'dvdt5', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'replica-48855172-4d64-4f48-9e95-cb4428a8e522', 'template-win2k12R2-std-vl']

      2016-08-19 15:18:17,699 INFO Matching VM Base_Template_Win7Pro64 with prefix: dtst

      2016-08-19 15:18:17,699 INFO Matching VM GI-Win7x64 with prefix: dtst

      2016-08-19 15:18:17,699 INFO Matching VM GoldenClient with prefix: dtst

      2016-08-19 15:18:17,700 INFO Matching VM GoldenDesktop with prefix: dtst

      2016-08-19 15:18:17,700 INFO Matching VM W7x64-Clean with prefix: dtst

      2016-08-19 15:18:17,700 INFO Matching VM cp-parent-0061be90-6728-4d90-80d2-266320fdf818 with prefix: dtst

      2016-08-19 15:18:17,700 INFO Matching VM cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1 with prefix: dtst

      2016-08-19 15:18:17,700 INFO Matching VM cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43 with prefix: dtst

      2016-08-19 15:18:17,700 INFO Matching VM cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6 with prefix: dtst

      2016-08-19 15:18:17,700 INFO Matching VM dappvolprov02 with prefix: dtst

      2016-08-19 15:18:17,700 INFO Matching VM dcmp0701v with prefix: dtst

      2016-08-19 15:18:17,700 INFO Matching VM dcvp0703v with prefix: dtst

      2016-08-19 15:18:17,701 INFO Matching VM dcvp0704v with prefix: dtst

      2016-08-19 15:18:17,701 INFO Matching VM dhcs0701v with prefix: dtst

      2016-08-19 15:18:17,701 INFO Matching VM dhcs0702v with prefix: dtst

      2016-08-19 15:18:17,701 INFO Matching VM dhdb0341v with prefix: dtst

      2016-08-19 15:18:17,701 INFO Matching VM dhds99001v with prefix: dtst

      2016-08-19 15:18:17,701 INFO Matching VM dhds99002v with prefix: dtst

      2016-08-19 15:18:17,701 INFO Matching VM dhvp0701v with prefix: dtst

      2016-08-19 15:18:17,701 INFO Matching VM dpsc0702v with prefix: dtst

      2016-08-19 15:18:17,701 INFO Matching VM dsrm0401v with prefix: dtst

      2016-08-19 15:18:17,702 INFO Matching VM dsrm0702v with prefix: dtst

      2016-08-19 15:18:17,702 INFO Matching VM dtds0704v with prefix: dtst

      2016-08-19 15:18:17,702 INFO Matching VM dtst0701v with prefix: dtst

      2016-08-19 15:18:17,702 INFO VM dtst0701v matched with prefix: dtst

      2016-08-19 15:18:17,702 INFO Matching VM dtst0702v with prefix: dtst

      2016-08-19 15:18:17,702 INFO VM dtst0702v matched with prefix: dtst

      2016-08-19 15:18:17,702 INFO Matching VM dvav0701v with prefix: dtst

      2016-08-19 15:18:17,702 INFO Matching VM dvav0702v with prefix: dtst

      2016-08-19 15:18:17,702 INFO Matching VM dvcr0702v with prefix: dtst

      2016-08-19 15:18:17,703 INFO Matching VM dvdt01v with prefix: dtst

      2016-08-19 15:18:17,703 INFO Matching VM dvdt02v with prefix: dtst

      2016-08-19 15:18:17,703 INFO Matching VM dvdt03v with prefix: dtst

      2016-08-19 15:18:17,703 INFO Matching VM dvdt1 with prefix: dtst

      2016-08-19 15:18:17,703 INFO Matching VM dvdt2 with prefix: dtst

      2016-08-19 15:18:17,703 INFO Matching VM dvdt3 with prefix: dtst

      2016-08-19 15:18:17,703 INFO Matching VM dvdt4 with prefix: dtst

      2016-08-19 15:18:17,703 INFO Matching VM dvdt5 with prefix: dtst

      2016-08-19 15:18:17,703 INFO Matching VM dvdtjm001v with prefix: dtst

      2016-08-19 15:18:17,704 INFO Matching VM dvdtjm002v with prefix: dtst

      2016-08-19 15:18:17,704 INFO Matching VM dvdtjm003v with prefix: dtst

      2016-08-19 15:18:17,704 INFO Matching VM dvdtjm004v with prefix: dtst

      2016-08-19 15:18:17,704 INFO Matching VM dvdtjm005v with prefix: dtst

      2016-08-19 15:18:17,704 INFO Matching VM dvsm0704v with prefix: dtst

      2016-08-19 15:18:17,704 INFO Matching VM goldImage-xdadm-VDI-Team with prefix: dtst

      2016-08-19 15:18:17,704 INFO Matching VM replica-48855172-4d64-4f48-9e95-cb4428a8e522 with prefix: dtst

      2016-08-19 15:18:17,704 INFO Matching VM template-win2k12R2-std-vl with prefix: dtst

      2016-08-19 15:18:17,704 WARNING Not enough VMs found based on the prefixes: dtst

      2016-08-19 15:18:17,705 INFO Desktop VMs found: ['dtst0701v', 'dtst0702v']

      2016-08-19 15:18:17,705 INFO Looking for 5 VMs with prefixes dtst in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-0061be90-6728-4d90-80d2-266320fdf818', 'cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1', 'cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43', 'cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdt1', 'dvdt2', 'dvdt3', 'dvdt4', 'dvdt5', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'replica-48855172-4d64-4f48-9e95-cb4428a8e522', 'template-win2k12R2-std-vl']

      2016-08-19 15:18:17,705 INFO Matching VM Base_Template_Win7Pro64 with prefix: dtst

      2016-08-19 15:18:17,705 INFO Matching VM GI-Win7x64 with prefix: dtst

      2016-08-19 15:18:17,705 INFO Matching VM GoldenClient with prefix: dtst

      2016-08-19 15:18:17,705 INFO Matching VM GoldenDesktop with prefix: dtst

      2016-08-19 15:18:17,705 INFO Matching VM W7x64-Clean with prefix: dtst

      2016-08-19 15:18:17,705 INFO Matching VM cp-parent-0061be90-6728-4d90-80d2-266320fdf818 with prefix: dtst

      2016-08-19 15:18:17,706 INFO Matching VM cp-parent-2b9ed405-f7a4-4896-82c1-44445c8645e1 with prefix: dtst

      2016-08-19 15:18:17,706 INFO Matching VM cp-replica-203e8321-2eef-49d4-92f0-fe8acf0adb43 with prefix: dtst

      2016-08-19 15:18:17,706 INFO Matching VM cp-template-abaccf82-2c4f-49a7-afb8-4148ced0c9e6 with prefix: dtst

      2016-08-19 15:18:17,706 INFO Matching VM dappvolprov02 with prefix: dtst

      2016-08-19 15:18:17,706 INFO Matching VM dcmp0701v with prefix: dtst

      2016-08-19 15:18:17,706 INFO Matching VM dcvp0703v with prefix: dtst

      2016-08-19 15:18:17,706 INFO Matching VM dcvp0704v with prefix: dtst

      2016-08-19 15:18:17,706 INFO Matching VM dhcs0701v with prefix: dtst

      2016-08-19 15:18:17,706 INFO Matching VM dhcs0702v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dhdb0341v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dhds99001v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dhds99002v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dhvp0701v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dpsc0702v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dsrm0401v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dsrm0702v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dtds0704v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dvav0701v with prefix: dtst

      2016-08-19 15:18:17,707 INFO Matching VM dvav0702v with prefix: dtst

      2016-08-19 15:18:17,708 INFO Matching VM dvcr0702v with prefix: dtst

      2016-08-19 15:18:17,708 INFO Matching VM dvdt01v with prefix: dtst

      2016-08-19 15:18:17,708 INFO Matching VM dvdt02v with prefix: dtst

      2016-08-19 15:18:17,708 INFO Matching VM dvdt03v with prefix: dtst

      2016-08-19 15:18:17,708 INFO Matching VM dvdt1 with prefix: dtst

      2016-08-19 15:18:17,708 INFO Matching VM dvdt2 with prefix: dtst

      2016-08-19 15:18:17,708 INFO Matching VM dvdt3 with prefix: dtst

      2016-08-19 15:18:17,708 INFO Matching VM dvdt4 with prefix: dtst

      2016-08-19 15:18:17,709 INFO Matching VM dvdt5 with prefix: dtst

      2016-08-19 15:18:17,709 INFO Matching VM dvdtjm001v with prefix: dtst

      2016-08-19 15:18:17,709 INFO Matching VM dvdtjm002v with prefix: dtst

      2016-08-19 15:18:17,709 INFO Matching VM dvdtjm003v with prefix: dtst

      2016-08-19 15:18:17,709 INFO Matching VM dvdtjm004v with prefix: dtst

      2016-08-19 15:18:17,709 INFO Matching VM dvdtjm005v with prefix: dtst

      2016-08-19 15:18:17,709 INFO Matching VM dvsm0704v with prefix: dtst

      2016-08-19 15:18:17,709 INFO Matching VM goldImage-xdadm-VDI-Team with prefix: dtst

      2016-08-19 15:18:17,710 INFO Matching VM replica-48855172-4d64-4f48-9e95-cb4428a8e522 with prefix: dtst

      2016-08-19 15:18:17,710 INFO Matching VM template-win2k12R2-std-vl with prefix: dtst

      2016-08-19 15:18:17,710 WARNING Not enough VMs found based on the prefixes: dtst

      2016-08-19 15:18:17,710 INFO Client VMs found: []

      2016-08-19 15:18:17,710 WARNING There were not enough desktops found: (expected: 5, found: 2)

      2016-08-19 15:18:17,710 WARNING There were not enough clients found: (expected: 5, found: 0)

      2016-08-19 15:18:17,710 INFO Loading AD Info to the data structures is done with adinfo: [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 3L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'deskvms': ['dtst0701v', 'dtst0702v'], 'rdshtype': 0L, 'clientvms': [], 'workprofile': {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}, 'clientprefix': 'dtst'}]

      2016-08-19 15:18:17,713 DEBUG Getting the configuration option monitor_control.pseudo_perfctr in VM dtst0701v

      2016-08-19 15:18:17,736 INFO The value of performance counter for VM dtst0701v: None

      2016-08-19 15:18:17,736 INFO ============================================================

      2016-08-19 15:18:17,736 INFO Error: The pseudo perf counter is not enabled in the desktop. Please run checkconfig.pyc <desk> to fix this issue.

      2016-08-19 15:18:17,736 INFO ============================================================

      2016-08-19 15:21:24,344 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:21:24,344 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:31:12,088 DEBUG Saving the run profile to the database with parameters: {u'name': u'Multi-VM-remote-PCoIP-4', u'rampuptime': u'70', u'numofvms': u'5', u'mode': u'2', u'adgroups': [{u'protocol': u'2', u'name': u'Test', u'wrk_FK': u'StandardBenchmarkProfile_1i', u'poolname': u'', u'percent': u'100', u'connections_per_client': u'1', u'rdshtype': u'0', u'clientprefix': u'dtst'}], u'desktype': u'0', u'deskprefix': u'dtst'}

      2016-08-19 15:31:12,088 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 15:31:12,090 INFO Running query: INSERT INTO runprofile (name, numofvms, mode, deskprefix, rampuptime, desktype) VALUES ("Multi-VM-remote-PCoIP-4", 5, 2, "dtst", 70, 0)

      2016-08-19 15:31:12,104 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-4"

      2016-08-19 15:31:12,104 INFO Running query: SELECT * from ADgroupprofile where name = "Test" and wrk_FK = "StandardBenchmarkProfile_1i" and percent = 100 and protocol = 2 and clientprefix = "dtst" and connections_per_client = 1 and rdshtype = 0 and poolname = ""

      2016-08-19 15:31:12,105 INFO Running query: INSERT INTO runpivot (grp_FK, run_FK) values (3, 8)

      2016-08-19 15:31:12,118 DEBUG Getting all the run profiles from the database.

      2016-08-19 15:31:12,118 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 15:49:51,247 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:49:51,247 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:49:51,249 DEBUG Deleting the run profile from the database: Multi-VM-remote-PCoIP-4

      2016-08-19 15:49:51,250 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-4"

      2016-08-19 15:49:51,250 INFO Running query: SELECT distinct * from runpivot where run_FK = 8

      2016-08-19 15:49:51,251 INFO Running query: DELETE from runpivot where run_FK = 8

      2016-08-19 15:49:51,255 INFO Running query: DELETE from ADgroupprofile where grp_id = 3

      2016-08-19 15:49:51,257 INFO Running query: DELETE from runprofile where name = "Multi-VM-remote-PCoIP-4"

      2016-08-19 15:49:51,258 INFO Running query: delete from remoteresults using remoteresults,testinfo where remoteresults.info_FK = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-4"

      2016-08-19 15:49:51,259 INFO Running query: delete from remoteerrorinfo using remoteerrorinfo,testinfo where remoteerrorinfo.errinfo_fk = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-4"

      2016-08-19 15:49:51,260 INFO Running query: delete from localresults using localresults,testinfo where localresults.linfo_FK = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-4"

      2016-08-19 15:49:51,261 INFO Running query: delete from localerrorinfo using localerrorinfo,testinfo where localerrorinfo.lerrinfo_fk = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-4"

      2016-08-19 15:49:51,261 INFO Running query: delete from testinfo where test_name = "Multi-VM-remote-PCoIP-4"

      2016-08-19 15:50:04,862 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:50:04,862 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:50:04,864 DEBUG Deleting the run profile from the database: Multi-VM-remote-PCoIP-3

      2016-08-19 15:50:04,864 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:50:04,865 INFO Running query: SELECT distinct * from runpivot where run_FK = 7

      2016-08-19 15:50:04,866 INFO Running query: DELETE from runpivot where run_FK = 7

      2016-08-19 15:50:04,870 INFO Running query: DELETE from ADgroupprofile where grp_id = 3

      2016-08-19 15:50:04,872 INFO Running query: DELETE from runprofile where name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:50:04,874 INFO Running query: delete from remoteresults using remoteresults,testinfo where remoteresults.info_FK = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:50:04,874 INFO Running query: delete from remoteerrorinfo using remoteerrorinfo,testinfo where remoteerrorinfo.errinfo_fk = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:50:04,875 INFO Running query: delete from localresults using localresults,testinfo where localresults.linfo_FK = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:50:04,876 INFO Running query: delete from localerrorinfo using localerrorinfo,testinfo where localerrorinfo.lerrinfo_fk = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:50:04,877 INFO Running query: delete from testinfo where test_name = "Multi-VM-remote-PCoIP-3"

      2016-08-19 15:50:10,414 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:50:10,414 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:50:10,416 DEBUG Deleting the run profile from the database: Multi-VM-remote-PCoIP-2

      2016-08-19 15:50:10,416 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 15:50:10,417 INFO Running query: SELECT distinct * from runpivot where run_FK = 6

      2016-08-19 15:50:10,418 INFO Running query: DELETE from runpivot where run_FK = 6

      2016-08-19 15:50:10,430 INFO Running query: DELETE from ADgroupprofile where grp_id = 3

      2016-08-19 15:50:10,432 INFO Running query: DELETE from runprofile where name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 15:50:10,433 INFO Running query: delete from remoteresults using remoteresults,testinfo where remoteresults.info_FK = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 15:50:10,434 INFO Running query: delete from remoteerrorinfo using remoteerrorinfo,testinfo where remoteerrorinfo.errinfo_fk = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 15:50:10,435 INFO Running query: delete from localresults using localresults,testinfo where localresults.linfo_FK = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 15:50:10,435 INFO Running query: delete from localerrorinfo using localerrorinfo,testinfo where localerrorinfo.lerrinfo_fk = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 15:50:10,436 INFO Running query: delete from testinfo where test_name = "Multi-VM-remote-PCoIP-2"

      2016-08-19 15:50:14,837 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:50:14,837 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:50:14,839 DEBUG Deleting the run profile from the database: Multi-VM-remote-PCoIP

      2016-08-19 15:50:14,839 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP"

      2016-08-19 15:50:14,840 INFO Running query: SELECT distinct * from runpivot where run_FK = 5

      2016-08-19 15:50:14,841 INFO Running query: DELETE from runpivot where run_FK = 5

      2016-08-19 15:50:14,849 INFO Running query: DELETE from ADgroupprofile where grp_id = 3

      2016-08-19 15:50:14,850 INFO Running query: DELETE from runprofile where name = "Multi-VM-remote-PCoIP"

      2016-08-19 15:50:14,851 INFO Running query: delete from remoteresults using remoteresults,testinfo where remoteresults.info_FK = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP"

      2016-08-19 15:50:14,852 INFO Running query: delete from remoteerrorinfo using remoteerrorinfo,testinfo where remoteerrorinfo.errinfo_fk = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP"

      2016-08-19 15:50:14,853 INFO Running query: delete from localresults using localresults,testinfo where localresults.linfo_FK = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP"

      2016-08-19 15:50:14,853 INFO Running query: delete from localerrorinfo using localerrorinfo,testinfo where localerrorinfo.lerrinfo_fk = testinfo.test_id and testinfo.test_name = "Multi-VM-remote-PCoIP"

      2016-08-19 15:50:14,854 INFO Running query: delete from testinfo where test_name = "Multi-VM-remote-PCoIP"

      2016-08-19 15:50:14,865 DEBUG Getting all the run profiles from the database.

      2016-08-19 15:50:14,865 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 15:50:18,300 DEBUG Getting all the run profiles from the database.

      2016-08-19 15:50:18,301 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 15:50:18,306 DEBUG Getting all the test names and their start times from the database.

      2016-08-19 15:50:18,307 INFO Running query: SELECT distinct start_time,test_name from testinfo order by start_time DESC

      2016-08-19 15:50:18,309 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:50:18,309 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:50:18,323 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 15:50:18,324 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 15:50:18,324 DEBUG key: VCuser, value: s25775d

      2016-08-19 15:50:18,324 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 15:50:18,324 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 15:50:18,328 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:50:18,328 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:50:18,331 DEBUG Returning View configuration parameters: {'protocol': 'RDP', 'Viewserver': 'dhcs0702v', 'Viewuser': 's25775d', 'adserver': '127.0.0.1', 'addomainname': 'dummy', 'Viewpasswd': '*******'}

      2016-08-19 15:50:18,352 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 15:50:18,353 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 15:50:18,353 DEBUG key: VCuser, value: s25775d

      2016-08-19 15:50:18,353 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 15:50:18,353 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 15:50:18,359 DEBUG Getting all the workload profiles from the database.

      2016-08-19 15:50:18,359 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 15:50:18,361 DEBUG Getting all the new custom applications by parsing the workload config file.

      2016-08-19 15:50:18,383 DEBUG Loading the run profile from the database: Single-VM-local-2

      2016-08-19 15:50:18,383 INFO Running query: SELECT distinct * from runprofile where name = "Single-VM-local-2"

      2016-08-19 15:50:18,384 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 3

      2016-08-19 15:50:18,384 INFO Running query: SELECT * from ADgroupprofile where grp_id = 1

      2016-08-19 15:50:18,385 DEBUG Returning profile: {'deskprefix': 'GoldenDesktop', 'rampuptime': 5L, 'run_id': 3L, 'extrainfo': None, 'numofvms': 1L, 'mode': 0L, 'adgroups': [{'schedlist': None, 'protocol': 0L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 1L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': ''}], 'desktype': 0L, 'name': 'Single-VM-local-2'}

      2016-08-19 15:50:18,385 INFO Running query: SELECT max(test_id),min(test_id) from testinfo where test_name = "Single-VM-local-2" and start_time = "2016-08-17 16:49:05"

      2016-08-19 15:50:18,386 INFO Running query: select count(distinct linfo_FK) from localresults where linfo_FK >= 1 and linfo_FK <= 1

      2016-08-19 15:50:18,387 INFO Running query: select count(distinct info_FK) from remoteresults where info_FK >= 1 and info_FK <= 1

      2016-08-19 15:50:18,387 INFO Running query: select count(distinct lerrinfo_FK) from localerrorinfo where lerrinfo_FK >= 1 and lerrinfo_FK <= 1

      2016-08-19 15:50:18,388 INFO Running query: select count(distinct errinfo_FK) from remoteerrorinfo where errinfo_FK >= 1 and errinfo_FK <= 1

      2016-08-19 15:50:18,389 DEBUG Test Status (name: Single-VM-local-2 start_time: 2016-08-17 16:49:05): 0 uploaded (0 errors)

      2016-08-19 15:50:18,390 DEBUG Getting the detailed information about the workload profile: StorageAppsProfile_5i

      2016-08-19 15:50:18,390 INFO Running query: SELECT distinct * from workloadconfig where name = "StorageAppsProfile_5i"

      2016-08-19 15:50:18,391 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StorageAppsProfile_5i', 'hosttime': 1, 'random': 1, 'Iterations': 5L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,7ZIP,PPT,OUTLOOK,VIDEO'}

      2016-08-19 16:12:30,249 INFO New client registration: 192.168.155.177

      2016-08-19 16:12:30,250 INFO Current registered machines status : [Pairs required: 5] [clients: 1] [servers: 0]

      2016-08-19 16:12:30,251 INFO New client registration: 192.168.155.178

      2016-08-19 16:12:30,252 INFO Current registered machines status : [Pairs required: 5] [clients: 2] [servers: 0]

      2016-08-19 16:12:30,253 INFO New client registration: 192.168.155.176

      2016-08-19 16:12:30,254 INFO Current registered machines status : [Pairs required: 5] [clients: 3] [servers: 0]

      2016-08-19 16:12:30,254 INFO New client registration: 192.168.155.180

      2016-08-19 16:12:30,255 INFO Current registered machines status : [Pairs required: 5] [clients: 4] [servers: 0]

      2016-08-19 16:12:30,306 INFO New client registration: 192.168.155.179

      2016-08-19 16:12:30,307 INFO Current registered machines status : [Pairs required: 5] [clients: 5] [servers: 0]

      2016-08-19 16:14:26,412 INFO New client registration: 192.168.155.177

      2016-08-19 16:14:26,418 INFO New client registration: 192.168.155.176

      2016-08-19 16:14:26,709 INFO New client registration: 192.168.155.178

      2016-08-19 16:14:37,024 INFO New client registration: 192.168.155.179

      2016-08-19 16:15:07,253 INFO New client registration: 192.168.155.180

      2016-08-19 16:15:30,307 INFO Scanning all the VMs for any required fixes.

      2016-08-19 16:15:30,307 INFO Getting the IP address for VM dtst0701v from vCenter

      2016-08-19 16:15:30,318 INFO Got the IP address for VM dtst0701v: 192.168.155.100

      2016-08-19 16:15:30,318 INFO Getting the IP address for VM dtst0702v from vCenter

      2016-08-19 16:15:30,327 INFO Got the IP address for VM dtst0702v: 192.168.155.150

      2016-08-19 16:15:30,330 INFO Fixing the VM dtst0701v with status: poweredOn

      2016-08-19 16:15:30,334 INFO Resetting the VM: dtst0701v

      2016-08-19 16:15:30,370 INFO Fixing the VM dtst0702v with status: poweredOn

      2016-08-19 16:15:30,373 INFO Resetting the VM: dtst0702v

      2016-08-19 16:15:30,395 INFO Done scanning VMs.

      2016-08-19 16:17:30,418 INFO New client registration: 192.168.155.177

      2016-08-19 16:17:30,603 INFO New client registration: 192.168.155.178

      2016-08-19 16:17:35,036 INFO New client registration: 192.168.155.179

      2016-08-19 16:17:40,879 INFO New client registration: 192.168.155.180

      2016-08-19 16:17:57,279 INFO New client registration: 192.168.155.176

      2016-08-19 16:18:30,396 INFO Scanning all the VMs for any required fixes.

      2016-08-19 16:18:30,400 INFO Fixing the VM dtst0701v with status: poweredOn

      2016-08-19 16:18:30,403 INFO Resetting the VM: dtst0701v

      2016-08-19 16:18:30,424 INFO Fixing the VM dtst0702v with status: poweredOn

      2016-08-19 16:18:30,430 INFO Resetting the VM: dtst0702v

      2016-08-19 16:18:30,441 INFO Done scanning VMs.

      2016-08-19 16:20:07,782 INFO New client registration: 192.168.155.177

      2016-08-19 16:41:00,745 DEBUG Getting all the workload profiles from the database.

      2016-08-19 16:41:00,745 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 16:44:54,322 DEBUG Getting all the hostnames from the vCenter.

      2016-08-19 16:44:54,343 DEBUG Returning hosts: ['dhvm08001.ncsecu.dev', 'dhvm08002.ncsecu.dev']

      2016-08-19 16:45:08,065 DEBUG Provisioning the VMs using vCenter cloning operations with parameters: {u'maxvmperhost': u'50', u'parentvm': u'GoldenClient', u'vmprefix': u'dvpd', u'confType': u'0', u'numofvms': u'5', u'clonetype': u'1', u'reslist': u'dhvm08001.ncsecu.dev,dhvm08002.ncsecu.dev', u'customspec': u'VP-Linked-Clones'}

      2016-08-19 16:45:08,065 DEBUG Stopping the runtest thread.

      2016-08-19 16:45:08,065 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 16:45:08,065 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 16:45:08,066 DEBUG key: VCuser, value: s25775d

      2016-08-19 16:45:08,066 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 16:45:08,066 INFO Getting list of hosts from vCenter

      2016-08-19 16:45:08,087 INFO Getting all the datacenters from vCenter

      2016-08-19 16:45:08,097 INFO Getting all the compute resources from vCenter

      2016-08-19 16:45:08,112 INFO Getting all the clusters from vCenter

      2016-08-19 16:45:08,126 DEBUG Starting the provisioning thread

      2016-08-19 16:45:08,126 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 16:45:08,126 INFO Returning the provisioning status: 1000

      2016-08-19 16:45:08,127 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 16:45:08,128 DEBUG key: VCuser, value: s25775d

      2016-08-19 16:45:08,128 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 16:45:08,128 INFO Getting list of hosts from vCenter

      2016-08-19 16:45:08,148 INFO Getting all the datacenters from vCenter

      2016-08-19 16:45:08,158 INFO Getting all the compute resources from vCenter

      2016-08-19 16:45:08,173 INFO Getting all the clusters from vCenter

      2016-08-19 16:45:08,382 DEBUG Host list prepared for provisioning: [u'dhvm08001.ncsecu.dev', u'dhvm08002.ncsecu.dev']

      2016-08-19 16:45:08,382 DEBUG Getting the hostname from the hosts lists with index: 0

      2016-08-19 16:45:08,382 DEBUG Returning the hostname : dhvm08001.ncsecu.dev

      2016-08-19 16:45:08,586 INFO Creating 5 linked clones with prefix dvpd

      2016-08-19 16:45:08,586 DEBUG Calling Clone APIs to create new clone: dvpd1

      2016-08-19 16:45:08,743 DEBUG Calling Clone APIs to create new clone: dvpd2

      2016-08-19 16:45:08,871 DEBUG Calling Clone APIs to create new clone: dvpd3

      2016-08-19 16:45:09,041 DEBUG Calling Clone APIs to create new clone: dvpd4

      2016-08-19 16:45:09,264 DEBUG Calling Clone APIs to create new clone: dvpd5

      2016-08-19 16:45:33,980 INFO Operation: Provisioning_Clones, Time of Start: Fri Aug 19 16:45:08 2016, Total time taken: 52.12

      2016-08-19 16:45:33,980 DEBUG Completed provisioning. Checking if all the VMs were created succesfully with prefix: dvpd

      2016-08-19 16:45:34,186 DEBUG Powering on VM: dvpd1

      2016-08-19 16:45:34,197 DEBUG Powering on VM: dvpd2

      2016-08-19 16:45:34,230 DEBUG Powering on VM: dvpd3

      2016-08-19 16:45:34,244 DEBUG Powering on VM: dvpd4

      2016-08-19 16:45:34,260 DEBUG Powering on VM: dvpd5

      2016-08-19 16:45:38,058 INFO Returning the provisioning status: 1

      2016-08-19 16:45:47,013 DEBUG Getting all the workload profiles from the database.

      2016-08-19 16:45:47,014 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 16:46:27,045 DEBUG Saving the run profile to the database with parameters: {u'name': u'Multi-VM-remote-PCoIP', u'rampuptime': u'70', u'numofvms': u'5', u'mode': u'2', u'adgroups': [{u'protocol': u'2', u'name': u'Test', u'wrk_FK': u'StandardBenchmarkProfile_1i', u'poolname': u'', u'percent': u'100', u'connections_per_client': u'1', u'rdshtype': u'0', u'clientprefix': u'dvpd'}], u'desktype': u'0', u'deskprefix': u'dvpd'}

      2016-08-19 16:46:27,045 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 16:46:27,047 INFO Running query: INSERT INTO runprofile (name, numofvms, mode, deskprefix, rampuptime, desktype) VALUES ("Multi-VM-remote-PCoIP", 5, 2, "dvpd", 70, 0)

      2016-08-19 16:46:27,054 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP"

      2016-08-19 16:46:27,055 INFO Running query: SELECT * from ADgroupprofile where name = "Test" and wrk_FK = "StandardBenchmarkProfile_1i" and percent = 100 and protocol = 2 and clientprefix = "dvpd" and connections_per_client = 1 and rdshtype = 0 and poolname = ""

      2016-08-19 16:46:27,056 INFO Running query: INSERT INTO ADgroupprofile (name, wrk_FK, percent, protocol, clientprefix, connections_per_client, rdshtype, poolname) values ("Test", "StandardBenchmarkProfile_1i", 100, 2, "dvpd", 1, 0, "")

      2016-08-19 16:46:27,058 INFO Running query: SELECT * from ADgroupprofile where name = "Test" and wrk_FK = "StandardBenchmarkProfile_1i" and percent = 100 and protocol = 2 and clientprefix = "dvpd" and connections_per_client = 1 and rdshtype = 0 and poolname = ""

      2016-08-19 16:46:27,059 INFO Running query: INSERT INTO runpivot (grp_FK, run_FK) values (4, 9)

      2016-08-19 16:46:27,069 DEBUG Getting all the run profiles from the database.

      2016-08-19 16:46:27,069 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 16:46:32,587 INFO ============================================================

      2016-08-19 16:46:32,587 INFO Executing the run profile from the database: Multi-VM-remote-PCoIP

      2016-08-19 16:46:32,587 INFO ============================================================

      2016-08-19 16:46:32,587 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP"

      2016-08-19 16:46:32,588 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 9

      2016-08-19 16:46:32,589 INFO Running query: SELECT * from ADgroupprofile where grp_id = 4

      2016-08-19 16:46:32,590 DEBUG Running profile Multi-VM-remote-PCoIP with parameters: {'deskprefix': 'dvpd', 'rampuptime': 70L, 'run_id': 9L, 'extrainfo': None, 'numofvms': 5L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 4L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dvpd'}], 'desktype': 0L, 'name': 'Multi-VM-remote-PCoIP'}

      2016-08-19 16:46:32,590 DEBUG Resetting all variables in shared_data.

      2016-08-19 16:46:32,590 DEBUG Resetting all variables in shared_data [Done].

      2016-08-19 16:46:32,591 INFO Setting the checkpoint index of upload/connections: 4

      2016-08-19 16:46:32,591 DEBUG Setting all past tests state to Done

      2016-08-19 16:46:32,591 INFO Running query: UPDATE testinfo SET test_state = "Done"

      2016-08-19 16:46:32,611 DEBUG Setting all hosts to busy

      2016-08-19 16:46:32,611 INFO Running query: UPDATE hostinfo SET state = "Old"

      2016-08-19 16:46:32,620 DEBUG Connecting to the View server agent.

      2016-08-19 16:46:32,621 INFO adding callback function for getting the remote root object for ipaddr: dhcs0702v

      2016-08-19 16:46:32,622 INFO Got View object from the View server.

      2016-08-19 16:46:32,629 WARNING ViewAPI not supported on this View server.

      2016-08-19 16:46:32,630 DEBUG Connecting to the AD agent

      2016-08-19 16:46:32,630 INFO Adding callback function for getting the remote root object for IP address: 127.0.0.1

      2016-08-19 16:46:32,631 INFO Got AD object from the AD server.

      2016-08-19 16:46:32,671 DEBUG Waiting for the current thread to terminate. IF it does not terminate in 10 seconds, the vdiappd service will be restarted.

      2016-08-19 16:46:32,672 DEBUG Stopping the runtest thread.

      2016-08-19 16:46:32,672 DEBUG Starting the runtest thread 2586

      2016-08-19 16:46:32,678 INFO Getting list of hosts from vCenter

      2016-08-19 16:46:32,705 INFO Getting all the datacenters from vCenter

      2016-08-19 16:46:32,715 INFO Getting all the compute resources from vCenter

      2016-08-19 16:46:32,730 INFO Getting all the clusters from vCenter

      2016-08-19 16:46:32,745 INFO Getting all the VMs from the vCenter.

      2016-08-19 16:46:32,967 INFO Getting all the VMs from the vCenter[done].

      2016-08-19 16:46:32,967 INFO AD group: {'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 4L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dvpd'}

      2016-08-19 16:46:32,967 INFO Running query: SELECT distinct * from workloadconfig where name = "StandardBenchmarkProfile_1i"

      2016-08-19 16:46:32,968 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 16:46:32,968 INFO Making AD config file config-test.txt for the Test AD group desktops with workprofile: {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 16:46:32,969 INFO Looking for 5 VMs with prefixes dvpd in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-b6d80771-530d-4c40-8475-812eae33fcd0', 'cp-parent-fe8e9a02-7a31-4bb7-b47e-1b99e3de0ffa', 'cp-replica-dd7fa8f8-a153-43c6-a395-9a8d5f514819', 'cp-template-0a36f734-dac1-48de-94a1-e9c5ce7e41c1', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvpd-01-v', 'dvpd-02-v', 'dvpd-03-v', 'dvpd-04-v', 'dvpd-05-v', 'dvpd1', 'dvpd2', 'dvpd3', 'dvpd4', 'dvpd5', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'replica-1e69582a-5da5-4f8e-9adc-b255f252eacb', 'template-win2k12R2-std-vl']

      2016-08-19 16:46:32,969 INFO Matching VM Base_Template_Win7Pro64 with prefix: dvpd

      2016-08-19 16:46:32,969 INFO Matching VM GI-Win7x64 with prefix: dvpd

      2016-08-19 16:46:32,969 INFO Matching VM GoldenClient with prefix: dvpd

      2016-08-19 16:46:32,969 INFO Matching VM GoldenDesktop with prefix: dvpd

      2016-08-19 16:46:32,970 INFO Matching VM W7x64-Clean with prefix: dvpd

      2016-08-19 16:46:32,970 INFO Matching VM cp-parent-b6d80771-530d-4c40-8475-812eae33fcd0 with prefix: dvpd

      2016-08-19 16:46:32,970 INFO Matching VM cp-parent-fe8e9a02-7a31-4bb7-b47e-1b99e3de0ffa with prefix: dvpd

      2016-08-19 16:46:32,970 INFO Matching VM cp-replica-dd7fa8f8-a153-43c6-a395-9a8d5f514819 with prefix: dvpd

      2016-08-19 16:46:32,970 INFO Matching VM cp-template-0a36f734-dac1-48de-94a1-e9c5ce7e41c1 with prefix: dvpd

      2016-08-19 16:46:32,970 INFO Matching VM dappvolprov02 with prefix: dvpd

      2016-08-19 16:46:32,970 INFO Matching VM dcmp0701v with prefix: dvpd

      2016-08-19 16:46:32,970 INFO Matching VM dcvp0703v with prefix: dvpd

      2016-08-19 16:46:32,971 INFO Matching VM dcvp0704v with prefix: dvpd

      2016-08-19 16:46:32,971 INFO Matching VM dhcs0701v with prefix: dvpd

      2016-08-19 16:46:32,971 INFO Matching VM dhcs0702v with prefix: dvpd

      2016-08-19 16:46:32,971 INFO Matching VM dhdb0341v with prefix: dvpd

      2016-08-19 16:46:32,971 INFO Matching VM dhds99001v with prefix: dvpd

      2016-08-19 16:46:32,971 INFO Matching VM dhds99002v with prefix: dvpd

      2016-08-19 16:46:32,971 INFO Matching VM dhvp0701v with prefix: dvpd

      2016-08-19 16:46:32,971 INFO Matching VM dpsc0702v with prefix: dvpd

      2016-08-19 16:46:32,971 INFO Matching VM dsrm0401v with prefix: dvpd

      2016-08-19 16:46:32,972 INFO Matching VM dsrm0702v with prefix: dvpd

      2016-08-19 16:46:32,972 INFO Matching VM dtds0704v with prefix: dvpd

      2016-08-19 16:46:32,972 INFO Matching VM dtst0701v with prefix: dvpd

      2016-08-19 16:46:32,972 INFO Matching VM dtst0702v with prefix: dvpd

      2016-08-19 16:46:32,972 INFO Matching VM dvav0701v with prefix: dvpd

      2016-08-19 16:46:32,972 INFO Matching VM dvav0702v with prefix: dvpd

      2016-08-19 16:46:32,972 INFO Matching VM dvcr0702v with prefix: dvpd

      2016-08-19 16:46:32,972 INFO Matching VM dvdt01v with prefix: dvpd

      2016-08-19 16:46:32,972 INFO Matching VM dvdt02v with prefix: dvpd

      2016-08-19 16:46:32,973 INFO Matching VM dvdt03v with prefix: dvpd

      2016-08-19 16:46:32,973 INFO Matching VM dvdtjm001v with prefix: dvpd

      2016-08-19 16:46:32,973 INFO Matching VM dvdtjm002v with prefix: dvpd

      2016-08-19 16:46:32,973 INFO Matching VM dvdtjm003v with prefix: dvpd

      2016-08-19 16:46:32,973 INFO Matching VM dvdtjm004v with prefix: dvpd

      2016-08-19 16:46:32,973 INFO Matching VM dvdtjm005v with prefix: dvpd

      2016-08-19 16:46:32,973 INFO Matching VM dvpd-01-v with prefix: dvpd

      2016-08-19 16:46:32,973 INFO VM dvpd-01-v matched with prefix: dvpd

      2016-08-19 16:46:32,973 INFO Matching VM dvpd-02-v with prefix: dvpd

      2016-08-19 16:46:32,973 INFO VM dvpd-02-v matched with prefix: dvpd

      2016-08-19 16:46:32,974 INFO Matching VM dvpd-03-v with prefix: dvpd

      2016-08-19 16:46:32,974 INFO VM dvpd-03-v matched with prefix: dvpd

      2016-08-19 16:46:32,974 INFO Matching VM dvpd-04-v with prefix: dvpd

      2016-08-19 16:46:32,974 INFO VM dvpd-04-v matched with prefix: dvpd

      2016-08-19 16:46:32,974 INFO Matching VM dvpd-05-v with prefix: dvpd

      2016-08-19 16:46:32,974 INFO VM dvpd-05-v matched with prefix: dvpd

      2016-08-19 16:46:32,974 INFO Desktop VMs found: ['dvpd-01-v', 'dvpd-02-v', 'dvpd-03-v', 'dvpd-04-v', 'dvpd-05-v']

      2016-08-19 16:46:32,974 INFO Looking for 5 VMs with prefixes dvpd in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-b6d80771-530d-4c40-8475-812eae33fcd0', 'cp-parent-fe8e9a02-7a31-4bb7-b47e-1b99e3de0ffa', 'cp-replica-dd7fa8f8-a153-43c6-a395-9a8d5f514819', 'cp-template-0a36f734-dac1-48de-94a1-e9c5ce7e41c1', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdtjm001v', 'dvdtjm002v', 'dvdtjm003v', 'dvdtjm004v', 'dvdtjm005v', 'dvpd1', 'dvpd2', 'dvpd3', 'dvpd4', 'dvpd5', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'replica-1e69582a-5da5-4f8e-9adc-b255f252eacb', 'template-win2k12R2-std-vl']

      2016-08-19 16:46:32,975 INFO Matching VM Base_Template_Win7Pro64 with prefix: dvpd

      2016-08-19 16:46:32,975 INFO Matching VM GI-Win7x64 with prefix: dvpd

      2016-08-19 16:46:32,975 INFO Matching VM GoldenClient with prefix: dvpd

      2016-08-19 16:46:32,975 INFO Matching VM GoldenDesktop with prefix: dvpd

      2016-08-19 16:46:32,975 INFO Matching VM W7x64-Clean with prefix: dvpd

      2016-08-19 16:46:32,975 INFO Matching VM cp-parent-b6d80771-530d-4c40-8475-812eae33fcd0 with prefix: dvpd

      2016-08-19 16:46:32,975 INFO Matching VM cp-parent-fe8e9a02-7a31-4bb7-b47e-1b99e3de0ffa with prefix: dvpd

      2016-08-19 16:46:32,975 INFO Matching VM cp-replica-dd7fa8f8-a153-43c6-a395-9a8d5f514819 with prefix: dvpd

      2016-08-19 16:46:32,975 INFO Matching VM cp-template-0a36f734-dac1-48de-94a1-e9c5ce7e41c1 with prefix: dvpd

      2016-08-19 16:46:32,976 INFO Matching VM dappvolprov02 with prefix: dvpd

      2016-08-19 16:46:32,976 INFO Matching VM dcmp0701v with prefix: dvpd

      2016-08-19 16:46:32,976 INFO Matching VM dcvp0703v with prefix: dvpd

      2016-08-19 16:46:32,976 INFO Matching VM dcvp0704v with prefix: dvpd

      2016-08-19 16:46:32,976 INFO Matching VM dhcs0701v with prefix: dvpd

      2016-08-19 16:46:32,976 INFO Matching VM dhcs0702v with prefix: dvpd

      2016-08-19 16:46:32,976 INFO Matching VM dhdb0341v with prefix: dvpd

      2016-08-19 16:46:32,976 INFO Matching VM dhds99001v with prefix: dvpd

      2016-08-19 16:46:32,976 INFO Matching VM dhds99002v with prefix: dvpd

      2016-08-19 16:46:32,977 INFO Matching VM dhvp0701v with prefix: dvpd

      2016-08-19 16:46:32,977 INFO Matching VM dpsc0702v with prefix: dvpd

      2016-08-19 16:46:32,977 INFO Matching VM dsrm0401v with prefix: dvpd

      2016-08-19 16:46:32,977 INFO Matching VM dsrm0702v with prefix: dvpd

      2016-08-19 16:46:32,977 INFO Matching VM dtds0704v with prefix: dvpd

      2016-08-19 16:46:32,977 INFO Matching VM dtst0701v with prefix: dvpd

      2016-08-19 16:46:32,977 INFO Matching VM dtst0702v with prefix: dvpd

      2016-08-19 16:46:32,977 INFO Matching VM dvav0701v with prefix: dvpd

      2016-08-19 16:46:32,977 INFO Matching VM dvav0702v with prefix: dvpd

      2016-08-19 16:46:32,978 INFO Matching VM dvcr0702v with prefix: dvpd

      2016-08-19 16:46:32,978 INFO Matching VM dvdt01v with prefix: dvpd

      2016-08-19 16:46:32,978 INFO Matching VM dvdt02v with prefix: dvpd

      2016-08-19 16:46:32,978 INFO Matching VM dvdt03v with prefix: dvpd

      2016-08-19 16:46:32,978 INFO Matching VM dvdtjm001v with prefix: dvpd

      2016-08-19 16:46:32,978 INFO Matching VM dvdtjm002v with prefix: dvpd

      2016-08-19 16:46:32,978 INFO Matching VM dvdtjm003v with prefix: dvpd

      2016-08-19 16:46:32,978 INFO Matching VM dvdtjm004v with prefix: dvpd

      2016-08-19 16:46:32,978 INFO Matching VM dvdtjm005v with prefix: dvpd

      2016-08-19 16:46:32,979 INFO Matching VM dvpd1 with prefix: dvpd

      2016-08-19 16:46:32,979 INFO VM dvpd1 matched with prefix: dvpd

      2016-08-19 16:46:32,979 INFO Matching VM dvpd2 with prefix: dvpd

      2016-08-19 16:46:32,979 INFO VM dvpd2 matched with prefix: dvpd

      2016-08-19 16:46:32,979 INFO Matching VM dvpd3 with prefix: dvpd

      2016-08-19 16:46:32,979 INFO VM dvpd3 matched with prefix: dvpd

      2016-08-19 16:46:32,979 INFO Matching VM dvpd4 with prefix: dvpd

      2016-08-19 16:46:32,979 INFO VM dvpd4 matched with prefix: dvpd

      2016-08-19 16:46:32,979 INFO Matching VM dvpd5 with prefix: dvpd

      2016-08-19 16:46:32,980 INFO VM dvpd5 matched with prefix: dvpd

      2016-08-19 16:46:32,980 INFO Client VMs found: ['dvpd1', 'dvpd2', 'dvpd3', 'dvpd4', 'dvpd5']

      2016-08-19 16:46:32,980 INFO Loading AD Info to the data structures is done with adinfo: [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 4L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'deskvms': ['dvpd-01-v', 'dvpd-02-v', 'dvpd-03-v', 'dvpd-04-v', 'dvpd-05-v'], 'rdshtype': 0L, 'clientvms': ['dvpd1', 'dvpd2', 'dvpd3', 'dvpd4', 'dvpd5'], 'workprofile': {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}, 'clientprefix': 'dvpd'}]

      2016-08-19 16:46:32,983 DEBUG Getting the configuration option monitor_control.pseudo_perfctr in VM dvpd-01-v

      2016-08-19 16:46:33,012 INFO The value of performance counter for VM dvpd-01-v: true

      2016-08-19 16:46:33,012 INFO Powering on 5 desktop VMs

      2016-08-19 16:46:33,012 INFO Powering on VMs in Test AD group: ['dvpd-01-v', 'dvpd-02-v', 'dvpd-03-v', 'dvpd-04-v', 'dvpd-05-v']

      2016-08-19 16:46:33,012 DEBUG Powering on the VM: dvpd-01-v

      2016-08-19 16:46:33,016 DEBUG VM dvpd-01-v is powered on. Rebooting the VM...

      2016-08-19 16:46:34,117 DEBUG Powering on the VM: dvpd-02-v

      2016-08-19 16:46:34,473 INFO New client registration: 192.168.155.183

      2016-08-19 16:46:34,473 INFO Current registered machines status : [Pairs required: 5] [clients: 1] [servers: 0]

      2016-08-19 16:46:34,646 INFO New client registration: 192.168.155.185

      2016-08-19 16:46:34,647 INFO Current registered machines status : [Pairs required: 5] [clients: 2] [servers: 0]

      2016-08-19 16:46:34,652 INFO New client registration: 192.168.155.182

      2016-08-19 16:46:34,653 INFO Current registered machines status : [Pairs required: 5] [clients: 3] [servers: 0]

      2016-08-19 16:46:35,068 DEBUG Powering on the VM: dvpd-03-v

      2016-08-19 16:46:35,766 INFO New client registration: 192.168.155.184

      2016-08-19 16:46:35,766 INFO Current registered machines status : [Pairs required: 5] [clients: 4] [servers: 0]

      2016-08-19 16:46:35,782 INFO New client registration: 192.168.155.181

      2016-08-19 16:46:35,783 INFO Current registered machines status : [Pairs required: 5] [clients: 5] [servers: 0]

      2016-08-19 16:46:36,071 DEBUG Powering on the VM: dvpd-04-v

      2016-08-19 16:46:37,073 DEBUG Powering on the VM: dvpd-05-v

      2016-08-19 16:46:37,588 DEBUG Returning the current status: 0 with message Status: [Powering On --> Desktops: 5, Clients: 0 ]

      2016-08-19 16:46:41,181 INFO Powering on 5 client VMs

      2016-08-19 16:46:41,182 INFO Powering on VMs in Test AD group: ['dvpd1', 'dvpd2', 'dvpd3', 'dvpd4', 'dvpd5']

      2016-08-19 16:46:41,182 DEBUG Powering on the VM: dvpd1

      2016-08-19 16:46:41,185 DEBUG VM dvpd1 is powered on. Rebooting the VM...

      2016-08-19 16:46:42,197 DEBUG Powering on the VM: dvpd2

      2016-08-19 16:46:42,202 DEBUG VM dvpd2 is powered on. Rebooting the VM...

      2016-08-19 16:46:42,589 DEBUG Returning the current status: 0 with message Status: [Powering On --> Desktops: 5, Clients: 2 ]

      2016-08-19 16:46:43,298 DEBUG Powering on the VM: dvpd3

      2016-08-19 16:46:43,304 DEBUG VM dvpd3 is powered on. Rebooting the VM...

      2016-08-19 16:46:44,412 DEBUG Powering on the VM: dvpd4

      2016-08-19 16:46:44,416 DEBUG VM dvpd4 is powered on. Rebooting the VM...

      2016-08-19 16:46:45,438 DEBUG Powering on the VM: dvpd5

      2016-08-19 16:46:45,443 DEBUG VM dvpd5 is powered on. Rebooting the VM...

      2016-08-19 16:46:47,588 DEBUG Returning the current status: 0 with message Status: [Powering On --> Desktops: 5, Clients: 5 ]

      2016-08-19 16:46:47,656 INFO Operation: poweron_desktops, Time of Start: Fri Aug 19 16:46:33 2016, Total time taken: 9.18

      2016-08-19 16:46:47,656 INFO Operation: poweron_clients, Time of Start: Fri Aug 19 16:46:41 2016, Total time taken: 7.55

      2016-08-19 16:46:47,657 DEBUG Creating 5 users and adding to the AD security group: Test

      2016-08-19 16:46:47,657 INFO ------------------------------------------------------------

      2016-08-19 16:46:47,657 INFO Waiting for the threshold number of VMs to register

      2016-08-19 16:46:47,657 INFO ------------------------------------------------------------

      Peer will receive following PB traceback:

      Unhandled Error

      Traceback (most recent call last):

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 153, in gotItem

        self.callExpressionReceived(item)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 116, in callExpressionReceived

        self.expressionReceived(obj)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 515, in expressionReceived

        method(*sexp[1:])

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 827, in proto_message

        self._recvMessage(self.localObjectForID, requestID, objectID, message, answerRequired, netArgs, netKw)

      --- <exception caught here> ---

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 841, in _recvMessage

        netResult = object.remoteMessageReceived(self, message, netArgs, netKw)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/flavors.py", line 112, in remoteMessageReceived

        raise NoSuchMethod("No such method: remote_%s" % (message,))

      twisted.spread.flavors.NoSuchMethod: No such method: remote_addUsers

      Peer will receive following PB traceback:

      Unhandled Error

      Traceback (most recent call last):

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 153, in gotItem

        self.callExpressionReceived(item)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 116, in callExpressionReceived

        self.expressionReceived(obj)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 515, in expressionReceived

        method(*sexp[1:])

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 827, in proto_message

        self._recvMessage(self.localObjectForID, requestID, objectID, message, answerRequired, netArgs, netKw)

      --- <exception caught here> ---

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 841, in _recvMessage

        netResult = object.remoteMessageReceived(self, message, netArgs, netKw)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/flavors.py", line 112, in remoteMessageReceived

        raise NoSuchMethod("No such method: remote_%s" % (message,))

      twisted.spread.flavors.NoSuchMethod: No such method: remote_findNetbiosDomainName

      Unhandled error in Deferred:

      Unhandled Error

      Traceback from remote host -- Traceback unavailable

      Unhandled error in Deferred:

      Unhandled Error

      Traceback from remote host -- Traceback unavailable

      2016-08-19 16:46:52,588 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 5 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 16:47:21,456 INFO New client registration: 192.168.155.179

      2016-08-19 16:47:21,456 INFO Current registered machines status : [Pairs required: 5] [clients: 6] [servers: 0]

      2016-08-19 16:47:21,459 INFO New client registration: 192.168.155.176

      2016-08-19 16:47:21,459 INFO Current registered machines status : [Pairs required: 5] [clients: 7] [servers: 0]

      2016-08-19 16:47:22,588 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 7 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 16:47:25,002 INFO New client registration: 192.168.155.177

      2016-08-19 16:47:25,002 INFO Current registered machines status : [Pairs required: 5] [clients: 8] [servers: 0]

      2016-08-19 16:47:27,592 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 8 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 16:47:41,088 INFO New client registration: 192.168.155.182

      2016-08-19 16:47:41,089 INFO New client registration: 192.168.155.185

      2016-08-19 16:47:41,156 INFO New client registration: 192.168.155.183

      2016-08-19 16:47:41,204 INFO New client registration: 192.168.155.181

      2016-08-19 16:47:48,288 INFO New client registration: 192.168.155.184

      2016-08-19 16:50:25,003 INFO Scanning all the VMs for any required fixes.

      2016-08-19 16:50:25,003 INFO Getting the IP address for VM dvpd-01-v from vCenter

      2016-08-19 16:50:25,014 INFO Got the IP address for VM dvpd-01-v: 192.168.155.177

      2016-08-19 16:50:25,015 INFO Getting the IP address for VM dvpd-02-v from vCenter

      2016-08-19 16:50:25,024 INFO Got the IP address for VM dvpd-02-v: 192.168.155.176

      2016-08-19 16:50:25,024 INFO Getting the IP address for VM dvpd-03-v from vCenter

      2016-08-19 16:50:25,034 INFO Got the IP address for VM dvpd-03-v: 192.168.155.179

      2016-08-19 16:50:25,034 INFO Getting the IP address for VM dvpd-04-v from vCenter

      2016-08-19 16:50:25,037 INFO Getting the IP address for VM dvpd-05-v from vCenter

      2016-08-19 16:50:25,040 INFO Getting the IP address for VM dvpd1 from vCenter

      2016-08-19 16:50:25,049 INFO Got the IP address for VM dvpd1: 192.168.155.183

      2016-08-19 16:50:25,049 INFO Getting the IP address for VM dvpd2 from vCenter

      2016-08-19 16:50:25,058 INFO Got the IP address for VM dvpd2: 192.168.155.185

      2016-08-19 16:50:25,059 INFO Getting the IP address for VM dvpd3 from vCenter

      2016-08-19 16:50:25,067 INFO Got the IP address for VM dvpd3: 192.168.155.181

      2016-08-19 16:50:25,068 INFO Getting the IP address for VM dvpd4 from vCenter

      2016-08-19 16:50:25,077 INFO Got the IP address for VM dvpd4: 192.168.155.182

      2016-08-19 16:50:25,077 INFO Getting the IP address for VM dvpd5 from vCenter

      2016-08-19 16:50:25,086 INFO Got the IP address for VM dvpd5: 192.168.155.184

      2016-08-19 16:50:25,089 INFO Fixing the VM dvpd-04-v with status: poweredOff

      2016-08-19 16:50:25,092 INFO Powering on the VM: dvpd-04-v

      2016-08-19 16:50:25,107 INFO Fixing the VM dvpd-05-v with status: poweredOff

      2016-08-19 16:50:25,112 INFO Powering on the VM: dvpd-05-v

      2016-08-19 16:50:25,120 INFO Done scanning VMs.

      2016-08-19 16:53:25,120 INFO Scanning all the VMs for any required fixes.

      2016-08-19 16:53:25,121 INFO Getting the IP address for VM dvpd-04-v from vCenter

      2016-08-19 16:53:25,125 INFO Getting the IP address for VM dvpd-05-v from vCenter

      2016-08-19 16:53:25,131 INFO Fixing the VM dvpd-04-v with status: poweredOff

      2016-08-19 16:53:25,134 INFO Powering on the VM: dvpd-04-v

      2016-08-19 16:53:25,149 INFO Fixing the VM dvpd-05-v with status: poweredOff

      2016-08-19 16:53:25,154 INFO Powering on the VM: dvpd-05-v

      2016-08-19 16:53:25,166 INFO Done scanning VMs.

      2016-08-19 16:53:46,351 INFO New client registration: 192.168.155.180

      2016-08-19 16:53:46,351 INFO Current registered machines status : [Pairs required: 5] [clients: 9] [servers: 0]

      2016-08-19 16:53:46,385 INFO New client registration: 192.168.155.178

      2016-08-19 16:53:46,386 INFO Current registered machines status : [Pairs required: 5] [clients: 10] [servers: 0]

      2016-08-19 16:53:47,603 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 10 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 16:56:25,261 INFO Getting the IP address for VM dvpd-04-v from vCenter

      2016-08-19 16:56:25,272 INFO Got the IP address for VM dvpd-04-v: 192.168.155.180

      2016-08-19 16:56:25,272 INFO Getting the IP address for VM dvpd-05-v from vCenter

      2016-08-19 16:56:25,281 INFO Got the IP address for VM dvpd-05-v: 192.168.155.178

      2016-08-19 16:56:25,281 DEBUG There might be issues with the following VMs in this run (Check vCenter Console to look for errors). Continuing the run with other VMs.

      2016-08-19 16:56:25,281 INFO Getting the IP address for VM dvpd-01-v from vCenter

      2016-08-19 16:56:25,290 INFO Got the IP address for VM dvpd-01-v: 192.168.155.177

      2016-08-19 16:56:25,290 INFO Getting the IP address for VM dvpd-02-v from vCenter

      2016-08-19 16:56:25,299 INFO Got the IP address for VM dvpd-02-v: 192.168.155.176

      2016-08-19 16:56:25,299 INFO Getting the IP address for VM dvpd-03-v from vCenter

      2016-08-19 16:56:25,308 INFO Got the IP address for VM dvpd-03-v: 192.168.155.179

      2016-08-19 16:56:25,308 INFO Getting the IP address for VM dvpd-04-v from vCenter

      2016-08-19 16:56:25,317 INFO Got the IP address for VM dvpd-04-v: 192.168.155.180

      2016-08-19 16:56:25,317 INFO Getting the IP address for VM dvpd-05-v from vCenter

      2016-08-19 16:56:25,326 INFO Got the IP address for VM dvpd-05-v: 192.168.155.178

      2016-08-19 16:56:25,326 INFO Getting the IP address for VM dvpd1 from vCenter

      2016-08-19 16:56:25,335 INFO Got the IP address for VM dvpd1: 192.168.155.183

      2016-08-19 16:56:25,335 INFO Getting the IP address for VM dvpd2 from vCenter

      2016-08-19 16:56:25,344 INFO Got the IP address for VM dvpd2: 192.168.155.185

      2016-08-19 16:56:25,344 INFO Getting the IP address for VM dvpd3 from vCenter

      2016-08-19 16:56:25,353 INFO Got the IP address for VM dvpd3: 192.168.155.181

      2016-08-19 16:56:25,353 INFO Getting the IP address for VM dvpd4 from vCenter

      2016-08-19 16:56:25,362 INFO Got the IP address for VM dvpd4: 192.168.155.182

      2016-08-19 16:56:25,362 INFO Getting the IP address for VM dvpd5 from vCenter

      2016-08-19 16:56:25,371 INFO Got the IP address for VM dvpd5: 192.168.155.184

      2016-08-19 16:56:25,371 DEBUG IP: 192.168.155.180 --> Name: dvpd-04-v

      2016-08-19 16:56:25,372 DEBUG IP: 192.168.155.178 --> Name: dvpd-05-v

      2016-08-19 16:56:25,372 DEBUG IP: 192.168.155.177 --> Name: dvpd-01-v

      2016-08-19 16:56:25,372 DEBUG IP: 192.168.155.176 --> Name: dvpd-02-v

      2016-08-19 16:56:25,372 DEBUG IP: 192.168.155.184 --> Name: dvpd5

      2016-08-19 16:56:25,372 DEBUG IP: 192.168.155.185 --> Name: dvpd2

      2016-08-19 16:56:25,372 DEBUG IP: 192.168.155.179 --> Name: dvpd-03-v

      2016-08-19 16:56:25,372 DEBUG IP: 192.168.155.181 --> Name: dvpd3

      2016-08-19 16:56:25,373 DEBUG IP: 192.168.155.182 --> Name: dvpd4

      2016-08-19 16:56:25,373 DEBUG IP: 192.168.155.183 --> Name: dvpd1

      2016-08-19 16:56:25,373 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.177"

      2016-08-19 16:56:25,374 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.177", 0, "Unpaired", "dvpd-01-v")

      2016-08-19 16:56:25,385 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.176"

      2016-08-19 16:56:25,386 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.176", 0, "Unpaired", "dvpd-02-v")

      2016-08-19 16:56:25,388 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.179"

      2016-08-19 16:56:25,388 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.179", 0, "Unpaired", "dvpd-03-v")

      2016-08-19 16:56:25,390 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.180"

      2016-08-19 16:56:25,390 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.180", 0, "Unpaired", "dvpd-04-v")

      2016-08-19 16:56:25,392 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.178"

      2016-08-19 16:56:25,392 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.178", 0, "Unpaired", "dvpd-05-v")

      2016-08-19 16:56:25,394 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.183"

      2016-08-19 16:56:25,394 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.183", 1, "Unpaired", "dvpd1")

      2016-08-19 16:56:25,396 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.185"

      2016-08-19 16:56:25,397 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.185", 1, "Unpaired", "dvpd2")

      2016-08-19 16:56:25,398 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.181"

      2016-08-19 16:56:25,399 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.181", 1, "Unpaired", "dvpd3")

      2016-08-19 16:56:25,400 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.182"

      2016-08-19 16:56:25,401 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.182", 1, "Unpaired", "dvpd4")

      2016-08-19 16:56:25,413 INFO Running query: SELECT * from hostinfo where IPADDR = "192.168.155.184"

      2016-08-19 16:56:25,414 INFO Running query: INSERT INTO hostinfo (IPADDR, is_Client, state, name) values ("192.168.155.184", 1, "Unpaired", "dvpd5")

      2016-08-19 16:56:25,415 INFO Registering remaining VMs.

      2016-08-19 16:56:25,416 INFO Threshold met for the number of machines. Starting the benchmark plan.

      2016-08-19 16:56:25,416 INFO Waiting for VMs to settle; sleeping for the ramp up time.

      2016-08-19 16:57:35,486 DEBUG Using View VP Agent to get the pool-desktop map.

      2016-08-19 16:57:35,487 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:57:37,040 ERROR Error from the remote: Error in mapping prefixes with error: 2004: Error in mapping VM Prefixes to Pools: string indices must be integers, not str

      Traceback:

      Traceback (most recent call last):

        File "d:\build\ob\bora-3657232\performance\viewplanner\ViewAgent\Package\C\listener.py", line 194, in remote_mapPrefixToPool

        File "d:\build\ob\bora-3657232\performance\viewplanner\ViewAgent\Package\C\viewadmin.py", line 142, in mapPrefixToPool

      TypeError: string indices must be integers, not str

       

      Unhandled error in Deferred:

      Unhandled Error

      Traceback from remote host -- Traceback unavailable

      2016-08-19 16:57:37,601 DEBUG Returning the current status: 0 with message Error in mapping prefixes with error: 2004: Error in mapping VM Prefixes to Pools: string indices must be integers, not str

      Traceback:

      Traceback (most recent call last):

        File "d:\build\ob\bora-3657232\performance\viewplanner\ViewAgent\Package\C\listener.py", line 194, in remote_mapPrefixToPool

        File "d:\build\ob\bora-3657232\performance\viewplanner\ViewAgent\Package\C\viewadmin.py", line 142, in mapPrefixToPool

      TypeError: string indices must be integers, not str

      . Please see viewplanner.log for more details

      2016-08-19 16:57:40,492 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:57:45,498 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:57:50,500 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:57:55,505 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:00,507 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:05,508 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:10,510 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:15,511 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:20,513 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:25,514 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:30,516 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:35,517 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:40,519 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:45,520 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:50,522 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:58:55,523 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:00,524 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:05,526 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:10,527 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:15,528 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:20,529 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:25,531 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:30,532 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:35,534 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:40,535 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:45,537 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:50,538 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 16:59:55,540 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:00,541 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:05,546 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:10,552 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:15,557 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:20,562 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:25,568 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:30,573 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:35,579 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:40,584 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:45,587 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:50,592 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:00:55,597 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:00,603 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:05,608 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:10,614 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:15,619 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:20,625 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:25,630 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:30,636 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:35,641 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:40,647 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:45,652 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:50,657 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:01:55,663 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:00,669 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:05,674 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:10,679 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:15,685 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:20,690 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:25,696 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:30,701 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:35,707 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:40,712 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:45,717 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:50,720 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:02:55,725 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:00,731 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:05,736 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:10,742 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:15,747 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:20,753 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:25,758 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:30,763 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:35,769 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:40,774 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:45,780 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:50,785 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:03:55,791 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:00,796 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:05,802 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:10,807 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:15,813 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:20,818 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:25,824 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:30,829 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:35,835 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:40,840 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:45,846 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:50,851 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:04:55,856 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:00,862 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:05,867 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:10,873 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:15,875 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:20,880 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:25,886 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:30,891 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:35,897 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:40,900 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:45,906 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:50,908 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:05:55,914 DEBUG Waiting for the prefixes map to be fetched from View server.

      2016-08-19 17:06:00,919 ERROR Error communicating with ViewPlanner agent in View server. Restart the ViewPlanner agent service in View server and try again.

      2016-08-19 17:25:57,193 INFO New client registration: 192.168.155.188

      2016-08-19 17:25:57,194 INFO Current registered machines status : [Pairs required: 5] [clients: 6] [servers: 5]

      2016-08-19 17:25:57,209 INFO New client registration: 192.168.155.171

      2016-08-19 17:25:57,209 INFO Current registered machines status : [Pairs required: 5] [clients: 7] [servers: 5]

      2016-08-19 17:25:58,123 INFO New client registration: 192.168.155.173

      2016-08-19 17:25:58,124 INFO Current registered machines status : [Pairs required: 5] [clients: 8] [servers: 5]

      2016-08-19 17:25:58,125 INFO New client registration: 192.168.155.187

      2016-08-19 17:25:58,126 INFO Current registered machines status : [Pairs required: 5] [clients: 9] [servers: 5]

      2016-08-19 17:25:58,134 INFO New client registration: 192.168.155.186

      2016-08-19 17:25:58,135 INFO Current registered machines status : [Pairs required: 5] [clients: 10] [servers: 5]

      2016-08-19 17:27:45,207 INFO New client registration: 192.168.155.171

      2016-08-19 17:27:45,347 INFO New client registration: 192.168.155.188

      2016-08-19 17:27:45,963 INFO New client registration: 192.168.155.186

      2016-08-19 17:28:15,829 INFO New client registration: 192.168.155.187

      2016-08-19 17:28:16,203 INFO New client registration: 192.168.155.173

      2016-08-19 17:30:35,893 INFO New client registration: 192.168.155.187

      2016-08-19 17:30:36,056 INFO New client registration: 192.168.155.173

      2016-08-19 17:30:39,211 INFO New client registration: 192.168.155.186

      2016-08-19 17:30:48,294 INFO New client registration: 192.168.155.171

      2016-08-19 17:30:48,536 INFO New client registration: 192.168.155.188

      2016-08-19 18:13:36,870 DEBUG Getting all the run profiles from the database.

      2016-08-19 18:13:36,870 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 18:13:36,885 DEBUG Getting all the test names and their start times from the database.

      2016-08-19 18:13:36,886 INFO Running query: SELECT distinct start_time,test_name from testinfo order by start_time DESC

      2016-08-19 18:13:36,887 DEBUG Getting all the workload profiles from the database.

      2016-08-19 18:13:36,887 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 18:13:36,900 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 18:13:36,900 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 18:13:36,900 DEBUG key: VCuser, value: s25775d

      2016-08-19 18:13:36,901 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 18:13:36,901 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 18:13:36,908 DEBUG Getting all the workload profiles from the database.

      2016-08-19 18:13:36,908 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 18:13:36,911 DEBUG Returning View configuration parameters: {'protocol': 'RDP', 'Viewserver': 'dhcs0702v', 'Viewuser': 's25775d', 'adserver': '127.0.0.1', 'addomainname': 'dummy', 'Viewpasswd': '*******'}

      2016-08-19 18:13:36,929 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 18:13:36,929 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 18:13:36,929 DEBUG key: VCuser, value: s25775d

      2016-08-19 18:13:36,929 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 18:13:36,929 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 18:13:36,933 DEBUG Getting all the workload profiles from the database.

      2016-08-19 18:13:36,933 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 18:13:36,935 DEBUG Getting all the new custom applications by parsing the workload config file.

      2016-08-19 18:13:37,036 DEBUG Loading the run profile from the database: Single-VM-local-2

      2016-08-19 18:13:37,036 INFO Running query: SELECT distinct * from runprofile where name = "Single-VM-local-2"

      2016-08-19 18:13:37,037 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 3

      2016-08-19 18:13:37,038 INFO Running query: SELECT * from ADgroupprofile where grp_id = 1

      2016-08-19 18:13:37,039 DEBUG Returning profile: {'deskprefix': 'GoldenDesktop', 'rampuptime': 5L, 'run_id': 3L, 'extrainfo': None, 'numofvms': 1L, 'mode': 0L, 'adgroups': [{'schedlist': None, 'protocol': 0L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 1L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': ''}], 'desktype': 0L, 'name': 'Single-VM-local-2'}

      2016-08-19 18:13:37,039 INFO Running query: SELECT max(test_id),min(test_id) from testinfo where test_name = "Single-VM-local-2" and start_time = "2016-08-17 16:49:05"

      2016-08-19 18:13:37,040 INFO Running query: select count(distinct linfo_FK) from localresults where linfo_FK >= 1 and linfo_FK <= 1

      2016-08-19 18:13:37,040 INFO Running query: select count(distinct info_FK) from remoteresults where info_FK >= 1 and info_FK <= 1

      2016-08-19 18:13:37,041 INFO Running query: select count(distinct lerrinfo_FK) from localerrorinfo where lerrinfo_FK >= 1 and lerrinfo_FK <= 1

      2016-08-19 18:13:37,042 INFO Running query: select count(distinct errinfo_FK) from remoteerrorinfo where errinfo_FK >= 1 and errinfo_FK <= 1

      2016-08-19 18:13:37,042 DEBUG Test Status (name: Single-VM-local-2 start_time: 2016-08-17 16:49:05): 0 uploaded (0 errors)

      2016-08-19 18:13:37,044 DEBUG Getting the detailed information about the workload profile: StorageAppsProfile_5i

      2016-08-19 18:13:37,044 INFO Running query: SELECT distinct * from workloadconfig where name = "StorageAppsProfile_5i"

      2016-08-19 18:13:37,045 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StorageAppsProfile_5i', 'hosttime': 1, 'random': 1, 'Iterations': 5L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,7ZIP,PPT,OUTLOOK,VIDEO'}

      2016-08-19 18:19:05,027 DEBUG Getting all the run profiles from the database.

      2016-08-19 18:19:05,027 INFO Running query: SELECT distinct name from runprofile

      2016-08-19 18:19:05,043 DEBUG Getting all the test names and their start times from the database.

      2016-08-19 18:19:05,043 INFO Running query: SELECT distinct start_time,test_name from testinfo order by start_time DESC

      2016-08-19 18:19:05,045 DEBUG Getting all the workload profiles from the database.

      2016-08-19 18:19:05,045 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 18:19:05,066 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 18:19:05,066 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 18:19:05,066 DEBUG key: VCuser, value: s25775d

      2016-08-19 18:19:05,066 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 18:19:05,067 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 18:19:05,068 DEBUG Getting all the workload profiles from the database.

      2016-08-19 18:19:05,068 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 18:19:05,070 DEBUG Returning View configuration parameters: {'protocol': 'RDP', 'Viewserver': 'dhcs0702v', 'Viewuser': 's25775d', 'adserver': '127.0.0.1', 'addomainname': 'dummy', 'Viewpasswd': '*******'}

      2016-08-19 18:19:05,092 DEBUG key: VCserver, value: 192.168.151.180

      2016-08-19 18:19:05,092 DEBUG key: datacenter, value: 801 Hills

      2016-08-19 18:19:05,092 DEBUG key: VCuser, value: s25775d

      2016-08-19 18:19:05,092 DEBUG key: VCpasswd, value: qlIRLM330226!

      2016-08-19 18:19:05,092 DEBUG Returning vCenter configuration parameters: {'VCserver': '192.168.151.180', 'datacenter': '801 Hills', 'VCuser': 's25775d', 'VCpasswd': '*******'}

      2016-08-19 18:19:05,098 DEBUG Getting all the workload profiles from the database.

      2016-08-19 18:19:05,098 INFO Running query: SELECT distinct name from workloadconfig

      2016-08-19 18:19:05,100 DEBUG Getting all the new custom applications by parsing the workload config file.

      2016-08-19 18:19:05,435 DEBUG Loading the run profile from the database: Single-VM-local-2

      2016-08-19 18:19:05,435 INFO Running query: SELECT distinct * from runprofile where name = "Single-VM-local-2"

      2016-08-19 18:19:05,436 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 3

      2016-08-19 18:19:05,437 INFO Running query: SELECT * from ADgroupprofile where grp_id = 1

      2016-08-19 18:19:05,438 DEBUG Returning profile: {'deskprefix': 'GoldenDesktop', 'rampuptime': 5L, 'run_id': 3L, 'extrainfo': None, 'numofvms': 1L, 'mode': 0L, 'adgroups': [{'schedlist': None, 'protocol': 0L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 1L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': ''}], 'desktype': 0L, 'name': 'Single-VM-local-2'}

      2016-08-19 18:19:05,438 INFO Running query: SELECT max(test_id),min(test_id) from testinfo where test_name = "Single-VM-local-2" and start_time = "2016-08-17 16:49:05"

      2016-08-19 18:19:05,439 INFO Running query: select count(distinct linfo_FK) from localresults where linfo_FK >= 1 and linfo_FK <= 1

      2016-08-19 18:19:05,441 INFO Running query: select count(distinct info_FK) from remoteresults where info_FK >= 1 and info_FK <= 1

      2016-08-19 18:19:05,441 INFO Running query: select count(distinct lerrinfo_FK) from localerrorinfo where lerrinfo_FK >= 1 and lerrinfo_FK <= 1

      2016-08-19 18:19:05,442 INFO Running query: select count(distinct errinfo_FK) from remoteerrorinfo where errinfo_FK >= 1 and errinfo_FK <= 1

      2016-08-19 18:19:05,443 DEBUG Test Status (name: Single-VM-local-2 start_time: 2016-08-17 16:49:05): 0 uploaded (0 errors)

      2016-08-19 18:19:05,444 DEBUG Getting the detailed information about the workload profile: StorageAppsProfile_5i

      2016-08-19 18:19:05,444 INFO Running query: SELECT distinct * from workloadconfig where name = "StorageAppsProfile_5i"

      2016-08-19 18:19:05,445 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StorageAppsProfile_5i', 'hosttime': 1, 'random': 1, 'Iterations': 5L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,7ZIP,PPT,OUTLOOK,VIDEO'}

      2016-08-19 18:24:16,202 INFO New client registration: 192.168.155.189

      2016-08-19 18:24:16,202 INFO Current registered machines status : [Pairs required: 5] [clients: 11] [servers: 5]

      2016-08-19 18:24:16,218 INFO New client registration: 192.168.155.190

      2016-08-19 18:24:16,218 INFO Current registered machines status : [Pairs required: 5] [clients: 12] [servers: 5]

      2016-08-19 18:24:16,310 INFO New client registration: 192.168.155.191

      2016-08-19 18:24:16,310 INFO Current registered machines status : [Pairs required: 5] [clients: 13] [servers: 5]

      2016-08-19 18:24:16,481 INFO New client registration: 192.168.155.192

      2016-08-19 18:24:16,481 INFO Current registered machines status : [Pairs required: 5] [clients: 14] [servers: 5]

      2016-08-19 18:24:16,623 INFO New client registration: 192.168.155.193

      2016-08-19 18:24:16,623 INFO Current registered machines status : [Pairs required: 5] [clients: 15] [servers: 5]

      2016-08-19 18:26:03,618 INFO New client registration: 192.168.155.189

      2016-08-19 18:26:06,389 INFO New client registration: 192.168.155.192

      2016-08-19 18:26:06,992 INFO New client registration: 192.168.155.191

      2016-08-19 18:26:33,616 INFO New client registration: 192.168.155.190

      2016-08-19 18:26:36,430 INFO New client registration: 192.168.155.193

      2016-08-19 18:29:03,189 INFO New client registration: 192.168.155.193

      2016-08-19 18:29:04,012 INFO New client registration: 192.168.155.189

      2016-08-19 18:29:05,955 INFO New client registration: 192.168.155.191

      2016-08-19 18:29:06,348 INFO New client registration: 192.168.155.192

      2016-08-19 18:29:30,534 INFO New client registration: 192.168.155.190

      2016-08-19 18:31:38,416 INFO New client registration: 192.168.155.190

      2016-08-19 18:55:46,769 INFO ============================================================

      2016-08-19 18:55:46,769 INFO Executing the run profile from the database: Multi-VM-remote-PCoIP

      2016-08-19 18:55:46,769 INFO ============================================================

      2016-08-19 18:55:46,769 INFO Running query: SELECT distinct * from runprofile where name = "Multi-VM-remote-PCoIP"

      2016-08-19 18:55:46,770 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 9

      2016-08-19 18:55:46,771 INFO Running query: SELECT * from ADgroupprofile where grp_id = 4

      2016-08-19 18:55:46,772 DEBUG Running profile Multi-VM-remote-PCoIP with parameters: {'deskprefix': 'dvpd', 'rampuptime': 70L, 'run_id': 9L, 'extrainfo': None, 'numofvms': 5L, 'mode': 2L, 'adgroups': [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 4L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dvpd'}], 'desktype': 0L, 'name': 'Multi-VM-remote-PCoIP'}

      2016-08-19 18:55:46,772 DEBUG Resetting all variables in shared_data.

      2016-08-19 18:55:46,772 DEBUG Resetting all variables in shared_data [Done].

      2016-08-19 18:55:46,773 INFO Setting the checkpoint index of upload/connections: 4

      2016-08-19 18:55:46,773 DEBUG Setting all past tests state to Done

      2016-08-19 18:55:46,773 INFO Running query: UPDATE testinfo SET test_state = "Done"

      2016-08-19 18:55:46,785 DEBUG Setting all hosts to busy

      2016-08-19 18:55:46,785 INFO Running query: UPDATE hostinfo SET state = "Old"

      2016-08-19 18:55:46,792 DEBUG Connecting to the View server agent.

      2016-08-19 18:55:46,793 INFO adding callback function for getting the remote root object for ipaddr: dhcs0702v

      2016-08-19 18:55:46,793 INFO Got View object from the View server.

      2016-08-19 18:55:46,800 WARNING ViewAPI not supported on this View server.

      2016-08-19 18:55:46,800 DEBUG Connecting to the AD agent

      2016-08-19 18:55:46,800 INFO Adding callback function for getting the remote root object for IP address: 127.0.0.1

      2016-08-19 18:55:46,801 INFO Got AD object from the AD server.

      2016-08-19 18:55:46,829 DEBUG Waiting for the current thread to terminate. IF it does not terminate in 10 seconds, the vdiappd service will be restarted.

      2016-08-19 18:55:46,830 DEBUG Stopping the runtest thread.

      2016-08-19 18:55:46,830 DEBUG Starting the runtest thread 2586

      2016-08-19 18:55:46,832 INFO Getting list of hosts from vCenter

      2016-08-19 18:55:46,929 INFO Getting all the datacenters from vCenter

      2016-08-19 18:55:46,971 INFO Getting all the compute resources from vCenter

      2016-08-19 18:55:47,024 INFO Getting all the clusters from vCenter

      2016-08-19 18:55:47,073 INFO Getting all the VMs from the vCenter.

      2016-08-19 18:55:47,756 INFO Getting all the VMs from the vCenter[done].

      2016-08-19 18:55:47,757 INFO AD group: {'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 4L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': 'dvpd'}

      2016-08-19 18:55:47,757 INFO Running query: SELECT distinct * from workloadconfig where name = "StandardBenchmarkProfile_1i"

      2016-08-19 18:55:47,758 INFO {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 18:55:47,758 INFO Making AD config file config-test.txt for the Test AD group desktops with workprofile: {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}

      2016-08-19 18:55:47,759 INFO Looking for 5 VMs with prefixes dvpd in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-b6d80771-530d-4c40-8475-812eae33fcd0', 'cp-parent-fe8e9a02-7a31-4bb7-b47e-1b99e3de0ffa', 'cp-replica-dd7fa8f8-a153-43c6-a395-9a8d5f514819', 'cp-template-0a36f734-dac1-48de-94a1-e9c5ce7e41c1', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdtjm001v', 'dvdtjm003v', 'dvdtjm005v', 'dvpd01v', 'dvpd02v', 'dvpd03v', 'dvpd04v', 'dvpd05v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'replica-8ead22ba-9c4d-43d9-9fe4-90043f580c87', 'template-win2k12R2-std-vl']

      2016-08-19 18:55:47,759 INFO Matching VM Base_Template_Win7Pro64 with prefix: dvpd

      2016-08-19 18:55:47,759 INFO Matching VM GI-Win7x64 with prefix: dvpd

      2016-08-19 18:55:47,759 INFO Matching VM GoldenClient with prefix: dvpd

      2016-08-19 18:55:47,759 INFO Matching VM GoldenDesktop with prefix: dvpd

      2016-08-19 18:55:47,759 INFO Matching VM W7x64-Clean with prefix: dvpd

      2016-08-19 18:55:47,759 INFO Matching VM cp-parent-b6d80771-530d-4c40-8475-812eae33fcd0 with prefix: dvpd

      2016-08-19 18:55:47,760 INFO Matching VM cp-parent-fe8e9a02-7a31-4bb7-b47e-1b99e3de0ffa with prefix: dvpd

      2016-08-19 18:55:47,760 INFO Matching VM cp-replica-dd7fa8f8-a153-43c6-a395-9a8d5f514819 with prefix: dvpd

      2016-08-19 18:55:47,760 INFO Matching VM cp-template-0a36f734-dac1-48de-94a1-e9c5ce7e41c1 with prefix: dvpd

      2016-08-19 18:55:47,760 INFO Matching VM dappvolprov02 with prefix: dvpd

      2016-08-19 18:55:47,760 INFO Matching VM dcmp0701v with prefix: dvpd

      2016-08-19 18:55:47,760 INFO Matching VM dcvp0703v with prefix: dvpd

      2016-08-19 18:55:47,760 INFO Matching VM dcvp0704v with prefix: dvpd

      2016-08-19 18:55:47,760 INFO Matching VM dhcs0701v with prefix: dvpd

      2016-08-19 18:55:47,760 INFO Matching VM dhcs0702v with prefix: dvpd

      2016-08-19 18:55:47,761 INFO Matching VM dhdb0341v with prefix: dvpd

      2016-08-19 18:55:47,761 INFO Matching VM dhds99001v with prefix: dvpd

      2016-08-19 18:55:47,761 INFO Matching VM dhds99002v with prefix: dvpd

      2016-08-19 18:55:47,761 INFO Matching VM dhvp0701v with prefix: dvpd

      2016-08-19 18:55:47,761 INFO Matching VM dpsc0702v with prefix: dvpd

      2016-08-19 18:55:47,761 INFO Matching VM dsrm0401v with prefix: dvpd

      2016-08-19 18:55:47,761 INFO Matching VM dsrm0702v with prefix: dvpd

      2016-08-19 18:55:47,761 INFO Matching VM dtds0704v with prefix: dvpd

      2016-08-19 18:55:47,761 INFO Matching VM dtst0701v with prefix: dvpd

      2016-08-19 18:55:47,762 INFO Matching VM dtst0702v with prefix: dvpd

      2016-08-19 18:55:47,762 INFO Matching VM dvav0701v with prefix: dvpd

      2016-08-19 18:55:47,762 INFO Matching VM dvav0702v with prefix: dvpd

      2016-08-19 18:55:47,762 INFO Matching VM dvcr0702v with prefix: dvpd

      2016-08-19 18:55:47,762 INFO Matching VM dvdt01v with prefix: dvpd

      2016-08-19 18:55:47,762 INFO Matching VM dvdt02v with prefix: dvpd

      2016-08-19 18:55:47,762 INFO Matching VM dvdt03v with prefix: dvpd

      2016-08-19 18:55:47,762 INFO Matching VM dvdtjm001v with prefix: dvpd

      2016-08-19 18:55:47,762 INFO Matching VM dvdtjm003v with prefix: dvpd

      2016-08-19 18:55:47,763 INFO Matching VM dvdtjm005v with prefix: dvpd

      2016-08-19 18:55:47,763 INFO Matching VM dvpd01v with prefix: dvpd

      2016-08-19 18:55:47,763 INFO VM dvpd01v matched with prefix: dvpd

      2016-08-19 18:55:47,763 INFO Matching VM dvpd02v with prefix: dvpd

      2016-08-19 18:55:47,763 INFO VM dvpd02v matched with prefix: dvpd

      2016-08-19 18:55:47,763 INFO Matching VM dvpd03v with prefix: dvpd

      2016-08-19 18:55:47,763 INFO VM dvpd03v matched with prefix: dvpd

      2016-08-19 18:55:47,763 INFO Matching VM dvpd04v with prefix: dvpd

      2016-08-19 18:55:47,763 INFO VM dvpd04v matched with prefix: dvpd

      2016-08-19 18:55:47,763 INFO Matching VM dvpd05v with prefix: dvpd

      2016-08-19 18:55:47,764 INFO VM dvpd05v matched with prefix: dvpd

      2016-08-19 18:55:47,764 INFO Desktop VMs found: ['dvpd01v', 'dvpd02v', 'dvpd03v', 'dvpd04v', 'dvpd05v']

      2016-08-19 18:55:47,764 INFO Looking for 5 VMs with prefixes dvpd in the VMs list: ['Base_Template_Win7Pro64', 'GI-Win7x64', 'GoldenClient', 'GoldenDesktop', 'W7x64-Clean', 'cp-parent-b6d80771-530d-4c40-8475-812eae33fcd0', 'cp-parent-fe8e9a02-7a31-4bb7-b47e-1b99e3de0ffa', 'cp-replica-dd7fa8f8-a153-43c6-a395-9a8d5f514819', 'cp-template-0a36f734-dac1-48de-94a1-e9c5ce7e41c1', 'dappvolprov02', 'dcmp0701v', 'dcvp0703v', 'dcvp0704v', 'dhcs0701v', 'dhcs0702v', 'dhdb0341v', 'dhds99001v', 'dhds99002v', 'dhvp0701v', 'dpsc0702v', 'dsrm0401v', 'dsrm0702v', 'dtds0704v', 'dtst0701v', 'dtst0702v', 'dvav0701v', 'dvav0702v', 'dvcr0702v', 'dvdt01v', 'dvdt02v', 'dvdt03v', 'dvdtjm001v', 'dvdtjm003v', 'dvdtjm005v', 'dvsm0704v', 'goldImage-xdadm-VDI-Team', 'replica-8ead22ba-9c4d-43d9-9fe4-90043f580c87', 'template-win2k12R2-std-vl']

      2016-08-19 18:55:47,764 INFO Matching VM Base_Template_Win7Pro64 with prefix: dvpd

      2016-08-19 18:55:47,764 INFO Matching VM GI-Win7x64 with prefix: dvpd

      2016-08-19 18:55:47,764 INFO Matching VM GoldenClient with prefix: dvpd

      2016-08-19 18:55:47,764 INFO Matching VM GoldenDesktop with prefix: dvpd

      2016-08-19 18:55:47,764 INFO Matching VM W7x64-Clean with prefix: dvpd

      2016-08-19 18:55:47,765 INFO Matching VM cp-parent-b6d80771-530d-4c40-8475-812eae33fcd0 with prefix: dvpd

      2016-08-19 18:55:47,765 INFO Matching VM cp-parent-fe8e9a02-7a31-4bb7-b47e-1b99e3de0ffa with prefix: dvpd

      2016-08-19 18:55:47,765 INFO Matching VM cp-replica-dd7fa8f8-a153-43c6-a395-9a8d5f514819 with prefix: dvpd

      2016-08-19 18:55:47,765 INFO Matching VM cp-template-0a36f734-dac1-48de-94a1-e9c5ce7e41c1 with prefix: dvpd

      2016-08-19 18:55:47,765 INFO Matching VM dappvolprov02 with prefix: dvpd

      2016-08-19 18:55:47,765 INFO Matching VM dcmp0701v with prefix: dvpd

      2016-08-19 18:55:47,765 INFO Matching VM dcvp0703v with prefix: dvpd

      2016-08-19 18:55:47,765 INFO Matching VM dcvp0704v with prefix: dvpd

      2016-08-19 18:55:47,765 INFO Matching VM dhcs0701v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dhcs0702v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dhdb0341v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dhds99001v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dhds99002v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dhvp0701v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dpsc0702v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dsrm0401v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dsrm0702v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dtds0704v with prefix: dvpd

      2016-08-19 18:55:47,766 INFO Matching VM dtst0701v with prefix: dvpd

      2016-08-19 18:55:47,767 INFO Matching VM dtst0702v with prefix: dvpd

      2016-08-19 18:55:47,767 INFO Matching VM dvav0701v with prefix: dvpd

      2016-08-19 18:55:47,767 INFO Matching VM dvav0702v with prefix: dvpd

      2016-08-19 18:55:47,767 INFO Matching VM dvcr0702v with prefix: dvpd

      2016-08-19 18:55:47,767 INFO Matching VM dvdt01v with prefix: dvpd

      2016-08-19 18:55:47,767 INFO Matching VM dvdt02v with prefix: dvpd

      2016-08-19 18:55:47,767 INFO Matching VM dvdt03v with prefix: dvpd

      2016-08-19 18:55:47,767 INFO Matching VM dvdtjm001v with prefix: dvpd

      2016-08-19 18:55:47,767 INFO Matching VM dvdtjm003v with prefix: dvpd

      2016-08-19 18:55:47,768 INFO Matching VM dvdtjm005v with prefix: dvpd

      2016-08-19 18:55:47,768 INFO Matching VM dvsm0704v with prefix: dvpd

      2016-08-19 18:55:47,768 INFO Matching VM goldImage-xdadm-VDI-Team with prefix: dvpd

      2016-08-19 18:55:47,768 INFO Matching VM replica-8ead22ba-9c4d-43d9-9fe4-90043f580c87 with prefix: dvpd

      2016-08-19 18:55:47,768 INFO Matching VM template-win2k12R2-std-vl with prefix: dvpd

      2016-08-19 18:55:47,768 WARNING Not enough VMs found based on the prefixes: dvpd

      2016-08-19 18:55:47,768 INFO Client VMs found: []

      2016-08-19 18:55:47,768 WARNING There were not enough clients found: (expected: 5, found: 0)

      2016-08-19 18:55:47,769 INFO Loading AD Info to the data structures is done with adinfo: [{'schedlist': None, 'protocol': 2L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 4L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'deskvms': ['dvpd01v', 'dvpd02v', 'dvpd03v', 'dvpd04v', 'dvpd05v'], 'rdshtype': 0L, 'clientvms': [], 'workprofile': {'IEbpm': 1L, 'ARbpm': 1L, 'Thinktime': 2L, 'extrainfo': None, 'name': 'StandardBenchmarkProfile_1i', 'hosttime': 1, 'random': 1, 'Iterations': 1L, 'wpm': 60L, 'video': 2L, 'applist': 'WORD,EXCEL_SORT,ACROBAT,IE_APACHEDOC,7ZIP,PPT,FIREFOX,OUTLOOK,IE_WEBALBUM,VIDEO'}, 'clientprefix': 'dvpd'}]

      2016-08-19 18:55:47,777 DEBUG Getting the configuration option monitor_control.pseudo_perfctr in VM dvpd01v

      2016-08-19 18:55:47,824 INFO The value of performance counter for VM dvpd01v: 1

      2016-08-19 18:55:47,824 INFO Powering on 5 desktop VMs

      2016-08-19 18:55:47,824 INFO Powering on VMs in Test AD group: ['dvpd01v', 'dvpd02v', 'dvpd03v', 'dvpd04v', 'dvpd05v']

      2016-08-19 18:55:47,824 DEBUG Powering on the VM: dvpd01v

      2016-08-19 18:55:48,796 DEBUG Powering on the VM: dvpd02v

      2016-08-19 18:55:49,752 DEBUG Powering on the VM: dvpd03v

      2016-08-19 18:55:49,760 DEBUG VM dvpd03v is powered on. Rebooting the VM...

      2016-08-19 18:55:50,842 DEBUG Powering on the VM: dvpd04v

      2016-08-19 18:55:51,802 DEBUG Returning the current status: 0 with message Status: [Powering On --> Desktops: 4, Clients: 0 ]

      2016-08-19 18:55:51,808 DEBUG Powering on the VM: dvpd05v

      2016-08-19 18:55:54,001 INFO Powering on 5 client VMs

      2016-08-19 18:55:54,001 INFO Powering on VMs in Test AD group: []

      2016-08-19 18:55:54,002 INFO Operation: poweron_desktops, Time of Start: Fri Aug 19 18:55:47 2016, Total time taken: 15.35

      2016-08-19 18:55:54,002 INFO Operation: poweron_clients, Time of Start: Fri Aug 19 18:55:54 2016, Total time taken: 7.55

      2016-08-19 18:55:54,002 DEBUG Creating 5 users and adding to the AD security group: Test

      2016-08-19 18:55:54,002 INFO ------------------------------------------------------------

      Peer will receive following PB traceback:

      2016-08-19 18:55:54,003 INFO Waiting for the threshold number of VMs to register

      Unhandled Error

      Traceback (most recent call last):

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 153, in gotItem

        self.callExpressionReceived(item)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 116, in callExpressionReceived

        self.expressionReceived(obj)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 515, in expressionReceived

        method(*sexp[1:])

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 827, in proto_message

        self._recvMessage(self.localObjectForID, requestID, objectID, message, answerRequired, netArgs, netKw)

      --- <exception caught here> ---

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 841, in _recvMessage

        netResult = object.remoteMessageReceived(self, message, netArgs, netKw)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/flavors.py", line 112, in remoteMessageReceived

        raise NoSuchMethod("No such method: remote_%s" % (message,))

      twisted.spread.flavors.NoSuchMethod: No such method: remote_addUsers

      Peer will receive following PB traceback:

      Unhandled Error

      Traceback (most recent call last):

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 153, in gotItem

        self.callExpressionReceived(item)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/banana.py", line 116, in callExpressionReceived

        self.expressionReceived(obj)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 515, in expressionReceived

        method(*sexp[1:])

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 827, in proto_message

        self._recvMessage(self.localObjectForID, requestID, objectID, message, answerRequired, netArgs, netKw)

      --- <exception caught here> ---

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/pb.py", line 841, in _recvMessage

        netResult = object.remoteMessageReceived(self, message, netArgs, netKw)

        File "/usr/local/lib64/python2.6/site-packages/Twisted-11.0.0-py2.6-linux-x86_64.egg/twisted/spread/flavors.py", line 112, in remoteMessageReceived

        raise NoSuchMethod("No such method: remote_%s" % (message,))

      twisted.spread.flavors.NoSuchMethod: No such method: remote_findNetbiosDomainName

      2016-08-19 18:55:54,005 INFO ------------------------------------------------------------

      Unhandled error in Deferred:

      Unhandled Error

      Traceback from remote host -- Traceback unavailable

      Unhandled error in Deferred:

      Unhandled Error

      Traceback from remote host -- Traceback unavailable

      2016-08-19 18:55:56,774 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 0 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 18:56:08,250 INFO New client registration: 192.168.155.192

      2016-08-19 18:56:08,251 INFO Current registered machines status : [Pairs required: 5] [clients: 1] [servers: 0]

      2016-08-19 18:56:08,310 INFO New client registration: 192.168.155.191

      2016-08-19 18:56:08,310 INFO Current registered machines status : [Pairs required: 5] [clients: 2] [servers: 0]

      2016-08-19 18:56:11,781 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 2 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 18:56:13,749 INFO New client registration: 192.168.155.189

      2016-08-19 18:56:13,749 INFO Current registered machines status : [Pairs required: 5] [clients: 3] [servers: 0]

      2016-08-19 18:56:13,868 INFO New client registration: 192.168.155.193

      2016-08-19 18:56:13,868 INFO Current registered machines status : [Pairs required: 5] [clients: 4] [servers: 0]

      2016-08-19 18:56:13,945 INFO New client registration: 192.168.155.190

      2016-08-19 18:56:13,946 INFO Current registered machines status : [Pairs required: 5] [clients: 5] [servers: 0]

      2016-08-19 18:56:16,782 DEBUG Returning the current status: 0 with message Status: [IP Registered: Desktops: 0, Clients: 5 ] [Workload Started on 0 machines ] [ Uploaded results --> Desktops: 0 (0 errors), Clients: 0 ]

      2016-08-19 18:59:13,946 INFO Scanning all the VMs for any required fixes.

      2016-08-19 18:59:13,946 INFO Getting the IP address for VM dvpd01v from vCenter

      2016-08-19 18:59:13,972 INFO Got the IP address for VM dvpd01v: 192.168.155.192

      2016-08-19 18:59:13,973 INFO Getting the IP address for VM dvpd02v from vCenter

      2016-08-19 18:59:13,993 INFO Got the IP address for VM dvpd02v: 192.168.155.191

      2016-08-19 18:59:13,994 INFO Getting the IP address for VM dvpd03v from vCenter

      2016-08-19 18:59:14,028 INFO Got the IP address for VM dvpd03v: 192.168.155.190

      2016-08-19 18:59:14,028 INFO Getting the IP address for VM dvpd04v from vCenter

      2016-08-19 18:59:14,059 INFO Got the IP address for VM dvpd04v: 192.168.155.193

      2016-08-19 18:59:14,059 INFO Getting the IP address for VM dvpd05v from vCenter

      2016-08-19 18:59:14,077 INFO Got the IP address for VM dvpd05v: 192.168.155.189

      2016-08-19 18:59:14,077 INFO Done scanning VMs.

      2016-08-19 19:02:14,078 INFO Scanning all the VMs for any required fixes.

      2016-08-19 19:02:14,078 INFO Done scanning VMs.

      2016-08-19 19:27:09,917 DEBUG ======================================= [START] Registration Status Dump ========================================

      2016-08-19 19:27:12,556 INFO Desktop status: VM(dvpd01v) IP(192.168.155.192) Not Registered

      VM(dvpd02v) IP(192.168.155.191) Not Registered

      VM(dvpd03v) IP(192.168.155.190) Not Registered

      VM(dvpd04v) IP(192.168.155.193) Not Registered

      VM(dvpd05v) IP(192.168.155.189) Not Registered

       

      2016-08-19 19:27:12,556 INFO Clients status:

       

      2016-08-19 19:27:12,556 DEBUG ======================================= [END] Registration Status Dump ========================================

        • 1. Re: View Planner 3.6 VMs will not Register IPs
          Atul_ Enthusiast
          VMware Employees

          Hi,

           

          can you navigate to "config" tab, and test Vcenter, AD and view connection, and check if communication is working properly?

           

          Atul

          • 2. Re: View Planner 3.6 VMs will not Register IPs
            gcopeny Novice

            Yes the View Connection has been working fine. I was having issues with using the loop back and "Dummy" AD credentials. Whenever I clicked on Test AD I have had to reboot my View Planner server. It seems to freeze up the system some how. I am saving actual AD server information now. Will see if this helps.

             

            Please note the virtual machines have the DOS box up with the following after performing the test run:

             

            C:\Windows\system32\REM The View Planner Agent service has started. Please check the c:\viewplanner-clientagent.txt for the progress of the run.

             

            C:\Windows\system32\python c:\interactive_interface.pyc

            Starting the interactive listener

            Starting the interactive listener...registered.

             

            All of them say this and they all just sit at the cursor blinking at this point. Nothing else ever happens to the CMD box. One of them has been sitting on that screen since my last run on Friday..

            • 3. Re: View Planner 3.6 VMs will not Register IPs
              gcopeny Novice

              I do not have the View Planner Agent on the AD domain controller. I am not sure I'll be allowed to do that actually which is why I went with the "Dummy" AD information. Should that not work in this case? Is there something I can do to get the loop back and Dummy information to function properly. That is very likely where my issue is.

              • 4. Re: View Planner 3.6 VMs will not Register IPs
                Atul_ Enthusiast
                VMware Employees

                you can get steps to use dummy AD in "ViewPlannerUsersGuide3.6.0.pdf" page 104.

                you can download user guide from https://my.vmware.com/en/group/vmware/details?downloadGroup=VIEW-PLAN-360&productId=320 

                • 5. Re: View Planner 3.6 VMs will not Register IPs
                  gcopeny Novice

                  Thank you Atul,

                   

                  This solution however still requires that the Agent be installed on the AD server. In this case I would not be using the dummy account. Currently after changing the settings in AdminOps.cfg I'm still having the same issue. Seems like the loop back is still getting hung up somewhere.

                  • 6. Re: View Planner 3.6 VMs will not Register IPs
                    Atul_ Enthusiast
                    VMware Employees

                    Installing agent in AD server is not required with this solution, only required users needed to be created manually. alternatively you can use "createUsers.ps1" script provided with the agent installer.

                    Here is the last point from the user guide page 104:

                     

                    "Once the above steps are complete, you must manually create the users on the AD server. The AD agent

                    package includes a PowerShell script (CreateUsers.ps1) for this purpose. Use this script to create the

                    AD group and the number of users you need for the run (run CreateUsers.ps1 -h for help).

                    You should then use this AD group name when creating run profiles in the View Planner web UI."

                    • 7. Re: View Planner 3.6 VMs will not Register IPs
                      gcopeny Novice

                      Yes I saw that. I suppose I misunderstood "The AD agent package includes.." as it seemed to me that the AD agent would then need to be installed on the AD server. I will try to get through some more of the documentation to see what else I may be missing. I had not gotten this far in the instructions yet. Some of this stuff is grossly out of order.

                       

                      At present changing those settings did not help but I have not run the script yet. Seems like the connection should work now if it's going to work regardless of the group being set up.

                       

                      Thanks again for assisting here.

                      • 8. Re: View Planner 3.6 VMs will not Register IPs
                        gcopeny Novice

                        Hello again Atul,

                         

                        I must be missing a part of the instructions. What IP Address/Name should I be using? The loop back with a group name in AD that I created is not working at all. That 127.0.0.1 address still hangs the entire View Planner system. Using the IP Address of the AD server fails looking for the agent. I must be skipping that part in the instructions where it connects properly using the steps listed above. Where should I be installing the AD Agent so that Planner can make the connection if not on an AD server?

                        • 9. Re: View Planner 3.6 VMs will not Register IPs
                          Atul_ Enthusiast
                          VMware Employees

                          Hi,

                           

                          - You do not need to install AD agent anywhere, if you can't install it in on AD machine.

                          - Follow steps on user guide page 104, and in viewplanner webUI config tab provide AD ip as "127.0.0.1" and domain as "dummy".

                          - After creating AD group and users manually using steps in page 104, make sure your desktop pool has entitlement to use that group.

                          This should be done automatically by viewplanner when a run is started, but you can do it manually too, just to make sure.

                          - Once above is done you can try to login manually on this pool using a client and newly created user. if this works then viewplanner run should also work without any issue.

                          - Do not forget to provide same AD group name you created manually to viewplanner run profile.

                          - If this does not work, let me know with logs of AD agent and harness.

                          • 10. Re: View Planner 3.6 VMs will not Register IPs
                            gcopeny Novice

                            Hello Atul,

                             

                            The steps on page 104 seem incomplete. The script does not run on the server I have the AD and Server agents installed on. Unfortunately in our environment I do not have rights, nor does the account on the server, to create users and groups in AD. I am working on finding a solution to that so I can manually create a group and users in AD and eventually modify the script.

                             

                            I also tried to run a single-local-run yesterday and it failed trying to connect to workload looking for User: Test_1. There is no reference to this in the documentation. I am created Test_1 on the GoldenDesktop vm I am attempting to run this on and gave it Admin rights but that did not seem to help. Here is part of the log for that.

                             

                            2016-08-25 14:40:54,155 INFO ------------------------------------------------------------

                            2016-08-25 14:40:54,155 INFO Initiating the execute plan for the run profile

                            2016-08-25 14:40:54,155 INFO ------------------------------------------------------------

                            2016-08-25 14:40:54,156 INFO Starting the local workload for the desktop VMs.

                            2016-08-25 14:40:54,156 INFO Unmapping data folders to default on VM 192.168.155.198

                            2016-08-25 14:40:54,156 INFO Running query: SELECT * from testinfo where ServerIP_FK = "192.168.155.198" and test_state = "waiting"

                            2016-08-25 14:40:54,157 INFO The server test id is 5

                            2016-08-25 14:40:54,158 INFO Running query: UPDATE hostinfo SET state = "Busy" where IPADDR = "192.168.155.198"

                            2016-08-25 14:40:54,182 INFO Running query: UPDATE testinfo SET test_name = "Single-VM-local-2", protocol = "NA", username = "Test_1", password = "passw0rd", test_state = "Running", start_time = "2016-08-25 14:40:47" where test_id = 5

                            2016-08-25 14:40:54,184 INFO Pushing workload configuration to the VM: 192.168.155.198

                            2016-08-25 14:40:54,185 INFO Executing remote command with arguments: server: 192.168.155.198, test_name: Single-VM-local-2

                            2016-08-25 14:40:54,185 INFO Adding callback function for getting the remote root object for IP address: 192.168.155.198

                            2016-08-25 14:40:54,301 INFO Adding callback for startWorkload command on the server 192.168.155.198

                            2016-08-25 14:40:54,302 INFO Data folder unmapped for 192.168.155.198

                            2016-08-25 14:40:58,322 INFO Workload configuration sent for group: Test

                            2016-08-25 14:40:58,323 INFO Workload start command sent.

                            2016-08-25 14:42:30,886 INFO ------------------------------------------------------------

                            2016-08-25 14:42:30,886 INFO The following users have not connected from their clients:

                            2016-08-25 14:42:30,886 INFO ------------------------------------------------------------

                            2016-08-25 14:42:30,886 INFO User: Test_1 -> Not connected/started

                            2016-08-25 14:55:00,079 DEBUG Getting all the workload profiles from the database.

                            2016-08-25 14:55:00,079 INFO Running query: SELECT distinct name from workloadconfig

                            2016-08-25 14:55:00,082 DEBUG Loading the run profile from the database: Single-VM-local-2

                            2016-08-25 14:55:00,082 INFO Running query: SELECT distinct * from runprofile where name = "Single-VM-local-2"

                            2016-08-25 14:55:00,083 INFO Running query: SELECT distinct grp_FK from runpivot where run_FK = 3

                            2016-08-25 14:55:00,084 INFO Running query: SELECT * from ADgroupprofile where grp_id = 1

                            2016-08-25 14:55:00,084 DEBUG Returning profile: {'deskprefix': 'GoldenDesktop', 'rampuptime': 5L, 'run_id': 3L, 'extrainfo': None, 'numofvms': 1L, 'mode': 0L, 'adgroups': [{'schedlist': None, 'protocol': 0L, 'name': 'Test', 'wrk_FK': 'StandardBenchmarkProfile_1i', 'poolname': '', 'grp_id': 1L, 'extrainfo': None, 'percent': 100L, 'connections_per_client': 1L, 'rdshtype': 0L, 'clientprefix': ''}], 'desktype': 0L, 'name': 'Single-VM-local-2'}

                             

                            I am unclear as to how View Planner actually ties into AD, the GoldenClient and GoldenDesktop machines. The documentation does not really connect those dots very well unfortunately. I have read and reread the sections you mentioned above and I believe it all assumes you have an account that has domain admin privileges and can run the script without error. Some clarity on how all of the user functions are tied in would be great.

                             

                            Thanks again for all of your help thus far.

                            • 11. Re: View Planner 3.6 VMs will not Register IPs
                              Atul_ Enthusiast
                              VMware Employees

                              Hi,

                              1- Viewplanner do not need AD for local mode test.  try "service vdiappd restart" on harness and re-run the test.  Check and make sure that autologon is enabled in your desktop, normally Viewplanner does that automatically.

                               

                              2- Viewplanner use AD for remote and passive mode test. Here is some clarification regarding that:

                                   - Viewplanner creates some user accounts under the group name you provide while creating a run profile.

                                        for example if user provides group  name as "Test" in "AD group settings" while creating a new Run profile, then VP will create this group in AD and then create users in this group as Test_1... Test_N.  as per number of desktops to be tested in a remote mode test.

                                 - After creating users VP will entitle this newly created group to the desktop pool created for remote test, based on desktop prefix (only one pool is supported). For this all desktops must be added to AD domain already.

                                -  Now VP uses these users to log-in to Horizon view pool and get a remote desktop while testing.

                              Hopes this helps. additionally you can head to vmware hands on lab and run VP in a controlled  environment to get yourself familiar.

                               

                              For getting hands on lab experience:

                              • Go to  http://labs.hol.vmware.com/
                              • On the left side in “ALL LABS” menu select “Focus: Performance”
                              • Enroll for lab “HOL-MBL-1651 Advanced Technical Concepts of Horizon 6 from A to Z”.
                              • After starting the lab, Click on “Table of contents” in right top of manual.
                              • Select “Lab Overview” and follow steps  to get yourself familiarized with environment.
                              • After finishing the Overview Jump to module 10 “Sizing VDI and RDSH deployments using View Planner 3.5”  and follow steps for the tutorial.

                               

                              Here is the link of lab manual for your reference (Check module 10 Sizing VDI and RDSH deployments using View Planner 3.5 ):

                              http://docs.hol.pub/HOL-2016/hol-mbl-1651_pdf_en.pdf