Hello, I have made both changes and restarted vcld
Here is a log of one of the VM's that are stilling timing out after 15 minutes. |21690|29445:29181|new| VCL Admin, |21690|29445:29181|new| The resources for your VCL request have been successfully reserved. |21690|29445:29181|new| Reservation Information: |21690|29445:29181|new| Image Name: CSUEB SPSS PASW Statistics & Amos 18 |21690|29445:29181|new| IP Address: 137.145.57.81 |21690|29445:29181|new| Connection will not be allowed until you acknowledge using the VCL web interface. You must acknowledge the reservation within the next 15 minutes or the resources will be reclaimed for other VCL users. |21690|29445:29181|new| -Visit http://cwiki.apache.org/VCL |21690|29445:29181|new| -Select "Current Reservations" |21690|29445:29181|new| -Click the "Connect" button |21690|29445:29181|new| Upon acknowledgement, all of the remaining connection details will be displayed. |21690|29445:29181|new| Thank You, |21690|29445:29181|new| VCL Team |21690|29445:29181|new| ****************************************************************** |21690|29445:29181|new| This is an automated notice. If you need assistance please respond |21690|29445:29181|new| with detailed information on the issue and a help ticket will be |21690|29445:29181|new| generated. |21690|29445:29181|new| To disable email notices |21690|29445:29181|new| -Visit http://cwiki.apache.org/VCL |21690|29445:29181|new| -Select User Preferences |21690|29445:29181|new| -Select General Preferences |21690|29445:29181|new| ****************************************************************** 2010-11-30 09:13:44|21690|29445:29181|new|new.pm:reserve_computer(1170)|IMTYPE_name= none calling notify_via 2010-11-30 09:13:44|21690|29445:29181|new|new.pm:reserve_computer(1240)|updated sublog 9483 for node vmguest-92 IPaddress 137.145.57.81 2010-11-30 09:13:44|21690|29445:29181|new|new.pm:process(381)|vmguest-92 with vmwarewinxp-SPSSPASWStatisticsAmos1844-v4 successfully reserved 2010-11-30 09:13:44|21690|29445:29181|new|utils.pm:insertloadlog(4710)|inserted computer=95, reserved, vmguest-92 successfully reserved with vmwarewinxp-SPSSPASWStatisticsAmos1844-v4 2010-11-30 09:13:44|21690|29445:29181|new|new.pm:process(393)|inserted computerloadlog entry, load state=reserved 2010-11-30 09:13:44|21690|29445:29181|new|utils.pm:update_computer_state(2228)|computer 95 state updated to: reserved 2010-11-30 09:13:44|21690|29445:29181|new|new.pm:process(417)|vmguest-92 state set to 'reserved' 2010-11-30 09:13:44|21690|29445:29181|new|utils.pm:update_request_state(2186)|request 29445 state updated to: reserved, laststate to: new 2010-11-30 09:13:44|21690|29445:29181|new|new.pm:process(426)|request state set to 'reserved'/'new' 2010-11-30 09:13:44|21690|29445:29181|new|utils.pm:insertloadlog(4710)|inserted computer=95, info, vmguest-92 successfully set to reserved with vmwarewinxp-SPSSPASWStatisticsAmos1844-v4 2010-11-30 09:13:44|21690|29445:29181|new|new.pm:process(439)|inserted computerloadlog entry: vmguest-92 successfully set to reserved with vmwarewinxp-SPSSPASWStatisticsAmos1844-v4 2010-11-30 09:13:44|21690|29445:29181|new|new.pm:process(446)|exiting 2010-11-30 09:13:44|21690|29445:29181|new|State.pm:DESTROY(905)|destructor called, ref($self)=VCL::new 2010-11-30 09:13:44|21690|29445:29181|new|utils.pm:delete_computerloadlog_reservation(7551)|removing computerloadlog entries matching loadstate = begin 2010-11-30 09:13:44|21690|29445:29181|new|utils.pm:delete_computerloadlog_reservation(7598)|deleted rows from computerloadlog for reservation id=29181 2010-11-30 09:13:44|21690|29445:29181|new|State.pm:DESTROY(912)|removed computerloadlog rows with loadstate=begin for reservation 2010-11-30 09:13:44|21690|29445:29181|new|State.pm:DESTROY(924)|number of database handles state process created: 1 2010-11-30 09:13:44|21690|29445:29181|new|State.pm:DESTROY(933)|process has a database handle stored in $ENV{dbh}, attempting disconnect 2010-11-30 09:13:44|21690|29445:29181|new|State.pm:DESTROY(935)|$ENV{dbh}: database disconnect successful 2010-11-30 09:13:44|21690|29445:29181|new|State.pm:DESTROY(949)|VCL::new process 21690 exiting 2010-11-30 09:13:44|31869|29445:29181|reserved|utils.pm:reservation_being_processed(9634)|computerloadlog 'begin' entry does NOT exist for reservation 29181 2010-11-30 09:13:44|31869|29445:29181|reserved|utils.pm:reservation_being_processed(9686)|reservation is NOT currently being processed 2010-11-30 09:13:44|31869|29445:29181|reserved|vcld:main(276)|reservation 29181 is NOT already being processed 2010-11-30 09:13:44|31869|29445:29181|reserved|utils.pm:get_request_info(5354)|standalone affiliation found: Local 2010-11-30 09:13:44|31869|29445:29181|reserved|utils.pm:get_management_node_info(6815)|management node info retrieved from database for vclnfs01 2010-11-30 09:13:44|31869|29445:29181|reserved|vcld:main(281)|retrieved request information from database 2010-11-30 09:13:44|31869|29445:29181|reserved|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 29181 2010-11-30 09:13:44|31869|29445:29181|reserved|utils.pm:update_request_state(2186)|request 29445 state updated to: pending, laststate to: reserved 2010-11-30 09:13:44|31869|29445:29181|reserved|utils.pm:insertloadlog(4710)|inserted computer=95, begin, beginning to process, state is reserved 2010-11-30 09:13:44|31869|29445:29181|reserved|vcld:make_new_child(509)|loaded VCL::reserved module 2010-11-30 09:13:44|31869|29445:29181|reserved|vcld:make_new_child(566)|current number of forked kids: 3 2010-11-30 09:13:44|21763|29445:29181|reserved|vcld:make_new_child(582)|vcld environment variable set to 0 for this process 2010-11-30 09:13:44|21763|29445:29181|reserved|Module.pm:new(132)|constructor called, class=VCL::reserved 2010-11-30 09:13:44|21763|29445:29181|reserved|Module.pm:new(154)|VCL::reserved object created 2010-11-30 09:13:44|21763|29445:29181|reserved|State.pm:initialize(85)|obtained a database handle for this state process, stored as $ENV{dbh} 2010-11-30 09:13:44|21763|29445:29181|reserved|State.pm:check_image_os(820)|no corrections need to be made, not an imaging request, returning 1 2010-11-30 09:13:44|21763|29445:29181|reserved|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 29181 2010-11-30 09:13:44|21763|29445:29181|reserved|utils.pm:rename_vcld_process(7863)|reservation count: 1 2010-11-30 09:13:44|21763|29445:29181|reserved|utils.pm:rename_vcld_process(7882)|PARENTIMAGE: 1 2010-11-30 09:13:44|21763|29445:29181|reserved|utils.pm:rename_vcld_process(7883)|SUBIMAGE: 0 2010-11-30 09:13:44|21763|29445:29181|reserved|utils.pm:rename_vcld_process(7901)|renamed process to 'vcld VCL::reserved 29445:29181 reserved' 2010-11-30 09:13:44|21763|29445:29181|reserved|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 29181 2010-11-30 09:13:44|21763|29445:29181|reserved|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 29181 2010-11-30 09:13:44|21763|29445:29181|reserved|State.pm:initialize(121)|attempting to load provisioning module: VCL::Module::Provisioning::vmware 2010-11-30 09:13:44|21763|29445:29181|reserved|State.pm:initialize(127)|VCL::Module::Provisioning::vmware module loaded 2010-11-30 09:13:44|21763|29445:29181|reserved|Module.pm:new(132)|constructor called, class=VCL::Module::Provisioning::vmware 2010-11-30 09:13:44|21763|29445:29181|reserved|Module.pm:new(154)|VCL::Module::Provisioning::vmware object created 2010-11-30 09:13:44|21763|29445:29181|reserved|vmware.pm:initialize(105)|vmware module initialized 2010-11-30 09:13:44|21763|29445:29181|reserved|State.pm:initialize(131)|VCL::Module::Provisioning::vmware provisioner object created 2010-11-30 09:13:44|21763|29445:29181|reserved|State.pm:initialize(145)|attempting to load OS module: VCL::Module::OS::Windows::Version_5::XP 2010-11-30 09:13:44|21763|29445:29181|reserved|State.pm:initialize(151)|VCL::Module::OS::Windows::Version_5::XP module loaded 2010-11-30 09:13:44|21763|29445:29181|reserved|Module.pm:new(132)|constructor called, class=VCL::Module::OS::Windows::Version_5::XP 2010-11-30 09:13:44|21763|29445:29181|reserved|Module.pm:new(154)|VCL::Module::OS::Windows::Version_5::XP object created 2010-11-30 09:13:44|21763|29445:29181|reserved|State.pm:initialize(154)|VCL::Module::OS::Windows::Version_5::XP OS object created 2010-11-30 09:13:44|21763|29445:29181|reserved|State.pm:initialize(169)|returning 1 2010-11-30 09:13:44|21763|29445:29181|reserved|vcld:make_new_child(591)|VCL::reserved object created and initialized 2010-11-30 09:13:44|21763|29445:29181|reserved|reserved.pm:process(121)|updated log table, set loaded time to now for id:9483 2010-11-30 09:13:44|21763|29445:29181|reserved|reserved.pm:process(133)|imagemeta user group defined 2010-11-30 09:13:44|21763|29445:29181|reserved|utils.pm:getusergroupmembers(3965)|no data returned for usergroupid returning empty lists 2010-11-30 09:13:44|21763|29445:29181|reserved|reserved.pm:process(138)|imagemeta user group membership count = 0 2010-11-30 09:13:44|21763|29445:29181|reserved|reserved.pm:process(158)|computer info: id=95, type=virtualmachine, hostname=vmguest-92 2010-11-30 09:13:44|21763|29445:29181|reserved|reserved.pm:process(159)|user info: uid=1002, unity id=admin, standalone=1 2010-11-30 09:13:44|21763|29445:29181|reserved|reserved.pm:process(160)|imagemeta checkuser set to: 1 2010-11-30 09:13:44|21763|29445:29181|reserved|reserved.pm:process(161)|formimaging set to: 0 2010-11-30 09:13:44|21763|29445:29181|reserved|reserved.pm:process(172)|begin checking for user acknowledgement 2010-11-30 09:13:44|21763|29445:29181|reserved|utils.pm:insertloadlog(4710)|inserted computer=95, info, reserved: waiting for user acknowledgement 2010-11-30 09:13:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:13:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:13:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:13:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:29|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 10 of 180, user has not acknowleged 2010-11-30 09:14:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:14:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:19|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 20 of 180, user has not acknowleged 2010-11-30 09:15:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:15:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:09|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 30 of 180, user has not acknowleged 2010-11-30 09:16:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:16:59|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 40 of 180, user has not acknowleged 2010-11-30 09:17:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:49|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 50 of 180, user has not acknowleged 2010-11-30 09:17:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:17:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:39|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 60 of 180, user has not acknowleged 2010-11-30 09:18:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:18:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:29|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 70 of 180, user has not acknowleged 2010-11-30 09:19:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:19:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:19|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 80 of 180, user has not acknowleged 2010-11-30 09:20:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:20:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:09|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 90 of 180, user has not acknowleged 2010-11-30 09:21:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:21:59|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 100 of 180, user has not acknowleged 2010-11-30 09:22:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:49|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 110 of 180, user has not acknowleged 2010-11-30 09:22:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:22:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:39|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 120 of 180, user has not acknowleged 2010-11-30 09:23:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:23:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:29|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 130 of 180, user has not acknowleged 2010-11-30 09:24:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:24:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:19|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 140 of 180, user has not acknowleged 2010-11-30 09:25:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:25:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:09|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 150 of 180, user has not acknowleged 2010-11-30 09:26:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:26:59|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 160 of 180, user has not acknowleged 2010-11-30 09:27:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:44|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:49|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:49|21763|29445:29181|reserved|reserved.pm:process(319)|attempt 170 of 180, user has not acknowleged 2010-11-30 09:27:54|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:27:59|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:04|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:09|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:14|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:19|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:24|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:29|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:34|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:39|21763|29445:29181|reserved|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:39|21763|29445:29181|reserved|reserved.pm:process(341)|user never acknowleged request, proceed to timeout 2010-11-30 09:28:39|21763|29445:29181|reserved|reserved.pm:process(385)|retval_conn = noack 2010-11-30 09:28:39|21763|29445:29181|reserved|reserved.pm:process(497)|user never acknowledged 2010-11-30 09:28:39|21763|29445:29181|reserved|utils.pm:update_request_state(2186)|request 29445 state updated to: timeout, laststate to: reserved 2010-11-30 09:28:39|21763|29445:29181|reserved|reserved.pm:process(500)|setting request into timeout state 2010-11-30 09:28:39|21763|29445:29181|reserved|utils.pm:update_computer_state(2228)|computer 95 state updated to: timeout 2010-11-30 09:28:39|21763|29445:29181|reserved|reserved.pm:process(508)|setting computerid 95 into timeout state 2010-11-30 09:28:39|21763|29445:29181|reserved|reserved.pm:process(518)|log id 9483 was updated and ending set to noack 2010-11-30 09:28:39|21763|29445:29181|reserved|utils.pm:insertloadlog(4710)|inserted computer=95, info, reserved: timing out user not acknowledged reservation 2010-11-30 09:28:39|21763|29445:29181|reserved|reserved.pm: process(527)|exiting 2010-11-30 09:28:39|21763|29445:29181|reserved|State.pm:DESTROY(905)|destructor called, ref($self)=VCL::reserved 2010-11-30 09:28:39|21763|29445:29181|reserved|utils.pm:delete_computerloadlog_reservation(7551)|removing computerloadlog entries matching loadstate = begin 2010-11-30 09:28:39|21763|29445:29181|reserved|utils.pm:delete_computerloadlog_reservation(7598)|deleted rows from computerloadlog for reservation id=29181 2010-11-30 09:28:39|21763|29445:29181|reserved|State.pm:DESTROY(912)|removed computerloadlog rows with loadstate=begin for reservation 2010-11-30 09:28:39|21763|29445:29181|reserved|State.pm:DESTROY(924)|number of database handles state process created: 1 2010-11-30 09:28:39|21763|29445:29181|reserved|State.pm:DESTROY(933)|process has a database handle stored in $ENV{dbh}, attempting disconnect 2010-11-30 09:28:39|21763|29445:29181|reserved|State.pm:DESTROY(935)|$ENV{dbh}: database disconnect successful 2010-11-30 09:28:39|21763|29445:29181|reserved|State.pm:DESTROY(949)|VCL::reserved process 21763 exiting 2010-11-30 09:28:39|31869|29445:29181|timeout|utils.pm:reservation_being_processed(9634)|computerloadlog 'begin' entry does NOT exist for reservation 29181 2010-11-30 09:28:39|31869|29445:29181|timeout|utils.pm:reservation_being_processed(9686)|reservation is NOT currently being processed 2010-11-30 09:28:39|31869|29445:29181|timeout|vcld:main(276)|reservation 29181 is NOT already being processed 2010-11-30 09:28:39|31869|29445:29181|timeout|utils.pm:get_request_info(5354)|standalone affiliation found: Local 2010-11-30 09:28:39|31869|29445:29181|timeout|utils.pm:get_management_node_info(6815)|management node info retrieved from database for vclnfs01 2010-11-30 09:28:39|31869|29445:29181|timeout|vcld:main(281)|retrieved request information from database 2010-11-30 09:28:39|31869|29445:29181|timeout|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 29181 2010-11-30 09:28:39|31869|29445:29181|timeout|utils.pm:update_request_state(2186)|request 29445 state updated to: pending, laststate to: timeout 2010-11-30 09:28:39|31869|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, begin, beginning to process, state is timeout 2010-11-30 09:28:39|31869|29445:29181|timeout|vcld:make_new_child(489)|request will be processed by reclaim.pm 2010-11-30 09:28:39|31869|29445:29181|timeout|vcld:make_new_child(509)|loaded VCL::reclaim module 2010-11-30 09:28:39|31869|29445:29181|timeout|vcld:make_new_child(566)|current number of forked kids: 4 2010-11-30 09:28:39|22619|29445:29181|timeout|vcld:make_new_child(582)|vcld environment variable set to 0 for this process 2010-11-30 09:28:39|22619|29445:29181|timeout|Module.pm:new(132)|constructor called, class=VCL::reclaim 2010-11-30 09:28:39|22619|29445:29181|timeout|Module.pm:new(154)|VCL::reclaim object created 2010-11-30 09:28:39|22619|29445:29181|timeout|State.pm:initialize(85)|obtained a database handle for this state process, stored as $ENV{dbh} 2010-11-30 09:28:39|22619|29445:29181|timeout|State.pm:check_image_os(820)|no corrections need to be made, not an imaging request, returning 1 2010-11-30 09:28:39|22619|29445:29181|timeout|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 29181 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:rename_vcld_process(7863)|reservation count: 1 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:rename_vcld_process(7882)|PARENTIMAGE: 1 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:rename_vcld_process(7883)|SUBIMAGE: 0 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:rename_vcld_process(7901)|renamed process to 'vcld VCL::reclaim 29445:29181 timeout' 2010-11-30 09:28:39|22619|29445:29181|timeout|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 29181 2010-11-30 09:28:39|22619|29445:29181|timeout|DataStructure.pm:is_parent_reservation(854)|returning true: parent reservation ID for this request: 29181 2010-11-30 09:28:39|22619|29445:29181|timeout|State.pm:initialize(121)|attempting to load provisioning module: VCL::Module::Provisioning::vmware 2010-11-30 09:28:39|22619|29445:29181|timeout|State.pm:initialize(127)|VCL::Module::Provisioning::vmware module loaded 2010-11-30 09:28:39|22619|29445:29181|timeout|Module.pm:new(132)|constructor called, class=VCL::Module::Provisioning::vmware 2010-11-30 09:28:39|22619|29445:29181|timeout|Module.pm:new(154)|VCL::Module::Provisioning::vmware object created 2010-11-30 09:28:39|22619|29445:29181|timeout|vmware.pm:initialize(105)|vmware module initialized 2010-11-30 09:28:39|22619|29445:29181|timeout|State.pm:initialize(131)|VCL::Module::Provisioning::vmware provisioner object created 2010-11-30 09:28:39|22619|29445:29181|timeout|State.pm:initialize(145)|attempting to load OS module: VCL::Module::OS::Windows::Version_5::XP 2010-11-30 09:28:39|22619|29445:29181|timeout|State.pm:initialize(151)|VCL::Module::OS::Windows::Version_5::XP module loaded 2010-11-30 09:28:39|22619|29445:29181|timeout|Module.pm:new(132)|constructor called, class=VCL::Module::OS::Windows::Version_5::XP 2010-11-30 09:28:39|22619|29445:29181|timeout|Module.pm:new(154)|VCL::Module::OS::Windows::Version_5::XP object created 2010-11-30 09:28:39|22619|29445:29181|timeout|State.pm:initialize(154)|VCL::Module::OS::Windows::Version_5::XP OS object created 2010-11-30 09:28:39|22619|29445:29181|timeout|State.pm:initialize(169)|returning 1 2010-11-30 09:28:39|22619|29445:29181|timeout|vcld:make_new_child(591)|VCL::reclaim object created and initialized 2010-11-30 09:28:39|22619|29445:29181|timeout|DataStructure.pm:get_reservation_remote_ip(956)|reservation remote IP is not defined 2010-11-30 09:28:39|22619|29445:29181|timeout|DataStructure.pm:get_computer_state_name(1946)|attempting to retrieve current state of computer vmguest-92 from the database 2010-11-30 09:28:39|22619|29445:29181|timeout|DataStructure.pm:get_computer_state_name(1977)|retrieved current state of computer vmguest-92 from the database: timeout 2010-11-30 09:28:39|22619|29445:29181|timeout|DataStructure.pm:_automethod(697)|data structure updated: $self->request_data->{reservation}{29181}{computer}{state}{name} |22619|29445:29181|timeout| computer_state_name = timeout 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, timeout, reclaim: starting timeout process 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, info, reclaim: request state is timeout 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, info, reclaim: request laststate is reserved 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, info, reclaim: computer type is virtualmachine 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, info, reclaim: computer OS is vmwarewinxp 2010-11-30 09:28:39|22619|29445:29181|timeout|reclaim.pm:process(138)|computer type is virtualmachine 2010-11-30 09:28:39|22619|29445:29181|timeout|reclaim.pm:process(142)|request laststate is reserved, attempting to sanitize computer 2010-11-30 09:28:39|22619|29445:29181|timeout|reclaim.pm:process(147)|calling VCL::Module::OS::Windows::Version_5::XP::get_current_image_name() subroutine 2010-11-30 09:28:39|22619|29445:29181|timeout|utils.pm:run_ssh_command(6180)|executing SSH command on vmguest-92: |22619|29445:29181|timeout| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x vmguest-92 'cat ~/currentimage.txt' 2>&1 2010-11-30 09:28:45|22619|29445:29181|timeout|utils.pm:run_ssh_command(6262)|run_ssh_command output: |22619|29445:29181|timeout| vmwarewinxp-SPSSPASWStatisticsAmos1844-v4 |22619|29445:29181|timeout| id=44 |22619|29445:29181|timeout| prettyname=CSUEB SPSS PASW Statistics & Amos 18 |22619|29445:29181|timeout| imagerevision_id=168 |22619|29445:29181|timeout| imagerevision_datecreated=2010-08-24 09:31:20 |22619|29445:29181|timeout| computer_id=230 |22619|29445:29181|timeout| computer_hostname=vmguest-222 2010-11-30 09:28:45|22619|29445:29181|timeout|utils.pm:run_ssh_command(6276)|SSH command executed on vmguest-92, returning (0, "vmwarewinxp-SPSSPASWStatistics...") 2010-11-30 09:28:45|22619|29445:29181|timeout|Windows.pm:get_currentimage_txt_contents(3885)|retrieved currentimage.txt from vmguest-92 2010-11-30 09:28:45|22619|29445:29181|timeout|Windows.pm:get_currentimage_txt_contents(3897)|found 7 lines in currentimage.txt on vmguest-92 2010-11-30 09:28:45|22619|29445:29181|timeout|Windows.pm:get_current_image_name(3923)|retrieved currentimage.txt contents from vmguest-92 2010-11-30 09:28:45|22619|29445:29181|timeout|Windows.pm:get_current_image_name(3938)|returning name of image currently loaded on vmguest-92: vmwarewinxp-SPSSPASWStatisticsAmos1844-v4 2010-11-30 09:28:45|22619|29445:29181|timeout|reclaim.pm:process(149)|retrieved name of image currently loaded on vmguest-92: vmwarewinxp-SPSSPASWStatisticsAmos1844-v4 2010-11-30 09:28:45|22619|29445:29181|timeout|reclaim.pm:process(159)|computer table current image name (vmwarewinxp-SPSSPASWStatisticsAmos1844-v4) matches OS's current image name (vmwarewinxp-SPSSPASWStatisticsAmos1844-v4) 2010-11-30 09:28:45|22619|29445:29181|timeout|reclaim.pm:process(172)|calling VCL::Module::OS::Windows::Version_5::XP::sanitize() subroutine 2010-11-30 09:28:45|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4630)|attempting to retrieve network configuration 2010-11-30 09:28:45|22619|29445:29181|timeout|utils.pm:run_ssh_command(6180)|executing SSH command on vmguest-92: |22619|29445:29181|timeout| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x vmguest-92 '$SYSTEMROOT/System32/ipconfig.exe /all' 2>&1 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4634)|ran ipconfig 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4655)|found interface: Local Area Connection 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4655)|found interface: Local Area Connection 2 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4706)|saving network configuration in $self->{network_configuration} 2010-11-30 09:28:50|22619|29445:29181|timeout|DataStructure.pm:get_computer_private_ip_address(1418)|returning private IP address previously retrieved from /etc/hosts: 10.10.10.171 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4747)|private interface found: Local Area Connection, description: AMD PCNET Family PCI Ethernet Adapter #2, address(es): 10.10.10.171 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4750)|returning data for private interface: Local Area Connection (10.10.10.171) 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_private_interface_name(4848)|returning private interface name: Local Area Connection 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:firewall_disable_rdp(4538)|RDP will be disabled on private interface: Local Area Connection 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4710)|network configuration has already been retrieved 2010-11-30 09:28:50|22619|29445:29181|timeout|DataStructure.pm:get_computer_private_ip_address(1418)|returning private IP address previously retrieved from /etc/hosts: 10.10.10.171 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4747)|private interface found: Local Area Connection, description: AMD PCNET Family PCI Ethernet Adapter #2, address(es): 10.10.10.171 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4795)|public interface found: Local Area Connection 2, description: AMD PCNET Family PCI Ethernet Adapter #3, address(es): 137.145.57.81 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_network_configuration(4798)|returning data for public interface: Local Area Connection 2 (137.145.57.81) 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:get_public_interface_name(4878)|returning public interface name: Local Area Connection 2 2010-11-30 09:28:50|22619|29445:29181|timeout|Windows.pm:firewall_disable_rdp(4554)|RDP will be disabled on public interface: Local Area Connection 2 2010-11-30 09:28:50|22619|29445:29181|timeout|utils.pm:run_ssh_command(6180)|executing SSH command on vmguest-92: |22619|29445:29181|timeout| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x vmguest-92 'netsh.exe firewall delete portopening protocol = TCP port = 3389 interface = "Local Area Connection" ;netsh.exe firewall delete portopening protocol = TCP port = 3389 interface = "Local Area Connection 2" ;netsh.exe firewall delete portopening protocol = TCP port = 3389 profile = ALL' 2>&1 2010-11-30 09:29:02|22619|29445:29181|timeout|utils.pm:run_ssh_command(6262)|run_ssh_command output: |22619|29445:29181|timeout| Ok. |22619|29445:29181|timeout| Ok. |22619|29445:29181|timeout| Ok. 2010-11-30 09:29:02|22619|29445:29181|timeout|utils.pm:run_ssh_command(6276)|SSH command executed on vmguest-92, returning (0, "Ok. Ok. Ok.") 2010-11-30 09:29:02|22619|29445:29181|timeout|Windows.pm:firewall_disable_rdp(4575)|configured firewall to disallow RDP 2010-11-30 09:29:02|22619|29445:29181|timeout|Windows.pm:revoke_access(768)|firewall was configured to deny RDP access on vmguest-92 2010-11-30 09:29:02|22619|29445:29181|timeout|Windows.pm:revoke_access(776)|access has been revoked to vmguest-92 2010-11-30 09:29:02|22619|29445:29181|timeout|Windows.pm:delete_user(1570)|attempting to delete user admin from vmguest-92 2010-11-30 09:29:02|22619|29445:29181|timeout|utils.pm:run_ssh_command(6180)|executing SSH command on vmguest-92: |22619|29445:29181|timeout| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x vmguest-92 'net user admin /DELETE' 2>&1 2010-11-30 09:29:07|22619|29445:29181|timeout|utils.pm:run_ssh_command(6262)|run_ssh_command output: |22619|29445:29181|timeout| The command completed successfully. 2010-11-30 09:29:07|22619|29445:29181|timeout|utils.pm:run_ssh_command(6276)|SSH command executed on vmguest-92, returning (0, "The command completed successf...") 2010-11-30 09:29:07|22619|29445:29181|timeout|Windows.pm:delete_user(1575)|deleted user admin from vmguest-92 2010-11-30 09:29:07|22619|29445:29181|timeout|Windows.pm:format_path_unix(7843)|formatted path for Unix: C:/Documents\ and\ Settings/admin 2010-11-30 09:29:07|22619|29445:29181|timeout|Windows.pm:format_path_dos(7875)|formatted path for DOS: C:\\Documents and Settings\\admin 2010-11-30 09:29:07|22619|29445:29181|timeout|utils.pm:run_ssh_command(6180)|executing SSH command on vmguest-92: |22619|29445:29181|timeout| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x vmguest-92 'cmd.exe /c dir /a /b "C:\\Documents and Settings\\admin"' 2>&1 2010-11-30 09:29:12|22619|29445:29181|timeout|Windows.pm:filesystem_entry_exists(1153)|filesystem entry does NOT exist on vmguest-92: C:\\Documents and Settings\\admin |22619|29445:29181|timeout| output: |22619|29445:29181|timeout| File Not Found 2010-11-30 09:29:12|22619|29445:29181|timeout|Windows.pm:delete_file(898)|file not deleted because it does not exist: C:/Documents and Settings/admin 2010-11-30 09:29:12|22619|29445:29181|timeout|Windows.pm:delete_user(1591)|deleted profile for user admin from vmguest-92 2010-11-30 09:29:12|22619|29445:29181|timeout|Windows.pm:delete_users(1350)|deleted 1 users from vmguest-92 2010-11-30 09:29:12|22619|29445:29181|timeout|Windows.pm:sanitize(667)|users have been deleted from vmguest-92 2010-11-30 09:29:12|22619|29445:29181|timeout|Windows.pm:sanitize(675)|vmguest-92 has been sanitized 2010-11-30 09:29:12|22619|29445:29181|timeout|reclaim.pm:process(173)|vmguest-92 has been sanitized 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:switch_state(8018)|called from VCL::reclaim::process(271) 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:switch_state(8054)|parent: parent reservation ID for this request: 29181 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, info, VCL::reclaim: switching request state to complete 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:update_request_state(2186)|request 29445 state updated to: complete, laststate to: timeout 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:switch_state(8078)|req=29445: request state changed: timeout->complete, laststate: reserved->timeout 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, info, VCL::reclaim: request state changed to complete, laststate to timeout 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, info, VCL::reclaim: switching computer state to available 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:update_computer_state(2228)|computer 95 state updated to: available 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:switch_state(8096)|req=29445: computer vmguest-92 state changed: timeout->available 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:switch_state(8106)|req=29445: log table id=9483 will not be updated 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:insertloadlog(4710)|inserted computer=95, info, VCL::reclaim: process exiting 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:switch_state(8122)|req=29445: process exiting 2010-11-30 09:29:12|22619|29445:29181|timeout|State.pm:DESTROY(905)|destructor called, ref($self)=VCL::reclaim 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:delete_computerloadlog_reservation(7551)|removing computerloadlog entries matching loadstate = begin 2010-11-30 09:29:12|22619|29445:29181|timeout|utils.pm:delete_computerloadlog_reservation(7598)|deleted rows from computerloadlog for reservation id=29181 2010-11-30 09:29:12|22619|29445:29181|timeout|State.pm:DESTROY(912)|removed computerloadlog rows with loadstate=begin for reservation 2010-11-30 09:29:12|22619|29445:29181|timeout|State.pm:DESTROY(924)|number of database handles state process created: 1 2010-11-30 09:29:12|22619|29445:29181|timeout|State.pm:DESTROY(933)|process has a database handle stored in $ENV{dbh}, attempting disconnect 2010-11-30 09:29:12|22619|29445:29181|timeout|State.pm:DESTROY(935)|$ENV{dbh}: database disconnect successful 2010-11-30 09:29:12|22619|29445:29181|timeout|State.pm:DESTROY(949)|VCL::reclaim process 22619 exiting
