jmBqKg3E

12011-09-21 12:08:01|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
2|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'echo WVdpZVHmJYoC9tq | /usr/bin/passwd -f root --stdin' 2>&1
32011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
4|29980|57475:57211|new| /usr/bin/passwd: invalid option -- 'f'
5|29980|57475:57211|new| Usage: passwd [options] [LOGIN]
6|29980|57475:57211|new| Options:
7|29980|57475:57211|new| -a, --all                     report password status on all accounts
8|29980|57475:57211|new| -d, --delete                  delete the password for the named account
9|29980|57475:57211|new| -e, --expire                  force expire the password for the named account
10|29980|57475:57211|new| -h, --help                    display this help message and exit
11|29980|57475:57211|new| -k, --keep-tokens             change password only if expired
12|29980|57475:57211|new| -i, --inactive INACTIVE       set password inactive after expiration
13|29980|57475:57211|new| to INACTIVE
14|29980|57475:57211|new| -l, --lock                    lock the password of the named account
15|29980|57475:57211|new| -n, --mindays MIN_DAYS        set minimum number of days before password
16|29980|57475:57211|new| change to MIN_DAYS
17|29980|57475:57211|new| -q, --quiet                   quiet mode
18|29980|57475:57211|new| -r, --repository REPOSITORY   change password in REPOSITORY repository
19|29980|57475:57211|new| -S, --status                  report password status on the named account
20|29980|57475:57211|new| -u, --unlock                  unlock the password of the named account
21|29980|57475:57211|new| -w, --warndays WARN_DAYS      set expiration warning days to WARN_DAYS
22|29980|57475:57211|new| -x, --maxdays MAX_DAYS        set maximum number of days before password
23|29980|57475:57211|new| change to MAX_DAYS
242011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-98, command:
25|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'echo WVdpZVHmJYoC9tq | /usr/bin/passwd -f root --stdin' 2>&1
26|29980|57475:57211|new| returning (6, "/usr/bin/passwd: invalid optio...")
272011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:changepasswd(982)|changed password for account: root, output:
28|29980|57475:57211|new| /usr/bin/passwd: invalid option -- 'f'
29|29980|57475:57211|new| Usage: passwd [options] [LOGIN]
30|29980|57475:57211|new| Options:
31|29980|57475:57211|new| -a, --all                     report password status on all accounts
32|29980|57475:57211|new| -d, --delete                  delete the password for the named account
33|29980|57475:57211|new| -e, --expire                  force expire the password for the named account
34|29980|57475:57211|new| -h, --help                    display this help message and exit
35|29980|57475:57211|new| -k, --keep-tokens             change password only if expired
36|29980|57475:57211|new| -i, --inactive INACTIVE       set password inactive after expiration
37|29980|57475:57211|new| to INACTIVE
38|29980|57475:57211|new| -l, --lock                    lock the password of the named account
39|29980|57475:57211|new| -n, --mindays MIN_DAYS        set minimum number of days before password
40|29980|57475:57211|new| change to MIN_DAYS
41|29980|57475:57211|new| -q, --quiet                   quiet mode
42|29980|57475:57211|new| -r, --repository REPOSITORY   change password in REPOSITORY repository
43|29980|57475:57211|new| -S, --status                  report password status on the named account
44|29980|57475:57211|new| -u, --unlock                  unlock the password of the named account
45|29980|57475:57211|new| -w, --warndays WARN_DAYS      set expiration warning days to WARN_DAYS
46|29980|57475:57211|new| -x, --maxdays MAX_DAYS        set maximum number of days before password
47|29980|57475:57211|new| change to MAX_DAYS
482011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:post_load(240)|successfully changed root password on vmguest-98
492011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
50|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 '/etc/init.d/ext_sshd stop' 2>&1
512011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
52|29980|57475:57211|new| bash: /etc/init.d/ext_sshd: No such file or directory
532011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-98, command:
54|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 '/etc/init.d/ext_sshd stop' 2>&1
55|29980|57475:57211|new| returning (127, "bash: /etc/init.d/ext_sshd: No...")
562011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
57|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'sed -i -e "/^AllowUsers .*/d" /etc/ssh/external_sshd_config' 2>&1
582011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
59|29980|57475:57211|new| sed: can't read /etc/ssh/external_sshd_config: No such file or directory
602011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-98, command:
61|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'sed -i -e "/^AllowUsers .*/d" /etc/ssh/external_sshd_config' 2>&1
62|29980|57475:57211|new| returning (2, "sed: can't read /etc/ssh/exter...")
632011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:post_load(259)|cleared AllowUsers directive from external_sshd_config
642011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:post_load(266)|calling clear_private_keys
652011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:clear_private_keys(443)|perparing to clear known identity keys
662011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
67|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 '/bin/rm -f /root/.ssh/id_rsa /root/.ssh/id_rsa.pub' 2>&1
682011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
692011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5478)|SSH command executed on vmguest-98, returning (0, "")
702011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:clear_private_keys(451)|cleared any id_rsa keys from /root/.ssh
712011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:post_load(268)|cleared known identity keys
722011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:get_network_configuration(2614)|retrieved network configuration:
73|29980|57475:57211|new| : {
74|29980|57475:57211|new| :   "eth0" => {
75|29980|57475:57211|new| :     "ip_address" => {
76|29980|57475:57211|new| :       "10.10.10.177" => "255.255.248.0"
77|29980|57475:57211|new| :     },
78|29980|57475:57211|new| :     "name" => "eth0",
79|29980|57475:57211|new| :     "physical_address" => "00:50:56:2a:3b:c1"
80|29980|57475:57211|new| :   },
81|29980|57475:57211|new| :   "eth1" => {
82|29980|57475:57211|new| :     "ip_address" => {
83|29980|57475:57211|new| :       "137.145.58.6" => "255.255.252.0"
84|29980|57475:57211|new| :     },
85|29980|57475:57211|new| :     "name" => "eth1",
86|29980|57475:57211|new| :     "physical_address" => "00:50:56:2a:3b:c2"
87|29980|57475:57211|new| :   },
88|29980|57475:57211|new| :   "lo" => {
89|29980|57475:57211|new| :     "ip_address" => {
90|29980|57475:57211|new| :       "127.0.0.1" => "255.0.0.0"
91|29980|57475:57211|new| :     },
92|29980|57475:57211|new| :     "name" => "lo"
93|29980|57475:57211|new| :   }
94|29980|57475:57211|new| : }
952011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:get_network_configuration(2617)|network configuration has already been retrieved
962011-09-21 12:08:02|29980|57475:57211|new|DataStructure.pm:get_computer_private_ip_address(1581)|attempting to retrieve private IP address for computer: vmguest-98
972011-09-21 12:08:02|29980|57475:57211|new|DataStructure.pm:get_computer_private_ip_address(1585)|retrieved contents of /etc/hosts on this management node, contains 529 lines
982011-09-21 12:08:02|29980|57475:57211|new|DataStructure.pm:get_computer_private_ip_address(1645)|returning IP address from /etc/hosts file: 10.10.10.177
992011-09-21 12:08:02|29980|57475:57211|new|OS.pm:get_public_interface_name(898)|ignoring private interface: eth0 (10.10.10.177)
1002011-09-21 12:08:02|29980|57475:57211|new|utils.pm:is_valid_ip_address(9266)|IP address is valid: 137.145.58.6
1012011-09-21 12:08:02|29980|57475:57211|new|utils.pm:is_public_ip_address(9412)|public IP address: 137.145.58.6, returning 1
1022011-09-21 12:08:02|29980|57475:57211|new|OS.pm:get_public_interface_name(919)|determined public interface name: eth1 (137.145.58.6)
1032011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:get_network_configuration(2645)|returning public network configuration
1042011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:get_public_ip_address(2746)|retrieved public IP address: 137.145.58.6
1052011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:update_public_hostname(376)|retrieved public IP address of vmguest-98: 137.145.58.6
1062011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_command(9010)|executed command: /bin/ipcalc --hostname 137.145.58.6, pid: 30789, exit status: 1, output:
107|29980|57475:57211|new| ipcalc: cannot find hostname for 137.145.58.6: Unknown host
1082011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:update_public_hostname(391)|failed to determine registered public hostname of vmguest-98 (137.145.58.6), command: '/bin/ipcalc --hostname 137.145.58.6', output:
109|29980|57475:57211|new| ipcalc: cannot find hostname for 137.145.58.6: Unknown host
1102011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
111|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 '/bin/ipcalc --hostname 137.145.58.6' 2>&1
1122011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
113|29980|57475:57211|new| bash: /bin/ipcalc: No such file or directory
1142011-09-21 12:08:02|29980|57475:57211|new|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-98, command:
115|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 '/bin/ipcalc --hostname 137.145.58.6' 2>&1
116|29980|57475:57211|new| returning (127, "bash: /bin/ipcalc: No such fil...")
117
118|29980|57475:57211|new| ---- WARNING ---- 
119|29980|57475:57211|new| 2011-09-21 12:08:02|29980|57475:57211|new|Linux.pm:update_public_hostname(405)|failed to determine registered public hostname of vmguest-98 (137.145.58.6) by running ipcalc on either the management node or vmguest-98, command: '/bin/ipcalc --hostname 137.145.58.6', output:
120|29980|57475:57211|new| bash: /bin/ipcalc: No such file or directory
121|29980|57475:57211|new| ( 0) Linux.pm, update_public_hostname (line: 405)
122|29980|57475:57211|new| (-1) Linux.pm, post_load (line: 273)
123|29980|57475:57211|new| (-2) VMware.pm, load (line: 415)
124|29980|57475:57211|new| (-3) new.pm, reload_image (line: 618)
125|29980|57475:57211|new| (-4) new.pm, process (line: 248)
126|29980|57475:57211|new| (-5) vcld, make_new_child (line: 568)
127
1282011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:file_exists(1390)|file or directory does not exist on vmguest-98: '/etc/init.d/vcl_post_load'
1292011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:run_script(1307)|script does NOT exist: /etc/init.d/vcl_post_load
1302011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:post_load(280)|/etc/init.d/vcl_post_load does not exist in image: vmwarelinux-ubu-v0
1312011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:activate_interfaces(2465)|attempting to find network interfaces with an active link
1322011-09-21 12:08:03|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
133|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'ip link' 2>&1
1342011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:execute(1267)|executed command: 'ip link', exit status: 0, output:
135|29980|57475:57211|new| 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 16436 qdisc noqueue state UNKNOWN
136|29980|57475:57211|new| link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
137|29980|57475:57211|new| 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UNKNOWN qlen 1000
138|29980|57475:57211|new| link/ether 00:50:56:2a:3b:c1 brd ff:ff:ff:ff:ff:ff
139|29980|57475:57211|new| 3: eth1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UNKNOWN qlen 1000
140|29980|57475:57211|new| link/ether 00:50:56:2a:3b:c2 brd ff:ff:ff:ff:ff:ff
1412011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:activate_interfaces(2474)|found interface names:
142|29980|57475:57211|new| eth0
143|29980|57475:57211|new| eth1
1442011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:find_files(2074)|attempting to find files on vmguest-98, base directory path: '/etc/sysconfig/network-scripts/', pattern: ifcfg-eth*, command: find "/etc/sysconfig/network-scripts/" -iname "ifcfg-eth*"
1452011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:find_files(2082)|base directory does not exist on vmguest-98: /etc/sysconfig/network-scripts/
1462011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:find_files(2092)|matching file count: 0
1472011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:activate_interfaces(2479)|found existing ifcfg-eth* files:
1482011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:activate_interfaces(2491)|ifcfg file does not exist for eth0
1492011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:activate_interfaces(2503)|attempting to echo contents to /etc/sysconfig/network-scripts/ifcfg-eth0:
150|29980|57475:57211|new| DEVICE=eth0
151|29980|57475:57211|new| BOOTPROTO=dhcp
152|29980|57475:57211|new| STARTMODE=onboot
153|29980|57475:57211|new| ONBOOT=yes
1542011-09-21 12:08:03|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
155|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'echo  "DEVICE=eth0
156|29980|57475:57211|new| BOOTPROTO=dhcp
157|29980|57475:57211|new| STARTMODE=onboot
158|29980|57475:57211|new| ONBOOT=yes
159|29980|57475:57211|new| " > /etc/sysconfig/network-scripts/ifcfg-eth0 && ifup eth0' 2>&1
1602011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:execute(1267)|executed command: 'echo  "DEVICE=eth0
161|29980|57475:57211|new| BOOTPROTO=dhcp
162|29980|57475:57211|new| STARTMODE=onboot
163|29980|57475:57211|new| ONBOOT=yes
164|29980|57475:57211|new| " > /etc/sysconfig/network-scripts/ifcfg-eth0 && ifup eth0', exit status: 1, output:
165|29980|57475:57211|new| bash: line 4: /etc/sysconfig/network-scripts/ifcfg-eth0: No such file or directory
166
167|29980|57475:57211|new| ---- WARNING ---- 
168|29980|57475:57211|new| 2011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:activate_interfaces(2513)|failed to create /etc/sysconfig/network-scripts/ifcfg-eth0 and enable interface: eth0, output:
169|29980|57475:57211|new| bash: line 4: /etc/sysconfig/network-scripts/ifcfg-eth0: No such file or directory
170|29980|57475:57211|new| ( 0) Linux.pm, activate_interfaces (line: 2513)
171|29980|57475:57211|new| (-1) Linux.pm, post_load (line: 291)
172|29980|57475:57211|new| (-2) VMware.pm, load (line: 415)
173|29980|57475:57211|new| (-3) new.pm, reload_image (line: 618)
174|29980|57475:57211|new| (-4) new.pm, process (line: 248)
175|29980|57475:57211|new| (-5) vcld, make_new_child (line: 568)
176
1772011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:activate_interfaces(2491)|ifcfg file does not exist for eth1
1782011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:activate_interfaces(2503)|attempting to echo contents to /etc/sysconfig/network-scripts/ifcfg-eth1:
179|29980|57475:57211|new| DEVICE=eth1
180|29980|57475:57211|new| BOOTPROTO=dhcp
181|29980|57475:57211|new| STARTMODE=onboot
182|29980|57475:57211|new| ONBOOT=yes
1832011-09-21 12:08:03|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
184|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'echo  "DEVICE=eth1
185|29980|57475:57211|new| BOOTPROTO=dhcp
186|29980|57475:57211|new| STARTMODE=onboot
187|29980|57475:57211|new| ONBOOT=yes
188|29980|57475:57211|new| " > /etc/sysconfig/network-scripts/ifcfg-eth1 && ifup eth1' 2>&1
1892011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:execute(1267)|executed command: 'echo  "DEVICE=eth1
190|29980|57475:57211|new| BOOTPROTO=dhcp
191|29980|57475:57211|new| STARTMODE=onboot
192|29980|57475:57211|new| ONBOOT=yes
193|29980|57475:57211|new| " > /etc/sysconfig/network-scripts/ifcfg-eth1 && ifup eth1', exit status: 1, output:
194|29980|57475:57211|new| bash: line 4: /etc/sysconfig/network-scripts/ifcfg-eth1: No such file or directory
195
196|29980|57475:57211|new| ---- WARNING ---- 
197|29980|57475:57211|new| 2011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:activate_interfaces(2513)|failed to create /etc/sysconfig/network-scripts/ifcfg-eth1 and enable interface: eth1, output:
198|29980|57475:57211|new| bash: line 4: /etc/sysconfig/network-scripts/ifcfg-eth1: No such file or directory
199|29980|57475:57211|new| ( 0) Linux.pm, activate_interfaces (line: 2513)
200|29980|57475:57211|new| (-1) Linux.pm, post_load (line: 291)
201|29980|57475:57211|new| (-2) VMware.pm, load (line: 415)
202|29980|57475:57211|new| (-3) new.pm, reload_image (line: 618)
203|29980|57475:57211|new| (-4) new.pm, process (line: 248)
204|29980|57475:57211|new| (-5) vcld, make_new_child (line: 568)
205
2062011-09-21 12:08:03|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
207|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'sed -i -e '/vcld_post_load.*/d' currentimage.txt && echo >> currentimage.txt && echo "vcld_post_load=success (Wed Sep 21 12:08:03 2011)" >> currentimage.txt && sed -i -e '/^[\s\r\n]*$/d' currentimage.txt && dos2unix currentimage.txt' 2>&1
208
209|29980|57475:57211|new| ---- WARNING ---- 
210|29980|57475:57211|new| 2011-09-21 12:08:03|29980|57475:57211|new|OS.pm:set_vcld_post_load_status(772)|failed to add line to currentimage.txt on vmguest-98: 'vcld_post_load=success (Wed Sep 21 12:08:03 2011)', exit status: 2, output:
211|29980|57475:57211|new| sed: can't read currentimage.txt: No such file or directory
212|29980|57475:57211|new| ( 0) OS.pm, set_vcld_post_load_status (line: 772)
213|29980|57475:57211|new| (-1) Linux.pm, post_load (line: 294)
214|29980|57475:57211|new| (-2) VMware.pm, load (line: 415)
215|29980|57475:57211|new| (-3) new.pm, reload_image (line: 618)
216|29980|57475:57211|new| (-4) new.pm, process (line: 248)
217|29980|57475:57211|new| (-5) vcld, make_new_child (line: 568)
218
2192011-09-21 12:08:03|29980|57475:57211|new|utils.pm:insertloadlog(3875)|inserted computer=101, loadimagecomplete, performed OS post-load tasks on vmguest-98
2202011-09-21 12:08:03|29980|57475:57211|new|new.pm:reload_image(619)|vmwarelinux-ubu-v0 was successfully reloaded on vmguest-98
2212011-09-21 12:08:03|29980|57475:57211|new|utils.pm:insertloadlog(3875)|inserted computer=101, loadimagecomplete, vmwarelinux-ubu-v0 was successfully reloaded on vmguest-98
2222011-09-21 12:08:03|29980|57475:57211|new|new.pm:reload_image(628)|node ready: successfully reloaded vmguest-98 with vmwarelinux-ubu-v0
2232011-09-21 12:08:03|29980|57475:57211|new|utils.pm:insertloadlog(3875)|inserted computer=101, nodeready, vmguest-98 was reloaded with vmwarelinux-ubu-v0
2242011-09-21 12:08:03|29980|57475:57211|new|utils.pm:update_currentimage(6088)|updating computer 101: image=29, imagerevision=29
2252011-09-21 12:08:03|29980|57475:57211|new|utils.pm:update_currentimage(6118)|updated currentimageid and imagerevision id for computer id 101
2262011-09-21 12:08:03|29980|57475:57211|new|new.pm:reload_image(633)|updated computer table for vmguest-98: currentimageid=29
2272011-09-21 12:08:03|29980|57475:57211|new|new.pm:reload_image(640)|returning 1
2282011-09-21 12:08:03|29980|57475:57211|new|new.pm:process(248)|vmguest-98 is loaded with vmwarelinux-ubu-v0
2292011-09-21 12:08:03|29980|57475:57211|new|new.pm:process(332)|vmguest-98 next image set to vmwarelinux-ubu-v0
2302011-09-21 12:08:03|29980|57475:57211|new|DataStructure.pm:is_parent_reservation(991)|returning true: parent reservation ID for this request: 57211
2312011-09-21 12:08:03|29980|57475:57211|new|new.pm:reserve_computer(881)|user_standalone=1, image OS type=linux
2322011-09-21 12:08:03|29980|57475:57211|new|OS.pm:update_public_ip_address(595)|IP configuration is set to dynamicDHCP, attempting to retrieve dynamic public IP address from vmguest-98
2332011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:get_network_configuration(2617)|network configuration has already been retrieved
2342011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:get_network_configuration(2617)|network configuration has already been retrieved
2352011-09-21 12:08:03|29980|57475:57211|new|DataStructure.pm:get_computer_private_ip_address(1557)|returning private IP address previously retrieved: 10.10.10.177
2362011-09-21 12:08:03|29980|57475:57211|new|OS.pm:get_public_interface_name(898)|ignoring private interface: eth0 (10.10.10.177)
2372011-09-21 12:08:03|29980|57475:57211|new|utils.pm:is_valid_ip_address(9266)|IP address is valid: 137.145.58.6
2382011-09-21 12:08:03|29980|57475:57211|new|utils.pm:is_public_ip_address(9412)|public IP address: 137.145.58.6, returning 1
2392011-09-21 12:08:03|29980|57475:57211|new|OS.pm:get_public_interface_name(919)|determined public interface name: eth1 (137.145.58.6)
2402011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:get_network_configuration(2645)|returning public network configuration
2412011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:get_public_ip_address(2746)|retrieved public IP address: 137.145.58.6
2422011-09-21 12:08:03|29980|57475:57211|new|OS.pm:update_public_ip_address(600)|retrieved public IP address from vmguest-98 using the OS module: 137.145.58.6
2432011-09-21 12:08:03|29980|57475:57211|new|utils.pm:update_computer_address(6258)|computer 101 IP address 137.145.58.6 updated in database
2442011-09-21 12:08:03|29980|57475:57211|new|OS.pm:update_public_ip_address(616)|updated dynamic public IP address in computer table for vmguest-98, 137.145.58.6
2452011-09-21 12:08:03|29980|57475:57211|new|utils.pm:insertloadlog(3875)|inserted computer=101, dynamicDHCPaddress, updated dynamic public IP address in computer table for vmguest-98, 137.145.58.6
2462011-09-21 12:08:03|29980|57475:57211|new|utils.pm:insertloadlog(3875)|inserted computer=101, info, node ready adding user account
2472011-09-21 12:08:03|29980|57475:57211|new|utils.pm:update_request_password(2116)|password updated for reservation_id 57211
2482011-09-21 12:08:03|29980|57475:57211|new|new.pm:reserve_computer(906)|updated password in the reservation table
2492011-09-21 12:08:03|29980|57475:57211|new|DataStructure.pm:_automethod(834)|data structure updated: $self->request_data->{reservation}{57211}{pw}
250|29980|57475:57211|new| reservation_password = nqa6Bq
2512011-09-21 12:08:03|29980|57475:57211|new|new.pm:reserve_computer(920)|calling OS module's reserve() subroutine
2522011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:reserve(820)|Enterered reserve() in the Linux OS module
2532011-09-21 12:08:03|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
254|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'groupadd vcl' 2>&1
2552011-09-21 12:08:03|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
2562011-09-21 12:08:03|29980|57475:57211|new|utils.pm:run_ssh_command(5478)|SSH command executed on vmguest-98, returning (0, "")
2572011-09-21 12:08:03|29980|57475:57211|new|Linux.pm:add_vcl_usergroup(1115)|successfully added the vcl user group
2582011-09-21 12:08:03|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
259|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 '/usr/sbin/useradd -d /home/wv4973 -m wv4973 -g vcl' 2>&1
2602011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
2612011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5478)|SSH command executed on vmguest-98, returning (0, "")
2622011-09-21 12:08:04|29980|57475:57211|new|Linux.pm:reserve(855)|Standalone user setting single-use password
2632011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
264|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'echo nqa6Bq | /usr/bin/passwd -f wv4973 --stdin' 2>&1
2652011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
266|29980|57475:57211|new| /usr/bin/passwd: invalid option -- 'f'
267|29980|57475:57211|new| Usage: passwd [options] [LOGIN]
268|29980|57475:57211|new| Options:
269|29980|57475:57211|new| -a, --all                     report password status on all accounts
270|29980|57475:57211|new| -d, --delete                  delete the password for the named account
271|29980|57475:57211|new| -e, --expire                  force expire the password for the named account
272|29980|57475:57211|new| -h, --help                    display this help message and exit
273|29980|57475:57211|new| -k, --keep-tokens             change password only if expired
274|29980|57475:57211|new| -i, --inactive INACTIVE       set password inactive after expiration
275|29980|57475:57211|new| to INACTIVE
276|29980|57475:57211|new| -l, --lock                    lock the password of the named account
277|29980|57475:57211|new| -n, --mindays MIN_DAYS        set minimum number of days before password
278|29980|57475:57211|new| change to MIN_DAYS
279|29980|57475:57211|new| -q, --quiet                   quiet mode
280|29980|57475:57211|new| -r, --repository REPOSITORY   change password in REPOSITORY repository
281|29980|57475:57211|new| -S, --status                  report password status on the named account
282|29980|57475:57211|new| -u, --unlock                  unlock the password of the named account
283|29980|57475:57211|new| -w, --warndays WARN_DAYS      set expiration warning days to WARN_DAYS
284|29980|57475:57211|new| -x, --maxdays MAX_DAYS        set maximum number of days before password
285|29980|57475:57211|new| change to MAX_DAYS
2862011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-98, command:
287|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'echo nqa6Bq | /usr/bin/passwd -f wv4973 --stdin' 2>&1
288|29980|57475:57211|new| returning (6, "/usr/bin/passwd: invalid optio...")
2892011-09-21 12:08:04|29980|57475:57211|new|Linux.pm:changepasswd(982)|changed password for account: wv4973, output:
290|29980|57475:57211|new| /usr/bin/passwd: invalid option -- 'f'
291|29980|57475:57211|new| Usage: passwd [options] [LOGIN]
292|29980|57475:57211|new| Options:
293|29980|57475:57211|new| -a, --all                     report password status on all accounts
294|29980|57475:57211|new| -d, --delete                  delete the password for the named account
295|29980|57475:57211|new| -e, --expire                  force expire the password for the named account
296|29980|57475:57211|new| -h, --help                    display this help message and exit
297|29980|57475:57211|new| -k, --keep-tokens             change password only if expired
298|29980|57475:57211|new| -i, --inactive INACTIVE       set password inactive after expiration
299|29980|57475:57211|new| to INACTIVE
300|29980|57475:57211|new| -l, --lock                    lock the password of the named account
301|29980|57475:57211|new| -n, --mindays MIN_DAYS        set minimum number of days before password
302|29980|57475:57211|new| change to MIN_DAYS
303|29980|57475:57211|new| -q, --quiet                   quiet mode
304|29980|57475:57211|new| -r, --repository REPOSITORY   change password in REPOSITORY repository
305|29980|57475:57211|new| -S, --status                  report password status on the named account
306|29980|57475:57211|new| -u, --unlock                  unlock the password of the named account
307|29980|57475:57211|new| -w, --warndays WARN_DAYS      set expiration warning days to WARN_DAYS
308|29980|57475:57211|new| -x, --maxdays MAX_DAYS        set maximum number of days before password
309|29980|57475:57211|new| change to MAX_DAYS
3102011-09-21 12:08:04|29980|57475:57211|new|Linux.pm:reserve(859)|Successfully set password on useracct: wv4973 on vmguest-98
3112011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
312|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'sed -i -e "/^wv4973 .*/d" /etc/sudoers' 2>&1
3132011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
3142011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5478)|SSH command executed on vmguest-98, returning (0, "")
3152011-09-21 12:08:04|29980|57475:57211|new|Linux.pm:reserve(874)|cleared wv4973 from /etc/sudoers
3162011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
317|29980|57475:57211|new| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'echo "wv4973 ALL= NOPASSWD: ALL" >> /etc/sudoers' 2>&1
3182011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5464)|run_ssh_command output:
3192011-09-21 12:08:04|29980|57475:57211|new|utils.pm:run_ssh_command(5478)|SSH command executed on vmguest-98, returning (0, "")
3202011-09-21 12:08:04|29980|57475:57211|new|Linux.pm:reserve(881)|added wv4973 to /etc/sudoers
3212011-09-21 12:08:04|29980|57475:57211|new|new.pm:reserve_computer(921)|OS module successfully reserved resources for this reservation
3222011-09-21 12:08:04|29980|57475:57211|new|utils.pm:mail(1255)|SUCCESS -- Sending mail To: alexander.patterson@csueastbay.edu, VCL -- CSUEB Ubuntu reservation
3232011-09-21 12:08:04|29980|57475:57211|new|new.pm:reserve_computer(1017)|IMTYPE_name= none calling notify_via
3242011-09-21 12:08:04|29980|57475:57211|new|new.pm:reserve_computer(1087)|updated sublog 20569 for node vmguest-98 IPaddress 137.145.56.198
3252011-09-21 12:08:04|29980|57475:57211|new|new.pm:process(363)|vmguest-98 with vmwarelinux-ubu-v0 successfully reserved
3262011-09-21 12:08:04|29980|57475:57211|new|utils.pm:insertloadlog(3875)|inserted computer=101, reserved, vmguest-98 successfully reserved with vmwarelinux-ubu-v0
3272011-09-21 12:08:04|29980|57475:57211|new|new.pm:process(375)|inserted computerloadlog entry, load state=reserved
3282011-09-21 12:08:04|29980|57475:57211|new|utils.pm:update_computer_state(2033)|computer 101 state updated to: reserved
3292011-09-21 12:08:04|29980|57475:57211|new|new.pm:process(399)|vmguest-98 state set to 'reserved'
3302011-09-21 12:08:04|29980|57475:57211|new|utils.pm:update_request_state(1991)|request 57475 state updated to: reserved, laststate to: new
3312011-09-21 12:08:04|29980|57475:57211|new|new.pm:process(408)|request state set to 'reserved'/'new'
3322011-09-21 12:08:04|29980|57475:57211|new|utils.pm:insertloadlog(3875)|inserted computer=101, info, vmguest-98 successfully set to reserved with vmwarelinux-ubu-v0
3332011-09-21 12:08:04|29980|57475:57211|new|new.pm:process(421)|inserted computerloadlog entry: vmguest-98 successfully set to reserved with vmwarelinux-ubu-v0
3342011-09-21 12:08:04|29980|57475:57211|new|new.pm:process(428)|exiting
3352011-09-21 12:08:04|29980|57475:57211|new|State.pm:DESTROY(829)|VCL::new destructor called, address: 1a191e70
3362011-09-21 12:08:04|29980|57475:57211|new|utils.pm:delete_computerloadlog_reservation(6742)|removing computerloadlog entries matching loadstate = begin
3372011-09-21 12:08:04|29980|57475:57211|new|utils.pm:delete_computerloadlog_reservation(6789)|deleted rows from computerloadlog for reservation id=57211
3382011-09-21 12:08:04|29980|57475:57211|new|State.pm:DESTROY(848)|number of database handles state process created: 1
3392011-09-21 12:08:04|29980|57475:57211|new|State.pm:DESTROY(876)|VCL::new process duration: 108 seconds
3402011-09-21 12:08:04|29980|57475:57211|new|VIM_SSH.pm:DESTROY(1685)|vim-cmd call count: 24
3412011-09-21 12:08:04|11998|vcld:REAPER(718)|VCL process exited for reservation 57211, PID: 29980, signal: CHLD
3422011-09-21 12:08:04|11998|vcld:main(167)|lastcheckin time updated for management node 1: 2011-09-21 12:08:04
3432011-09-21 12:08:04|11998|57475:57211|reserved|utils.pm:reservation_being_processed(8938)|computerloadlog 'begin' entry does NOT exist for reservation 57211
3442011-09-21 12:08:04|11998|57475:57211|reserved|utils.pm:run_command(9010)|executed command: pgrep -fl 'vcld [0-9]+:57211 ', pid: 30818, exit status: 1, output:
3452011-09-21 12:08:04|11998|57475:57211|reserved|utils.pm:is_management_node_process_running(9192)|process is NOT running, identifier: 'vcld [0-9]+:57211 '
3462011-09-21 12:08:04|11998|57475:57211|reserved|utils.pm:reservation_being_processed(8959)|reservation is NOT currently being processed
3472011-09-21 12:08:04|11998|57475:57211|reserved|vcld:main(278)|reservation 57211 is NOT already being processed
3482011-09-21 12:08:04|11998|57475:57211|reserved|utils.pm:get_request_info(4520)|standalone affiliation found: CSUEB
3492011-09-21 12:08:04|11998|57475:57211|reserved|utils.pm:get_request_info(4527)|found NULL uid setting standalone flag: wv4973, uid: NULL
3502011-09-21 12:08:04|11998|57475:57211|reserved|vcld:main(283)|retrieved request information from database
3512011-09-21 12:08:04|11998|57475:57211|reserved|DataStructure.pm:is_parent_reservation(991)|returning true: parent reservation ID for this request: 57211
3522011-09-21 12:08:04|11998|57475:57211|reserved|utils.pm:update_request_state(1991)|request 57475 state updated to: pending, laststate to: reserved
3532011-09-21 12:08:04|11998|57475:57211|reserved|utils.pm:insertloadlog(3875)|inserted computer=101, begin, beginning to process, state is reserved
3542011-09-21 12:08:04|11998|57475:57211|reserved|vcld:make_new_child(515)|loaded VCL::reserved module
3552011-09-21 12:08:04|11998|57475:57211|reserved|vcld:make_new_child(539)|current number of forked kids: 2
3562011-09-21 12:08:04|30832|57475:57211|reserved|vcld:make_new_child(555)|vcld environment variable set to 0 for this process
3572011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'test' key for VCL::reserved object from arguments
3582011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'forimaging' key for VCL::reserved object from arguments
3592011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'reservation' key for VCL::reserved object from arguments
3602011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'userid' key for VCL::reserved object from arguments
3612011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'daterequested' key for VCL::reserved object from arguments
3622011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'RESERVATIONID' key for VCL::reserved object from arguments
3632011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'UPDATED' key for VCL::reserved object from arguments
3642011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'SUBIMAGE' key for VCL::reserved object from arguments
3652011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'CHECKTIME' key for VCL::reserved object from arguments
3662011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'state' key for VCL::reserved object from arguments
3672011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'laststate' key for VCL::reserved object from arguments
3682011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'PRELOADONLY' key for VCL::reserved object from arguments
3692011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'laststateid' key for VCL::reserved object from arguments
3702011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'user' key for VCL::reserved object from arguments
3712011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'id' key for VCL::reserved object from arguments
3722011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'PID' key for VCL::reserved object from arguments
3732011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'DURATION' key for VCL::reserved object from arguments
3742011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'PPID' key for VCL::reserved object from arguments
3752011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'datemodified' key for VCL::reserved object from arguments
3762011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'RESERVATIONCOUNT' key for VCL::reserved object from arguments
3772011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'end' key for VCL::reserved object from arguments
3782011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'stateid' key for VCL::reserved object from arguments
3792011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'PARENTIMAGE' key for VCL::reserved object from arguments
3802011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'checkuser' key for VCL::reserved object from arguments
3812011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'preload' key for VCL::reserved object from arguments
3822011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'NOTICEINTERVAL' key for VCL::reserved object from arguments
3832011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'start' key for VCL::reserved object from arguments
3842011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(172)|set 'logid' key for VCL::reserved object from arguments
3852011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:create_mn_os_object(361)|VCL::Module::OS::Linux::ManagementNode module loaded
3862011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(196)|VCL::Module::OS::Linux::ManagementNode object created for image <not set>, address: 1a510da0
3872011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_automethod(834)|data structure updated: $self->request_data->{reservation}{0}{computer}{hostname}
388|30832|57475:57211|reserved| computer_hostname = vclnfs01.calstate.edu
3892011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_automethod(834)|data structure updated: $self->request_data->{reservation}{0}{computer}{NODENAME}
390|30832|57475:57211|reserved| computer_node_name = vclnfs01
3912011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_automethod(834)|data structure updated: $self->request_data->{reservation}{0}{computer}{SHORTNAME}
392|30832|57475:57211|reserved| computer_short_name = vclnfs01
3932011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_automethod(834)|data structure updated: $self->request_data->{reservation}{0}{computer}{IPaddress}
394|30832|57475:57211|reserved| computer_ip_address = 137.145.56.10
3952011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:create_mn_os_object(366)|VCL::Module::OS::Linux::ManagementNode OS object created, address: 1a510da0
3962011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(192)|VCL::reserved object created for state reserved, address: 1a330ab0
3972011-09-21 12:08:04|30832|57475:57211|reserved|State.pm:initialize(86)|obtained a database handle for this state process, stored as $ENV{dbh}
3982011-09-21 12:08:04|30832|57475:57211|reserved|State.pm:check_image_os(743)|no corrections need to be made, not an imaging request, returning 1
3992011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:is_parent_reservation(991)|returning true: parent reservation ID for this request: 57211
4002011-09-21 12:08:04|30832|57475:57211|reserved|utils.pm:rename_vcld_process(7098)|reservation count: 1
4012011-09-21 12:08:04|30832|57475:57211|reserved|utils.pm:rename_vcld_process(7117)|PARENTIMAGE: 1
4022011-09-21 12:08:04|30832|57475:57211|reserved|utils.pm:rename_vcld_process(7118)|SUBIMAGE: 0
4032011-09-21 12:08:04|30832|57475:57211|reserved|utils.pm:rename_vcld_process(7136)|renamed process to 'vcld 57475:57211 reserved vmguest-98>vclesxi20 vmwarelinux-ubu-v0 wv4973'
4042011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:is_parent_reservation(991)|returning true: parent reservation ID for this request: 57211
4052011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:is_parent_reservation(991)|returning true: parent reservation ID for this request: 57211
4062011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:create_os_object(298)|VCL::Module::OS::Linux module loaded
4072011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:create_mn_os_object(335)|management node OS object has already been created, address: 1a510da0, returning 1
4082011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(196)|VCL::Module::OS::Linux object created for image vmwarelinux-ubu-v0, address: 1a1fafc0
4092011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:create_os_object(304)|VCL::Module::OS::Linux OS object created for vmwarelinux-ubu-v0, address: 1a1fafc0
4102011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:create_provisioning_object(420)|VCL::Module::Provisioning::VMware::VMware module loaded
4112011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:create_mn_os_object(335)|management node OS object has already been created, address: 1a510da0, returning 1
4122011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(200)|VCL::Module::Provisioning::VMware::VMware object created for computer vmguest-98, address: 1a6080b0
4132011-09-21 12:08:04|30832|57475:57211|reserved|VMware.pm:initialize(230)|initializing VCL::Module::Provisioning::VMware::VMware object
4142011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_initialize(613)|computer ID argument was specified, retrieving data for computer ID: 1
4152011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_initialize(643)|image ID argument was specified: 4, DataStructure object will contain image information for the production imagerevision of this image
4162011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_initialize(660)|retrieved data for imagerevision ID: 4
4172011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_initialize(676)|retrieved data for image ID: 4
4182011-09-21 12:08:04|30832|57475:57211|reserved|VMware.pm:get_vmhost_datastructure(1190)|created DataStructure object for VM host: vclesxi20
4192011-09-21 12:08:04|30832|57475:57211|reserved|VMware.pm:initialize(249)|attempting to create OS object for the image currently loaded on the VM host: vclesxi20
420|30832|57475:57211|reserved| image name: noimage
421|30832|57475:57211|reserved| OS module: VCL::Module::OS::Linux::UnixLab
4222011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_initialize(613)|computer ID argument was specified, retrieving data for computer ID: 1
4232011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_initialize(643)|image ID argument was specified: 4, DataStructure object will contain image information for the production imagerevision of this image
4242011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_initialize(660)|retrieved data for imagerevision ID: 4
4252011-09-21 12:08:04|30832|57475:57211|reserved|DataStructure.pm:_initialize(676)|retrieved data for image ID: 4
4262011-09-21 12:08:04|30832|57475:57211|reserved|VMware.pm:get_vmhost_datastructure(1190)|created DataStructure object for VM host: vclesxi20
4272011-09-21 12:08:04|30832|57475:57211|reserved|VMware.pm:get_vmhost_os_object(1232)|attempting to load VM host OS module: VCL::Module::OS::Linux::UnixLab
4282011-09-21 12:08:04|30832|57475:57211|reserved|VMware.pm:get_vmhost_os_object(1238)|VM host OS module loaded: VCL::Module::OS::Linux::UnixLab
4292011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:create_mn_os_object(335)|management node OS object has already been created, address: 1a510da0, returning 1
4302011-09-21 12:08:04|30832|57475:57211|reserved|Module.pm:new(196)|VCL::Module::OS::Linux::UnixLab object created for image noimage, address: 1ac3f550
4312011-09-21 12:08:04|30832|57475:57211|reserved|VMware.pm:get_vmhost_os_object(1244)|VM host OS object created: VCL::Module::OS::Linux::UnixLab
4322011-09-21 12:08:05|30832|57475:57211|reserved|OS.pm:is_ssh_responding(401)|vclesxi20 is responding to SSH, port 22: open, port 24: closed
4332011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:initialize(254)|OS on VM host vclesxi20 will be controlled using a VCL::Module::OS::Linux::UnixLab OS object
4342011-09-21 12:08:05|30832|57475:57211|reserved|DataStructure.pm:_initialize(613)|computer ID argument was specified, retrieving data for computer ID: 1
4352011-09-21 12:08:05|30832|57475:57211|reserved|DataStructure.pm:_initialize(643)|image ID argument was specified: 4, DataStructure object will contain image information for the production imagerevision of this image
4362011-09-21 12:08:05|30832|57475:57211|reserved|DataStructure.pm:_initialize(660)|retrieved data for imagerevision ID: 4
4372011-09-21 12:08:05|30832|57475:57211|reserved|DataStructure.pm:_initialize(676)|retrieved data for image ID: 4
4382011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:get_vmhost_datastructure(1190)|created DataStructure object for VM host: vclesxi20
4392011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:get_vmhost_api_object(1293)|attempting to load VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
4402011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:get_vmhost_api_object(1299)|loaded VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
4412011-09-21 12:08:05|30832|57475:57211|reserved|Module.pm:new(172)|set 'vmhost_data' key for VCL::Module::Provisioning::VMware::vSphere_SDK object from arguments
4422011-09-21 12:08:05|30832|57475:57211|reserved|Module.pm:new(172)|set 'vmhost_os' key for VCL::Module::Provisioning::VMware::vSphere_SDK object from arguments
4432011-09-21 12:08:05|30832|57475:57211|reserved|Module.pm:create_mn_os_object(335)|management node OS object has already been created, address: 1a510da0, returning 1
4442011-09-21 12:08:05|30832|57475:57211|reserved|Module.pm:new(200)|VCL::Module::Provisioning::VMware::vSphere_SDK object created for computer vmguest-98, address: 1ac3f640
4452011-09-21 12:08:05|30832|57475:57211|reserved|vSphere_SDK.pm:initialize(1826)|vSphere SDK for Perl does not appear to be installed on this managment node, unable to load VMware vSphere SDK Perl modules
4462011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:get_vmhost_api_object(1313)|API object could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK
4472011-09-21 12:08:05|30832|57475:57211|reserved|DataStructure.pm:_initialize(613)|computer ID argument was specified, retrieving data for computer ID: 1
4482011-09-21 12:08:05|30832|57475:57211|reserved|DataStructure.pm:_initialize(643)|image ID argument was specified: 4, DataStructure object will contain image information for the production imagerevision of this image
4492011-09-21 12:08:05|30832|57475:57211|reserved|DataStructure.pm:_initialize(660)|retrieved data for imagerevision ID: 4
4502011-09-21 12:08:05|30832|57475:57211|reserved|DataStructure.pm:_initialize(676)|retrieved data for image ID: 4
4512011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:get_vmhost_datastructure(1190)|created DataStructure object for VM host: vclesxi20
4522011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:get_vmhost_api_object(1293)|attempting to load VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
4532011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:get_vmhost_api_object(1299)|loaded VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
4542011-09-21 12:08:05|30832|57475:57211|reserved|Module.pm:new(172)|set 'vmhost_data' key for VCL::Module::Provisioning::VMware::VIM_SSH object from arguments
4552011-09-21 12:08:05|30832|57475:57211|reserved|Module.pm:new(172)|set 'vmhost_os' key for VCL::Module::Provisioning::VMware::VIM_SSH object from arguments
4562011-09-21 12:08:05|30832|57475:57211|reserved|Module.pm:create_mn_os_object(335)|management node OS object has already been created, address: 1a510da0, returning 1
4572011-09-21 12:08:05|30832|57475:57211|reserved|Module.pm:new(200)|VCL::Module::Provisioning::VMware::VIM_SSH object created for computer vmguest-98, address: 1ac5baa0
4582011-09-21 12:08:05|30832|57475:57211|reserved|VIM_SSH.pm:initialize(138)|VIM executable available on VM host: vim-cmd
4592011-09-21 12:08:05|30832|57475:57211|reserved|VIM_SSH.pm:initialize(140)|VCL::Module::Provisioning::VMware::VIM_SSH object initialized
4602011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:get_vmhost_api_object(1320)|created API object: VCL::Module::Provisioning::VMware::VIM_SSH
4612011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:initialize(278)|VMware on VM host vclesxi20 will be controlled using vim-cmd via SSH
4622011-09-21 12:08:05|30832|57475:57211|reserved|VMware.pm:initialize(293)|VMware OS and API objects created for VM host vclesxi20:
463|30832|57475:57211|reserved| VM host OS object type: VCL::Module::OS::Linux::UnixLab
464|30832|57475:57211|reserved| VMware API object type: VCL::Module::Provisioning::VMware::VIM_SSH
4652011-09-21 12:08:06|30832|57475:57211|reserved|VMware.pm:get_vmhost_product_name(5405)|VMware product being used on VM host vclesxi20: 'VMware ESXi 4.1.0 build-348481'
4662011-09-21 12:08:06|30832|57475:57211|reserved|VMware.pm:initialize(307)|product: VMware ESXi 4.1.0 build-348481, OS object: VCL::Module::OS::Linux::UnixLab
4672011-09-21 12:08:06|30832|57475:57211|reserved|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd call count: 1 (hostsvc/datastore/listsummary)
4682011-09-21 12:08:06|30832|57475:57211|reserved|VIM_SSH.pm:_run_vim_cmd(208)|executed command on VM host vclesxi20: vim-cmd hostsvc/datastore/listsummary
4692011-09-21 12:08:06|30832|57475:57211|reserved|VMware.pm:get_datastore_info(5531)|retrieved datastore info from VM host:
470|30832|57475:57211|reserved| netappfiler02-sata1
471|30832|57475:57211|reserved| vclesxi20-local-vmfs
4722011-09-21 12:08:06|30832|57475:57211|reserved|VMware.pm:get_vmx_base_directory_path(2745)|determined vmx base directory path: /vmfs/volumes/netappfiler02-sata1
4732011-09-21 12:08:06|30832|57475:57211|reserved|Linux.pm:file_exists(1404)|'/vmfs/volumes/netappfiler02-sata1' exists on vclesxi20, files: 0, directories: 0, links: 1
4742011-09-21 12:08:06|30832|57475:57211|reserved|VMware.pm:initialize(324)|not checking if vmdk base directory exists because it is the same as the vmx base directory: /vmfs/volumes/netappfiler02-sata1
4752011-09-21 12:08:06|30832|57475:57211|reserved|Module.pm:create_provisioning_object(426)|VCL::Module::Provisioning::VMware::VMware provisioner object created for vmguest-98, address: 1a6080b0
4762011-09-21 12:08:06|30832|57475:57211|reserved|State.pm:initialize(126)|returning 1
4772011-09-21 12:08:06|30832|57475:57211|reserved|vcld:make_new_child(565)|VCL::reserved object created and initialized
4782011-09-21 12:08:06|30832|57475:57211|reserved|reserved.pm:process(116)|updated log table, set loaded time to now for id:20569
4792011-09-21 12:08:06|30832|57475:57211|reserved|reserved.pm:process(131)|imagemeta user group membership count = 0
4802011-09-21 12:08:06|30832|57475:57211|reserved|reserved.pm:process(151)|computer info: id=101, type=virtualmachine, hostname=vmguest-98
4812011-09-21 12:08:06|30832|57475:57211|reserved|reserved.pm:process(152)|user info: login_id id=wv4973, standalone=1
4822011-09-21 12:08:06|30832|57475:57211|reserved|reserved.pm:process(153)|imagemeta checkuser set to: 1
4832011-09-21 12:08:06|30832|57475:57211|reserved|reserved.pm:process(154)|formimaging set to: 0
4842011-09-21 12:08:06|30832|57475:57211|reserved|reserved.pm:process(165)|begin checking for user acknowledgement
4852011-09-21 12:08:06|30832|57475:57211|reserved|utils.pm:insertloadlog(3875)|inserted computer=101, info, reserved: waiting for user acknowledgement
4862011-09-21 12:08:06|30832|57475:57211|reserved|DataStructure.pm:get_reservation_remote_ip(1093)|reservation remote IP is not defined
4872011-09-21 12:08:09|11998|vcld:main(167)|lastcheckin time updated for management node 1: 2011-09-21 12:08:09
4882011-09-21 12:08:11|30832|57475:57211|reserved|DataStructure.pm:get_reservation_remote_ip(1093)|reservation remote IP is not defined
4892011-09-21 12:08:14|11998|vcld:main(167)|lastcheckin time updated for management node 1: 2011-09-21 12:08:14
4902011-09-21 12:08:16|30832|57475:57211|reserved|DataStructure.pm:get_reservation_remote_ip(1106)|retrieved remote IP for reservation 57211: 134.154.213.182
4912011-09-21 12:08:16|30832|57475:57211|reserved|reserved.pm:process(183)|user acknowledged, remote IP: 134.154.213.182
4922011-09-21 12:08:16|30832|57475:57211|reserved|reserved.pm:process(197)|calling VCL::Module::OS::Linux::grant_access() subroutine
4932011-09-21 12:08:16|30832|57475:57211|reserved|Linux.pm:grant_access(914)|In grant_access routine wv4973,vmguest-98
4942011-09-21 12:08:16|30832|57475:57211|reserved|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
495|30832|57475:57211|reserved| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'sed -i -e "/^AllowUsers .*/d" /etc/ssh/external_sshd_config' 2>&1
4962011-09-21 12:08:16|30832|57475:57211|reserved|utils.pm:run_ssh_command(5464)|run_ssh_command output:
497|30832|57475:57211|reserved| sed: can't read /etc/ssh/external_sshd_config: No such file or directory
4982011-09-21 12:08:16|30832|57475:57211|reserved|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-98, command:
499|30832|57475:57211|reserved| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'sed -i -e "/^AllowUsers .*/d" /etc/ssh/external_sshd_config' 2>&1
500|30832|57475:57211|reserved| returning (2, "sed: can't read /etc/ssh/exter...")
5012011-09-21 12:08:16|30832|57475:57211|reserved|Linux.pm:grant_access(917)|cleared AllowUsers directive from external_sshd_config
5022011-09-21 12:08:16|30832|57475:57211|reserved|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
503|30832|57475:57211|reserved| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'echo "AllowUsers wv4973" >> /etc/ssh/external_sshd_config' 2>&1
5042011-09-21 12:08:16|30832|57475:57211|reserved|utils.pm:run_ssh_command(5464)|run_ssh_command output:
5052011-09-21 12:08:16|30832|57475:57211|reserved|utils.pm:run_ssh_command(5478)|SSH command executed on vmguest-98, returning (0, "")
5062011-09-21 12:08:16|30832|57475:57211|reserved|Linux.pm:grant_access(925)|added AllowUsers wv4973 to external_sshd_config
5072011-09-21 12:08:16|30832|57475:57211|reserved|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
508|30832|57475:57211|reserved| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 '/etc/init.d/ext_sshd restart' 2>&1
5092011-09-21 12:08:17|30832|57475:57211|reserved|utils.pm:run_ssh_command(5464)|run_ssh_command output:
510|30832|57475:57211|reserved| bash: /etc/init.d/ext_sshd: No such file or directory
5112011-09-21 12:08:17|30832|57475:57211|reserved|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-98, command:
512|30832|57475:57211|reserved| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 '/etc/init.d/ext_sshd restart' 2>&1
513|30832|57475:57211|reserved| returning (127, "bash: /etc/init.d/ext_sshd: No...")
5142011-09-21 12:08:17|30832|57475:57211|reserved|Linux.pm:grant_access(944)|started ext_sshd on vmguest-98
5152011-09-21 12:08:17|30832|57475:57211|reserved|reserved.pm:process(198)|OS access has been granted on vmguest-98
5162011-09-21 12:08:17|30832|57475:57211|reserved|reserved.pm:process(213)|VCL::Module::OS::Linux->post_reserve() subroutine exists
5172011-09-21 12:08:17|30832|57475:57211|reserved|reserved.pm:process(216)|calling VCL::Module::OS::Linux->post_reserve() subroutine
5182011-09-21 12:08:17|30832|57475:57211|reserved|utils.pm:insertloadlog(3875)|inserted computer=101, info, calling VCL::Module::OS::Linux->post_reserve() subroutine
5192011-09-21 12:08:17|30832|57475:57211|reserved|Linux.pm:post_reserve(321)|initiating Linux post_reserve: vmwarelinux-ubu-v0 on vmguest-98
5202011-09-21 12:08:17|30832|57475:57211|reserved|Linux.pm:file_exists(1390)|file or directory does not exist on vmguest-98: '/etc/init.d/vcl_post_reserve'
5212011-09-21 12:08:17|30832|57475:57211|reserved|Linux.pm:post_reserve(325)|script does NOT exist: /etc/init.d/vcl_post_reserve
5222011-09-21 12:08:17|30832|57475:57211|reserved|reserved.pm:process(219)|performed OS post_reserve tasks for vmwarelinux-ubu-v0 on vmguest-98
5232011-09-21 12:08:17|30832|57475:57211|reserved|utils.pm:insertloadlog(3875)|inserted computer=101, info, performed OS post_reserve tasks for vmwarelinux-ubu-v0 on vmguest-98
5242011-09-21 12:08:17|30832|57475:57211|reserved|reserved.pm:process(295)|checkuser flag=1 imageosname is vmwarelinux, checking for user connection by wv4973
5252011-09-21 12:08:17|30832|57475:57211|reserved|utils.pm:check_connection(1603)|checking for connection by wv4973 on vmguest-98, attempt 1
5262011-09-21 12:08:17|30832|57475:57211|reserved|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
527|30832|57475:57211|reserved| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'netstat -an' 2>&1
5282011-09-21 12:08:17|30832|57475:57211|reserved|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
529|30832|57475:57211|reserved| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'who' 2>&1
5302011-09-21 12:08:17|30832|57475:57211|reserved|utils.pm:run_ssh_command(5464)|run_ssh_command output:
5312011-09-21 12:08:17|30832|57475:57211|reserved|utils.pm:run_ssh_command(5478)|SSH command executed on vmguest-98, returning (0, "")
5322011-09-21 12:08:19|11998|vcld:main(167)|lastcheckin time updated for management node 1: 2011-09-21 12:08:19
5332011-09-21 12:08:24|11998|vcld:main(167)|lastcheckin time updated for management node 1: 2011-09-21 12:08:24
5342011-09-21 12:08:30|11998|vcld:main(167)|lastcheckin time updated for management node 1: 2011-09-21 12:08:30
5352011-09-21 12:08:35|11998|vcld:main(167)|lastcheckin time updated for management node 1: 2011-09-21 12:08:35
5362011-09-21 12:08:37|30832|57475:57211|reserved|utils.pm:check_connection(1603)|checking for connection by wv4973 on vmguest-98, attempt 2
5372011-09-21 12:08:37|30832|57475:57211|reserved|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-98:
538|30832|57475:57211|reserved| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-98 'netstat -an' 2>&1
5392011-09-21 12:08:37|30832|57475:57211|reserved|reserved.pm:process(299)|retval_conn = connected
5402011-09-21 12:08:37|30832|57475:57211|reserved|reserved.pm:process(310)|134.154.213.182 connected to vmguest-98
5412011-09-21 12:08:37|30832|57475:57211|reserved|utils.pm:insertloadlog(3875)|inserted computer=101, connected, reserved: user connected to remote machine
5422011-09-21 12:08:37|30832|57475:57211|reserved|utils.pm:update_request_state(1991)|request 57475 state updated to: inuse, laststate to: reserved
5432011-09-21 12:08:37|30832|57475:57211|reserved|reserved.pm:process(315)|setting request into inuse state
5442011-09-21 12:08:37|30832|57475:57211|reserved|utils.pm:update_computer_state(2033)|computer 101 state updated to: inuse
5452011-09-21 12:08:37|30832|57475:57211|reserved|reserved.pm:process(323)|setting computerid 101 into inuse state
5462011-09-21 12:08:37|30832|57475:57211|reserved|reserved.pm:process(331)|updated lastcheck time for reservation 57211
5472011-09-21 12:08:37|30832|57475:57211|reserved|reserved.pm:process(338)|exiting
5482011-09-21 12:08:37|30832|57475:57211|reserved|State.pm:DESTROY(829)|VCL::reserved destructor called, address: 1a330ab0
5492011-09-21 12:08:37|30832|57475:57211|reserved|utils.pm:delete_computerloadlog_reservation(6742)|removing computerloadlog entries matching loadstate = begin
5502011-09-21 12:08:37|30832|57475:57211|reserved|utils.pm:delete_computerloadlog_reservation(6789)|deleted rows from computerloadlog for reservation id=57211
5512011-09-21 12:08:37|30832|57475:57211|reserved|State.pm:DESTROY(848)|number of database handles state process created: 1
5522011-09-21 12:08:37|30832|57475:57211|reserved|State.pm:DESTROY(876)|VCL::reserved process duration: 33 seconds
5532011-09-21 12:08:37|30832|57475:57211|reserved|VIM_SSH.pm:DESTROY(1685)|vim-cmd call count: 1
5542011-09-21 12:08:37|11998|vcld:REAPER(718)|VCL process exited for reservation 57211, PID: 30832, signal: CHLD
5552011-09-21 12:08:37|11998|vcld:main(167)|lastcheckin time updated for management node 1: 2011-09-21 12:08:37
5562011-09-21 12:08:42|11998|vcld:main(167)|lastcheckin time updated for management node 1: 2011-09-21 12:08:42
5572011-09-21 12:08:44|30465|57476:57212|reload|OS.pm:wait_for_response(459)|waited 60 seconds for vmguest-22 to boot
5582011-09-21 12:08:44|30465|57476:57212|reload|OS.pm:wait_for_response(463)|waiting for vmguest-22 to respond to SSH, maximum of 600 seconds
5592011-09-21 12:08:44|30465|57476:57212|reload|Module.pm:code_loop_timeout(742)|waiting for vmguest-22 to respond to SSH, maximum of 600 seconds
5602011-09-21 12:08:44|30465|57476:57212|reload|Module.pm:code_loop_timeout(759)|attempt 1: waiting for vmguest-22 to respond to SSH
5612011-09-21 12:08:45|30465|57476:57212|reload|OS.pm:is_ssh_responding(401)|vmguest-22 is responding to SSH, port 22: open, port 24: closed
5622011-09-21 12:08:45|30465|57476:57212|reload|Module.pm:code_loop_timeout(762)|waiting for vmguest-22 to respond to SSH, code returned true
5632011-09-21 12:08:45|30465|57476:57212|reload|OS.pm:wait_for_response(473)|vmguest-22 is responding to SSH after 61 seconds
5642011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-22:
565|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 'echo HWNmMJoA3xvnyhT | /usr/bin/passwd -f root --stdin' 2>&1
5662011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5464)|run_ssh_command output:
567|30465|57476:57212|reload| /usr/bin/passwd: invalid option -- 'f'
568|30465|57476:57212|reload| Usage: passwd [options] [LOGIN]
569|30465|57476:57212|reload| Options:
570|30465|57476:57212|reload| -a, --all                     report password status on all accounts
571|30465|57476:57212|reload| -d, --delete                  delete the password for the named account
572|30465|57476:57212|reload| -e, --expire                  force expire the password for the named account
573|30465|57476:57212|reload| -h, --help                    display this help message and exit
574|30465|57476:57212|reload| -k, --keep-tokens             change password only if expired
575|30465|57476:57212|reload| -i, --inactive INACTIVE       set password inactive after expiration
576|30465|57476:57212|reload| to INACTIVE
577|30465|57476:57212|reload| -l, --lock                    lock the password of the named account
578|30465|57476:57212|reload| -n, --mindays MIN_DAYS        set minimum number of days before password
579|30465|57476:57212|reload| change to MIN_DAYS
580|30465|57476:57212|reload| -q, --quiet                   quiet mode
581|30465|57476:57212|reload| -r, --repository REPOSITORY   change password in REPOSITORY repository
582|30465|57476:57212|reload| -S, --status                  report password status on the named account
583|30465|57476:57212|reload| -u, --unlock                  unlock the password of the named account
584|30465|57476:57212|reload| -w, --warndays WARN_DAYS      set expiration warning days to WARN_DAYS
585|30465|57476:57212|reload| -x, --maxdays MAX_DAYS        set maximum number of days before password
586|30465|57476:57212|reload| change to MAX_DAYS
5872011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-22, command:
588|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 'echo HWNmMJoA3xvnyhT | /usr/bin/passwd -f root --stdin' 2>&1
589|30465|57476:57212|reload| returning (6, "/usr/bin/passwd: invalid optio...")
5902011-09-21 12:08:45|30465|57476:57212|reload|Linux.pm:changepasswd(982)|changed password for account: root, output:
591|30465|57476:57212|reload| /usr/bin/passwd: invalid option -- 'f'
592|30465|57476:57212|reload| Usage: passwd [options] [LOGIN]
593|30465|57476:57212|reload| Options:
594|30465|57476:57212|reload| -a, --all                     report password status on all accounts
595|30465|57476:57212|reload| -d, --delete                  delete the password for the named account
596|30465|57476:57212|reload| -e, --expire                  force expire the password for the named account
597|30465|57476:57212|reload| -h, --help                    display this help message and exit
598|30465|57476:57212|reload| -k, --keep-tokens             change password only if expired
599|30465|57476:57212|reload| -i, --inactive INACTIVE       set password inactive after expiration
600|30465|57476:57212|reload| to INACTIVE
601|30465|57476:57212|reload| -l, --lock                    lock the password of the named account
602|30465|57476:57212|reload| -n, --mindays MIN_DAYS        set minimum number of days before password
603|30465|57476:57212|reload| change to MIN_DAYS
604|30465|57476:57212|reload| -q, --quiet                   quiet mode
605|30465|57476:57212|reload| -r, --repository REPOSITORY   change password in REPOSITORY repository
606|30465|57476:57212|reload| -S, --status                  report password status on the named account
607|30465|57476:57212|reload| -u, --unlock                  unlock the password of the named account
608|30465|57476:57212|reload| -w, --warndays WARN_DAYS      set expiration warning days to WARN_DAYS
609|30465|57476:57212|reload| -x, --maxdays MAX_DAYS        set maximum number of days before password
610|30465|57476:57212|reload| change to MAX_DAYS
6112011-09-21 12:08:45|30465|57476:57212|reload|Linux.pm:post_load(240)|successfully changed root password on vmguest-22
6122011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-22:
613|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 '/etc/init.d/ext_sshd stop' 2>&1
6142011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5464)|run_ssh_command output:
615|30465|57476:57212|reload| bash: /etc/init.d/ext_sshd: No such file or directory
6162011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-22, command:
617|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 '/etc/init.d/ext_sshd stop' 2>&1
618|30465|57476:57212|reload| returning (127, "bash: /etc/init.d/ext_sshd: No...")
6192011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-22:
620|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 'sed -i -e "/^AllowUsers .*/d" /etc/ssh/external_sshd_config' 2>&1
6212011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5464)|run_ssh_command output:
622|30465|57476:57212|reload| sed: can't read /etc/ssh/external_sshd_config: No such file or directory
6232011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-22, command:
624|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 'sed -i -e "/^AllowUsers .*/d" /etc/ssh/external_sshd_config' 2>&1
625|30465|57476:57212|reload| returning (2, "sed: can't read /etc/ssh/exter...")
6262011-09-21 12:08:45|30465|57476:57212|reload|Linux.pm:post_load(259)|cleared AllowUsers directive from external_sshd_config
6272011-09-21 12:08:45|30465|57476:57212|reload|Linux.pm:post_load(266)|calling clear_private_keys
6282011-09-21 12:08:45|30465|57476:57212|reload|Linux.pm:clear_private_keys(443)|perparing to clear known identity keys
6292011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-22:
630|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 '/bin/rm -f /root/.ssh/id_rsa /root/.ssh/id_rsa.pub' 2>&1
6312011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5464)|run_ssh_command output:
6322011-09-21 12:08:45|30465|57476:57212|reload|utils.pm:run_ssh_command(5478)|SSH command executed on vmguest-22, returning (0, "")
6332011-09-21 12:08:45|30465|57476:57212|reload|Linux.pm:clear_private_keys(451)|cleared any id_rsa keys from /root/.ssh
6342011-09-21 12:08:45|30465|57476:57212|reload|Linux.pm:post_load(268)|cleared known identity keys
6352011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:get_network_configuration(2614)|retrieved network configuration:
636|30465|57476:57212|reload| : {
637|30465|57476:57212|reload| :   "eth0" => {
638|30465|57476:57212|reload| :     "ip_address" => {
639|30465|57476:57212|reload| :       "10.10.10.102" => "255.255.248.0"
640|30465|57476:57212|reload| :     },
641|30465|57476:57212|reload| :     "name" => "eth0",
642|30465|57476:57212|reload| :     "physical_address" => "00:50:56:2a:3b:2b"
643|30465|57476:57212|reload| :   },
644|30465|57476:57212|reload| :   "eth1" => {
645|30465|57476:57212|reload| :     "ip_address" => {
646|30465|57476:57212|reload| :       "137.145.58.171" => "255.255.252.0"
647|30465|57476:57212|reload| :     },
648|30465|57476:57212|reload| :     "name" => "eth1",
649|30465|57476:57212|reload| :     "physical_address" => "00:50:56:2a:3b:2c"
650|30465|57476:57212|reload| :   },
651|30465|57476:57212|reload| :   "lo" => {
652|30465|57476:57212|reload| :     "ip_address" => {
653|30465|57476:57212|reload| :       "127.0.0.1" => "255.0.0.0"
654|30465|57476:57212|reload| :     },
655|30465|57476:57212|reload| :     "name" => "lo"
656|30465|57476:57212|reload| :   }
657|30465|57476:57212|reload| : }
6582011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:get_network_configuration(2617)|network configuration has already been retrieved
6592011-09-21 12:08:46|30465|57476:57212|reload|DataStructure.pm:get_computer_private_ip_address(1581)|attempting to retrieve private IP address for computer: vmguest-22
6602011-09-21 12:08:46|30465|57476:57212|reload|DataStructure.pm:get_computer_private_ip_address(1585)|retrieved contents of /etc/hosts on this management node, contains 529 lines
6612011-09-21 12:08:46|30465|57476:57212|reload|DataStructure.pm:get_computer_private_ip_address(1645)|returning IP address from /etc/hosts file: 10.10.10.102
6622011-09-21 12:08:46|30465|57476:57212|reload|OS.pm:get_public_interface_name(898)|ignoring private interface: eth0 (10.10.10.102)
6632011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:is_valid_ip_address(9266)|IP address is valid: 137.145.58.171
6642011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:is_public_ip_address(9412)|public IP address: 137.145.58.171, returning 1
6652011-09-21 12:08:46|30465|57476:57212|reload|OS.pm:get_public_interface_name(919)|determined public interface name: eth1 (137.145.58.171)
6662011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:get_network_configuration(2645)|returning public network configuration
6672011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:get_public_ip_address(2746)|retrieved public IP address: 137.145.58.171
6682011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:update_public_hostname(376)|retrieved public IP address of vmguest-22: 137.145.58.171
6692011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:run_command(9010)|executed command: /bin/ipcalc --hostname 137.145.58.171, pid: 30981, exit status: 1, output:
670|30465|57476:57212|reload| ipcalc: cannot find hostname for 137.145.58.171: Unknown host
6712011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:update_public_hostname(391)|failed to determine registered public hostname of vmguest-22 (137.145.58.171), command: '/bin/ipcalc --hostname 137.145.58.171', output:
672|30465|57476:57212|reload| ipcalc: cannot find hostname for 137.145.58.171: Unknown host
6732011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-22:
674|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 '/bin/ipcalc --hostname 137.145.58.171' 2>&1
6752011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:run_ssh_command(5464)|run_ssh_command output:
676|30465|57476:57212|reload| bash: /bin/ipcalc: No such file or directory
6772011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:run_ssh_command(5474)|SSH command executed on vmguest-22, command:
678|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 '/bin/ipcalc --hostname 137.145.58.171' 2>&1
679|30465|57476:57212|reload| returning (127, "bash: /bin/ipcalc: No such fil...")
680
681|30465|57476:57212|reload| ---- WARNING ---- 
682|30465|57476:57212|reload| 2011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:update_public_hostname(405)|failed to determine registered public hostname of vmguest-22 (137.145.58.171) by running ipcalc on either the management node or vmguest-22, command: '/bin/ipcalc --hostname 137.145.58.171', output:
683|30465|57476:57212|reload| bash: /bin/ipcalc: No such file or directory
684|30465|57476:57212|reload| ( 0) Linux.pm, update_public_hostname (line: 405)
685|30465|57476:57212|reload| (-1) Linux.pm, post_load (line: 273)
686|30465|57476:57212|reload| (-2) VMware.pm, load (line: 415)
687|30465|57476:57212|reload| (-3) new.pm, reload_image (line: 618)
688|30465|57476:57212|reload| (-4) new.pm, process (line: 248)
689|30465|57476:57212|reload| (-5) vcld, make_new_child (line: 568)
690
6912011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:file_exists(1390)|file or directory does not exist on vmguest-22: '/etc/init.d/vcl_post_load'
6922011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:run_script(1307)|script does NOT exist: /etc/init.d/vcl_post_load
6932011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:post_load(280)|/etc/init.d/vcl_post_load does not exist in image: vmwarelinux-ubu-v0
6942011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:activate_interfaces(2465)|attempting to find network interfaces with an active link
6952011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-22:
696|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 'ip link' 2>&1
6972011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:execute(1267)|executed command: 'ip link', exit status: 0, output:
698|30465|57476:57212|reload| 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 16436 qdisc noqueue state UNKNOWN
699|30465|57476:57212|reload| link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
700|30465|57476:57212|reload| 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UNKNOWN qlen 1000
701|30465|57476:57212|reload| link/ether 00:50:56:2a:3b:2b brd ff:ff:ff:ff:ff:ff
702|30465|57476:57212|reload| 3: eth1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UNKNOWN qlen 1000
703|30465|57476:57212|reload| link/ether 00:50:56:2a:3b:2c brd ff:ff:ff:ff:ff:ff
7042011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:activate_interfaces(2474)|found interface names:
705|30465|57476:57212|reload| eth0
706|30465|57476:57212|reload| eth1
7072011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:find_files(2074)|attempting to find files on vmguest-22, base directory path: '/etc/sysconfig/network-scripts/', pattern: ifcfg-eth*, command: find "/etc/sysconfig/network-scripts/" -iname "ifcfg-eth*"
7082011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:find_files(2082)|base directory does not exist on vmguest-22: /etc/sysconfig/network-scripts/
7092011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:find_files(2092)|matching file count: 0
7102011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:activate_interfaces(2479)|found existing ifcfg-eth* files:
7112011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:activate_interfaces(2491)|ifcfg file does not exist for eth0
7122011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:activate_interfaces(2503)|attempting to echo contents to /etc/sysconfig/network-scripts/ifcfg-eth0:
713|30465|57476:57212|reload| DEVICE=eth0
714|30465|57476:57212|reload| BOOTPROTO=dhcp
715|30465|57476:57212|reload| STARTMODE=onboot
716|30465|57476:57212|reload| ONBOOT=yes
7172011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-22:
718|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 'echo  "DEVICE=eth0
719|30465|57476:57212|reload| BOOTPROTO=dhcp
720|30465|57476:57212|reload| STARTMODE=onboot
721|30465|57476:57212|reload| ONBOOT=yes
722|30465|57476:57212|reload| " > /etc/sysconfig/network-scripts/ifcfg-eth0 && ifup eth0' 2>&1
7232011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:execute(1267)|executed command: 'echo  "DEVICE=eth0
724|30465|57476:57212|reload| BOOTPROTO=dhcp
725|30465|57476:57212|reload| STARTMODE=onboot
726|30465|57476:57212|reload| ONBOOT=yes
727|30465|57476:57212|reload| " > /etc/sysconfig/network-scripts/ifcfg-eth0 && ifup eth0', exit status: 1, output:
728|30465|57476:57212|reload| bash: line 4: /etc/sysconfig/network-scripts/ifcfg-eth0: No such file or directory
729
730|30465|57476:57212|reload| ---- WARNING ---- 
731|30465|57476:57212|reload| 2011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:activate_interfaces(2513)|failed to create /etc/sysconfig/network-scripts/ifcfg-eth0 and enable interface: eth0, output:
732|30465|57476:57212|reload| bash: line 4: /etc/sysconfig/network-scripts/ifcfg-eth0: No such file or directory
733|30465|57476:57212|reload| ( 0) Linux.pm, activate_interfaces (line: 2513)
734|30465|57476:57212|reload| (-1) Linux.pm, post_load (line: 291)
735|30465|57476:57212|reload| (-2) VMware.pm, load (line: 415)
736|30465|57476:57212|reload| (-3) new.pm, reload_image (line: 618)
737|30465|57476:57212|reload| (-4) new.pm, process (line: 248)
738|30465|57476:57212|reload| (-5) vcld, make_new_child (line: 568)
739
7402011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:activate_interfaces(2491)|ifcfg file does not exist for eth1
7412011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:activate_interfaces(2503)|attempting to echo contents to /etc/sysconfig/network-scripts/ifcfg-eth1:
742|30465|57476:57212|reload| DEVICE=eth1
743|30465|57476:57212|reload| BOOTPROTO=dhcp
744|30465|57476:57212|reload| STARTMODE=onboot
745|30465|57476:57212|reload| ONBOOT=yes
7462011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-22:
747|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 'echo  "DEVICE=eth1
748|30465|57476:57212|reload| BOOTPROTO=dhcp
749|30465|57476:57212|reload| STARTMODE=onboot
750|30465|57476:57212|reload| ONBOOT=yes
751|30465|57476:57212|reload| " > /etc/sysconfig/network-scripts/ifcfg-eth1 && ifup eth1' 2>&1
7522011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:execute(1267)|executed command: 'echo  "DEVICE=eth1
753|30465|57476:57212|reload| BOOTPROTO=dhcp
754|30465|57476:57212|reload| STARTMODE=onboot
755|30465|57476:57212|reload| ONBOOT=yes
756|30465|57476:57212|reload| " > /etc/sysconfig/network-scripts/ifcfg-eth1 && ifup eth1', exit status: 1, output:
757|30465|57476:57212|reload| bash: line 4: /etc/sysconfig/network-scripts/ifcfg-eth1: No such file or directory
758
759|30465|57476:57212|reload| ---- WARNING ---- 
760|30465|57476:57212|reload| 2011-09-21 12:08:46|30465|57476:57212|reload|Linux.pm:activate_interfaces(2513)|failed to create /etc/sysconfig/network-scripts/ifcfg-eth1 and enable interface: eth1, output:
761|30465|57476:57212|reload| bash: line 4: /etc/sysconfig/network-scripts/ifcfg-eth1: No such file or directory
762|30465|57476:57212|reload| ( 0) Linux.pm, activate_interfaces (line: 2513)
763|30465|57476:57212|reload| (-1) Linux.pm, post_load (line: 291)
764|30465|57476:57212|reload| (-2) VMware.pm, load (line: 415)
765|30465|57476:57212|reload| (-3) new.pm, reload_image (line: 618)
766|30465|57476:57212|reload| (-4) new.pm, process (line: 248)
767|30465|57476:57212|reload| (-5) vcld, make_new_child (line: 568)
768
7692011-09-21 12:08:46|30465|57476:57212|reload|utils.pm:run_ssh_command(5380)|executing SSH command on vmguest-22:
770|30465|57476:57212|reload| /usr/bin/ssh -i /etc/vcl/vcl.key  -o StrictHostKeyChecking=no -l root -p 22 -x vmguest-22 'sed -i -e '/vcld_post_load.*/d' currentimage.txt && echo >> currentimage.txt && echo "vcld_post_load=success (Wed Sep 21 12:08:46 2011)" >> currentimage.txt && sed -i -e '/^[\s\r\n]*$/d' currentimage.txt && dos2unix currentimage.txt' 2>&1
771
772|30465|57476:57212|reload| ---- WARNING ---- 
773|30465|57476:57212|reload| 2011-09-21 12:08:47|30465|57476:57212|reload|OS.pm:set_vcld_post_load_status(772)|failed to add line to currentimage.txt on vmguest-22: 'vcld_post_load=success (Wed Sep 21 12:08:46 2011)', exit status: 2, output:
774|30465|57476:57212|reload| sed: can't read currentimage.txt: No such file or directory
775|30465|57476:57212|reload| ( 0) OS.pm, set_vcld_post_load_status (line: 772)
776|30465|57476:57212|reload| (-1) Linux.pm, post_load (line: 294)
777|30465|57476:57212|reload| (-2) VMware.pm, load (line: 415)
778|30465|57476:57212|reload| (-3) new.pm, reload_image (line: 618)
779|30465|57476:57212|reload| (-4) new.pm, process (line: 248)
780|30465|57476:57212|reload| (-5) vcld, make_new_child (line: 568)
781
7822011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:insertloadlog(3875)|inserted computer=26, loadimagecomplete, performed OS post-load tasks on vmguest-22
7832011-09-21 12:08:47|30465|57476:57212|reload|new.pm:reload_image(619)|vmwarelinux-ubu-v0 was successfully reloaded on vmguest-22
7842011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:insertloadlog(3875)|inserted computer=26, loadimagecomplete, vmwarelinux-ubu-v0 was successfully reloaded on vmguest-22
7852011-09-21 12:08:47|30465|57476:57212|reload|new.pm:reload_image(628)|node ready: successfully reloaded vmguest-22 with vmwarelinux-ubu-v0
7862011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:insertloadlog(3875)|inserted computer=26, nodeready, vmguest-22 was reloaded with vmwarelinux-ubu-v0
7872011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:update_currentimage(6088)|updating computer 26: image=29, imagerevision=29
7882011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:update_currentimage(6118)|updated currentimageid and imagerevision id for computer id 26
7892011-09-21 12:08:47|30465|57476:57212|reload|new.pm:reload_image(633)|updated computer table for vmguest-22: currentimageid=29
7902011-09-21 12:08:47|30465|57476:57212|reload|new.pm:reload_image(640)|returning 1
7912011-09-21 12:08:47|30465|57476:57212|reload|new.pm:process(248)|vmguest-22 is loaded with vmwarelinux-ubu-v0
7922011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:update_computer_state(2033)|computer 26 state updated to: available
7932011-09-21 12:08:47|30465|57476:57212|reload|new.pm:process(399)|vmguest-22 state set to 'available'
7942011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:update_request_state(1991)|request 57476 state updated to: complete, laststate to: reload
7952011-09-21 12:08:47|30465|57476:57212|reload|new.pm:process(408)|request state set to 'complete'/'reload'
7962011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:insertloadlog(3875)|inserted computer=26, info, vmguest-22 successfully set to available with vmwarelinux-ubu-v0
7972011-09-21 12:08:47|30465|57476:57212|reload|new.pm:process(421)|inserted computerloadlog entry: vmguest-22 successfully set to available with vmwarelinux-ubu-v0
7982011-09-21 12:08:47|30465|57476:57212|reload|new.pm:process(428)|exiting
7992011-09-21 12:08:47|30465|57476:57212|reload|State.pm:DESTROY(829)|VCL::new destructor called, address: 1a52a300
8002011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:delete_computerloadlog_reservation(6742)|removing computerloadlog entries matching loadstate = begin
8012011-09-21 12:08:47|30465|57476:57212|reload|utils.pm:delete_computerloadlog_reservation(6789)|deleted rows from computerloadlog for reservation id=57212
8022011-09-21 12:08:47|30465|57476:57212|reload|State.pm:DESTROY(848)|number of database handles state process created: 1
8032011-09-21 12:08:47|30465|57476:57212|reload|State.pm:DESTROY(876)|VCL::new process duration: 104 seconds