[Haizea] unmatching host CPU information between ONE and Haizea

Fri Oct 15 22:11:18 CDT 2010

The file is too long. I'll remove some redundant lines.
I tried to send an attachment but failed.
So I'll paste it into mail.

Thank you.
Here is my configuration file:
[general]
loglevel: VDEBUG
logfile: /vrstorm/cloudadmin/haizea/var/haizea.log
mode: opennebula
lease-preparation: unmanaged
persistence-file: ~/haizea/var/leases.dat
[scheduling]
policy-preemption: ar-preempts-everything
wakeup-interval: 1
backfilling: off
suspension: all
suspend-rate: 32
resume-rate: 32
suspendresume-exclusion: global
migration: yes
[accounting]
probes: ar best-effort immediate cpu-utilization
[opennebula]
host: localhost

The VDEBUG enabled haizea.log is here:

[ ] ENACT.ONE.INFO Fetching nodes from OpenNebula
[ ] ENACT.ONE.INFO Fetched node 1 node5 Disk: 80000 | CPU: 100 | Memory: 24097
[ ] ENACT.ONE.INFO Fetched node 2 node4 Disk: 80000 | CPU: 100 | Memory: 64434
[ ] ENACT.ONE.INFO Fetched 2 nodes from OpenNebula
[2010-10-14 15:10:34.78] RM Starting resource manager
[2010-10-14 15:10:34.78] CLOCK Starting clock
[2010-10-14 15:10:34.80] CLOCK Waking up to manage resources
[2010-10-14 15:10:34.80] CLOCK Wake-up time recorded as 2010-10-14 15:10:35.00
[2010-10-14 15:10:34.78] RPCSERVER RPC server started on port 42493
[2010-10-14 15:10:34.85] RM Requesting leases
[2010-10-14 15:10:34.85] RM Running scheduling function
[2010-10-14 15:10:34.86] CLOCK Going back to sleep. Waking up at 2010-10-14 15:10:36.00 to see if something interesting has happened by then.
[2010-10-14 15:10:36.00] CLOCK Waking up to manage resources
...
[2010-10-14 15:11:20.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:20.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:20.00
[2010-10-14 15:11:20.09] RM Requesting leases
[2010-10-14 15:11:20.09] RM Running scheduling function
[2010-10-14 15:11:20.10] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:21.00 to see if something interesting has happened by then.
[2010-10-14 15:11:21.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:21.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:21.00
[2010-10-14 15:11:21.05] RM Requesting leases
[2010-10-14 15:11:21.05] RM Running scheduling function
[2010-10-14 15:11:21.05] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:22.00 to see if something interesting has happened by then.
[2010-10-14 15:11:22.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:22.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:22.00
[2010-10-14 15:11:22.06] RM Requesting leases
[2010-10-14 15:11:22.06] LSCHED Lease #1 has been requested.
[2010-10-14 15:11:22.06] LEASES __________________________________________________
[2010-10-14 15:11:22.06] LEASES Lease ID : 1
[2010-10-14 15:11:22.06] LEASES Type : Best-effort
[2010-10-14 15:11:22.06] LEASES Submission time: 2010-10-14 15:11:21.00
[2010-10-14 15:11:22.06] LEASES Start : REQ: Unspecified | SCH: None | ACT: None
[2010-10-14 15:11:22.06] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:11:22.06] LEASES State : New
[2010-10-14 15:11:22.06] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:11:22.06] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1bf11850>
[2010-10-14 15:11:22.06] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:11:22.06] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:11:22.06] LEASES --------------------------------------------------
[2010-10-14 15:11:22.06] LSCHED Lease #1 has been marked as pending.
[2010-10-14 15:11:22.06] RM Running scheduling function
[2010-10-14 15:11:22.06] LSCHED Queued best-effort lease request #1, 1 nodes for 36500:00:00:00.00.
[2010-10-14 15:11:22.07] LSCHED Next request in the queue is lease 1. Attempting to schedule...
[2010-10-14 15:11:22.07] LEASES __________________________________________________
[2010-10-14 15:11:22.07] LEASES Lease ID : 1
[2010-10-14 15:11:22.07] LEASES Type : Best-effort
[2010-10-14 15:11:22.07] LEASES Submission time: 2010-10-14 15:11:21.00
[2010-10-14 15:11:22.07] LEASES Start : REQ: Unspecified | SCH: None | ACT: None
[2010-10-14 15:11:22.07] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:11:22.07] LEASES State : Queued
[2010-10-14 15:11:22.07] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:11:22.07] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1bf11850>
[2010-10-14 15:11:22.07] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:11:22.07] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:11:22.07] LEASES --------------------------------------------------
[2010-10-14 15:11:22.07] VMSCHED Attempting to map from 2010-10-14 15:11:32.00 to 2110-09-20 15:11:32.00
[2010-10-14 15:11:22.07] VMSCHED This lease can be scheduled from 2010-10-14 15:11:32.00 to 2110-09-20 15:11:32.00 (full duration)
[2010-10-14 15:11:22.07] VMSCHED Lease #1 has been scheduled on nodes [1] from 2010-10-14 15:11:32.00 to 2110-09-20 15:11:32.00
[2010-10-14 15:11:22.07] LEASES __________________________________________________
[2010-10-14 15:11:22.07] LEASES Lease ID : 1
[2010-10-14 15:11:22.07] LEASES Type : Best-effort
[2010-10-14 15:11:22.07] LEASES Submission time: 2010-10-14 15:11:21.00
[2010-10-14 15:11:22.07] LEASES Start : REQ: Unspecified | SCH: None | ACT: None
[2010-10-14 15:11:22.07] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:11:22.07] LEASES State : Ready
[2010-10-14 15:11:22.07] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:11:22.07] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1bf11850>
[2010-10-14 15:11:22.07] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:11:22.07] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:11:22.07] LEASES Type : VM
[2010-10-14 15:11:22.07] LEASES Nodes : V1 -> P1
[2010-10-14 15:11:22.07] LEASES Start : 2010-10-14 15:11:32.00
[2010-10-14 15:11:22.07] LEASES End : 2110-09-20 15:11:32.00
[2010-10-14 15:11:22.07] LEASES State : Scheduled
[2010-10-14 15:11:22.07] LEASES Resources :
N1: 0:100 1:1000 2:0
[2010-10-14 15:11:22.07] LEASES --
[2010-10-14 15:11:22.07] LEASES Type : SHUTDOWN
[2010-10-14 15:11:22.07] LEASES Start : 2110-09-20 15:11:32.00
[2010-10-14 15:11:22.07] LEASES End : 2110-09-20 15:11:32.00
[2010-10-14 15:11:22.07] LEASES State : Scheduled
[2010-10-14 15:11:22.07] LEASES Resources :
N1: 0:100 1:1000 2:0
[2010-10-14 15:11:22.07] LEASES ##
[2010-10-14 15:11:22.07] LEASES --------------------------------------------------
[2010-10-14 15:11:22.07] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:23.00 to see if something interesting has happened by then.
[2010-10-14 15:11:23.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:23.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:23.00
[2010-10-14 15:11:23.06] RM Requesting leases
[2010-10-14 15:11:23.06] RM Running scheduling function
[2010-10-14 15:11:23.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:24.00 to see if something interesting has happened by then.
[2010-10-14 15:11:24.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:24.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:24.00
[2010-10-14 15:11:24.06] RM Requesting leases
[2010-10-14 15:11:24.06] RM Running scheduling function
[2010-10-14 15:11:24.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:25.00 to see if something interesting has happened by then.
[2010-10-14 15:11:25.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:25.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:25.00
[2010-10-14 15:11:25.06] RM Requesting leases
[2010-10-14 15:11:25.06] RM Running scheduling function
[2010-10-14 15:11:25.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:26.00 to see if something interesting has happened by then.
[2010-10-14 15:11:26.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:26.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:26.00
[2010-10-14 15:11:26.06] RM Requesting leases
[2010-10-14 15:11:26.06] RM Running scheduling function
[2010-10-14 15:11:26.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:27.00 to see if something interesting has happened by then.
[2010-10-14 15:11:27.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:27.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:27.00
[2010-10-14 15:11:27.06] RM Requesting leases
[2010-10-14 15:11:27.06] RM Running scheduling function
[2010-10-14 15:11:27.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:28.00 to see if something interesting has happened by then.
[2010-10-14 15:11:28.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:28.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:28.00
[2010-10-14 15:11:28.06] RM Requesting leases
[2010-10-14 15:11:28.06] RM Running scheduling function
[2010-10-14 15:11:28.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:29.00 to see if something interesting has happened by then.
[2010-10-14 15:11:29.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:29.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:29.00
[2010-10-14 15:11:29.06] RM Requesting leases
[2010-10-14 15:11:29.06] RM Running scheduling function
[2010-10-14 15:11:29.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:30.00 to see if something interesting has happened by then.
[2010-10-14 15:11:29.99] CLOCK Waking up to manage resources
[2010-10-14 15:11:29.99] CLOCK Wake-up time recorded as 2010-10-14 15:11:30.00
[2010-10-14 15:11:30.06] RM Requesting leases
[2010-10-14 15:11:30.06] RM Running scheduling function
[2010-10-14 15:11:30.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:31.00 to see if something interesting has happened by then.
[2010-10-14 15:11:30.99] CLOCK Waking up to manage resources
[2010-10-14 15:11:30.99] CLOCK Wake-up time recorded as 2010-10-14 15:11:31.00
[2010-10-14 15:11:31.06] RM Requesting leases
[2010-10-14 15:11:31.06] RM Running scheduling function
[2010-10-14 15:11:31.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:32.00 to handle slot table event.
[2010-10-14 15:11:31.99] CLOCK Waking up to manage resources
[2010-10-14 15:11:31.99] CLOCK Wake-up time recorded as 2010-10-14 15:11:32.00
[2010-10-14 15:11:32.00] VMSCHED LEASE-1 Start of handleStartVM
[2010-10-14 15:11:32.00] LEASES __________________________________________________
[2010-10-14 15:11:32.00] LEASES Lease ID : 1
[2010-10-14 15:11:32.00] LEASES Type : Best-effort
[2010-10-14 15:11:32.00] LEASES Submission time: 2010-10-14 15:11:21.00
[2010-10-14 15:11:32.00] LEASES Start : REQ: Unspecified | SCH: None | ACT: None
[2010-10-14 15:11:32.00] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:11:32.00] LEASES State : Ready
[2010-10-14 15:11:32.00] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:11:32.00] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1bf11850>
[2010-10-14 15:11:32.00] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:11:32.00] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:11:32.00] LEASES Type : VM
[2010-10-14 15:11:32.00] LEASES Nodes : V1 -> P1
[2010-10-14 15:11:32.00] LEASES Start : 2010-10-14 15:11:32.00
[2010-10-14 15:11:32.00] LEASES End : 2110-09-20 15:11:32.00
[2010-10-14 15:11:32.00] LEASES State : Scheduled
[2010-10-14 15:11:32.00] LEASES Resources :
N1: 0:100 1:1000 2:0
[2010-10-14 15:11:32.00] LEASES --
[2010-10-14 15:11:32.00] LEASES Type : SHUTDOWN
[2010-10-14 15:11:32.00] LEASES Start : 2110-09-20 15:11:32.00
[2010-10-14 15:11:32.00] LEASES End : 2110-09-20 15:11:32.00
[2010-10-14 15:11:32.00] LEASES State : Scheduled
[2010-10-14 15:11:32.00] LEASES Resources :
N1: 0:100 1:1000 2:0
[2010-10-14 15:11:32.00] LEASES ##
[2010-10-14 15:11:32.00] LEASES --------------------------------------------------
[2010-10-14 15:11:32.00] ENACT.ONE.VM Sending request to start VM for L1V1 (ONE: vid=1109, hid=16)
[2010-10-14 15:11:32.01] ENACT.ONE.VM Request succesful.
[2010-10-14 15:11:32.01] LEASES __________________________________________________
[2010-10-14 15:11:32.01] LEASES Lease ID : 1
[2010-10-14 15:11:32.01] LEASES Type : Best-effort
[2010-10-14 15:11:32.01] LEASES Submission time: 2010-10-14 15:11:21.00
[2010-10-14 15:11:32.01] LEASES Start : REQ: Unspecified | SCH: None | ACT: 2010-10-14 15:11:32.00
[2010-10-14 15:11:32.01] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:11:32.01] LEASES State : Active
[2010-10-14 15:11:32.01] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:11:32.01] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1bf11850>
[2010-10-14 15:11:32.01] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:11:32.01] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:11:32.01] LEASES Type : VM
[2010-10-14 15:11:32.01] LEASES Nodes : V1 -> P1
[2010-10-14 15:11:32.01] LEASES Start : 2010-10-14 15:11:32.00
[2010-10-14 15:11:32.01] LEASES End : 2110-09-20 15:11:32.00
[2010-10-14 15:11:32.01] LEASES State : Active
[2010-10-14 15:11:32.01] LEASES Resources :
N1: 0:100 1:1000 2:0
[2010-10-14 15:11:32.01] LEASES --
[2010-10-14 15:11:32.01] LEASES Type : SHUTDOWN
[2010-10-14 15:11:32.01] LEASES Start : 2110-09-20 15:11:32.00
[2010-10-14 15:11:32.01] LEASES End : 2110-09-20 15:11:32.00
[2010-10-14 15:11:32.01] LEASES State : Scheduled
[2010-10-14 15:11:32.01] LEASES Resources :
N1: 0:100 1:1000 2:0
[2010-10-14 15:11:32.01] LEASES ##
[2010-10-14 15:11:32.01] LEASES --------------------------------------------------
[2010-10-14 15:11:32.01] VMSCHED LEASE-1 End of handleStartVM
[2010-10-14 15:11:32.01] VMSCHED Started VMs for lease 1 on nodes [1]
[2010-10-14 15:11:32.07] RM Requesting leases
[2010-10-14 15:11:32.07] RM Running scheduling function
[2010-10-14 15:11:32.08] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:33.00 to see if something interesting has happened by then.
[2010-10-14 15:11:33.00] CLOCK Waking up to manage resources
[2010-10-14 15:11:33.00] CLOCK Wake-up time recorded as 2010-10-14 15:11:33.00
[2010-10-14 15:11:33.08] RM Requesting leases
[2010-10-14 15:11:33.08] RM Running scheduling function
[2010-10-14 15:11:33.08] CLOCK Going back to sleep. Waking up at 2010-10-14 15:11:34.00 to see if something interesting has happened by then.
...
[2010-10-14 15:13:13.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:13.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:13.00
[2010-10-14 15:13:13.06] RM Requesting leases
[2010-10-14 15:13:13.06] RM Running scheduling function
[2010-10-14 15:13:13.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:14.00 to see if something interesting has happened by then.
[2010-10-14 15:13:13.99] CLOCK Waking up to manage resources
[2010-10-14 15:13:14.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:14.00
[2010-10-14 15:13:14.06] RM Requesting leases
[2010-10-14 15:13:14.07] RM Running scheduling function
[2010-10-14 15:13:14.07] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:15.00 to see if something interesting has happened by then.
[2010-10-14 15:13:15.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:15.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:15.00
[2010-10-14 15:13:15.05] RM Requesting leases
[2010-10-14 15:13:15.05] RM Running scheduling function
[2010-10-14 15:13:15.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:16.00 to see if something interesting has happened by then.
[2010-10-14 15:13:16.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:16.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:16.00
[2010-10-14 15:13:16.05] RM Requesting leases
[2010-10-14 15:13:16.05] RM Running scheduling function
[2010-10-14 15:13:16.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:17.00 to see if something interesting has happened by then.
[2010-10-14 15:13:17.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:17.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:17.00
[2010-10-14 15:13:17.05] RM Requesting leases
[2010-10-14 15:13:17.05] RM Running scheduling function
[2010-10-14 15:13:17.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:18.00 to see if something interesting has happened by then.
[2010-10-14 15:13:18.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:18.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:18.00
[2010-10-14 15:13:18.05] RM Requesting leases
[2010-10-14 15:13:18.05] LSCHED Lease #2 has been requested.
[2010-10-14 15:13:18.05] LEASES __________________________________________________
[2010-10-14 15:13:18.05] LEASES Lease ID : 2
[2010-10-14 15:13:18.05] LEASES Type : Best-effort
[2010-10-14 15:13:18.05] LEASES Submission time: 2010-10-14 15:13:17.00
[2010-10-14 15:13:18.05] LEASES Start : REQ: Unspecified | SCH: None | ACT: None
[2010-10-14 15:13:18.05] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:13:18.05] LEASES State : New
[2010-10-14 15:13:18.05] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:13:18.05] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1c051c10>
[2010-10-14 15:13:18.05] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:13:18.05] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:13:18.05] LEASES --------------------------------------------------
[2010-10-14 15:13:18.05] LSCHED Lease #2 has been marked as pending.
[2010-10-14 15:13:18.06] RM Running scheduling function
[2010-10-14 15:13:18.06] LSCHED Queued best-effort lease request #2, 1 nodes for 36500:00:00:00.00.
[2010-10-14 15:13:18.06] LSCHED Next request in the queue is lease 2. Attempting to schedule...
[2010-10-14 15:13:18.06] LEASES __________________________________________________
[2010-10-14 15:13:18.06] LEASES Lease ID : 2
[2010-10-14 15:13:18.06] LEASES Type : Best-effort
[2010-10-14 15:13:18.06] LEASES Submission time: 2010-10-14 15:13:17.00
[2010-10-14 15:13:18.06] LEASES Start : REQ: Unspecified | SCH: None | ACT: None
[2010-10-14 15:13:18.06] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:13:18.06] LEASES State : Queued
[2010-10-14 15:13:18.06] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:13:18.06] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1c051c10>
[2010-10-14 15:13:18.06] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:13:18.06] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:13:18.06] LEASES --------------------------------------------------
[2010-10-14 15:13:18.06] VMSCHED Attempting to map from 2010-10-14 15:13:28.00 to 2110-09-20 15:13:28.00
[2010-10-14 15:13:18.06] VMSCHED This lease can be scheduled from 2010-10-14 15:13:28.00 to 2110-09-20 15:13:28.00 (full duration)
[2010-10-14 15:13:18.06] VMSCHED Lease #2 has been scheduled on nodes [2] from 2010-10-14 15:13:28.00 to 2110-09-20 15:13:28.00
[2010-10-14 15:13:18.06] LEASES __________________________________________________
[2010-10-14 15:13:18.06] LEASES Lease ID : 2
[2010-10-14 15:13:18.06] LEASES Type : Best-effort
[2010-10-14 15:13:18.06] LEASES Submission time: 2010-10-14 15:13:17.00
[2010-10-14 15:13:18.06] LEASES Start : REQ: Unspecified | SCH: None | ACT: None
[2010-10-14 15:13:18.06] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:13:18.06] LEASES State : Ready
[2010-10-14 15:13:18.06] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:13:18.06] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1c051c10>
[2010-10-14 15:13:18.06] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:13:18.06] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:13:18.06] LEASES Type : VM
[2010-10-14 15:13:18.06] LEASES Nodes : V1 -> P2
[2010-10-14 15:13:18.06] LEASES Start : 2010-10-14 15:13:28.00
[2010-10-14 15:13:18.06] LEASES End : 2110-09-20 15:13:28.00
[2010-10-14 15:13:18.06] LEASES State : Scheduled
[2010-10-14 15:13:18.06] LEASES Resources :
N2: 0:100 1:1000 2:0
[2010-10-14 15:13:18.06] LEASES --
[2010-10-14 15:13:18.06] LEASES Type : SHUTDOWN
[2010-10-14 15:13:18.06] LEASES Start : 2110-09-20 15:13:28.00
[2010-10-14 15:13:18.06] LEASES End : 2110-09-20 15:13:28.00
[2010-10-14 15:13:18.06] LEASES State : Scheduled
[2010-10-14 15:13:18.06] LEASES Resources :
N2: 0:100 1:1000 2:0
[2010-10-14 15:13:18.06] LEASES ##
[2010-10-14 15:13:18.06] LEASES --------------------------------------------------
[2010-10-14 15:13:18.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:19.00 to see if something interesting has happened by then.
[2010-10-14 15:13:19.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:19.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:19.00
[2010-10-14 15:13:19.05] RM Requesting leases
[2010-10-14 15:13:19.05] RM Running scheduling function
[2010-10-14 15:13:19.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:20.00 to see if something interesting has happened by then.
[2010-10-14 15:13:20.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:20.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:20.00
[2010-10-14 15:13:20.05] RM Requesting leases
[2010-10-14 15:13:20.05] RM Running scheduling function
[2010-10-14 15:13:20.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:21.00 to see if something interesting has happened by then.
[2010-10-14 15:13:21.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:21.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:21.00
[2010-10-14 15:13:21.05] RM Requesting leases
[2010-10-14 15:13:21.05] RM Running scheduling function
[2010-10-14 15:13:21.05] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:22.00 to see if something interesting has happened by then.
[2010-10-14 15:13:22.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:22.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:22.00
[2010-10-14 15:13:22.07] RM Requesting leases
[2010-10-14 15:13:22.07] RM Running scheduling function
[2010-10-14 15:13:22.07] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:23.00 to see if something interesting has happened by then.
[2010-10-14 15:13:23.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:23.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:23.00
[2010-10-14 15:13:23.05] RM Requesting leases
[2010-10-14 15:13:23.05] RM Running scheduling function
[2010-10-14 15:13:23.05] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:24.00 to see if something interesting has happened by then.
[2010-10-14 15:13:24.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:24.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:24.00
[2010-10-14 15:13:24.06] RM Requesting leases
[2010-10-14 15:13:24.06] RM Running scheduling function
[2010-10-14 15:13:24.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:25.00 to see if something interesting has happened by then.
[2010-10-14 15:13:25.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:25.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:25.00
[2010-10-14 15:13:25.05] RM Requesting leases
[2010-10-14 15:13:25.05] RM Running scheduling function
[2010-10-14 15:13:25.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:26.00 to see if something interesting has happened by then.
[2010-10-14 15:13:26.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:26.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:26.00
[2010-10-14 15:13:26.05] RM Requesting leases
[2010-10-14 15:13:26.05] RM Running scheduling function
[2010-10-14 15:13:26.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:27.00 to see if something interesting has happened by then.
[2010-10-14 15:13:27.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:27.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:27.00
[2010-10-14 15:13:27.06] RM Requesting leases
[2010-10-14 15:13:27.06] RM Running scheduling function
[2010-10-14 15:13:27.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:28.00 to handle slot table event.
[2010-10-14 15:13:28.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:28.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:28.00
[2010-10-14 15:13:28.00] VMSCHED LEASE-2 Start of handleStartVM
[2010-10-14 15:13:28.00] LEASES __________________________________________________
[2010-10-14 15:13:28.00] LEASES Lease ID : 2
[2010-10-14 15:13:28.00] LEASES Type : Best-effort
[2010-10-14 15:13:28.00] LEASES Submission time: 2010-10-14 15:13:17.00
[2010-10-14 15:13:28.00] LEASES Start : REQ: Unspecified | SCH: None | ACT: None
[2010-10-14 15:13:28.00] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:13:28.00] LEASES State : Ready
[2010-10-14 15:13:28.00] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:13:28.00] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1c051c10>
[2010-10-14 15:13:28.00] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:13:28.00] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:13:28.00] LEASES Type : VM
[2010-10-14 15:13:28.00] LEASES Nodes : V1 -> P2
[2010-10-14 15:13:28.00] LEASES Start : 2010-10-14 15:13:28.00
[2010-10-14 15:13:28.00] LEASES End : 2110-09-20 15:13:28.00
[2010-10-14 15:13:28.00] LEASES State : Scheduled
[2010-10-14 15:13:28.00] LEASES Resources :
N2: 0:100 1:1000 2:0
[2010-10-14 15:13:28.00] LEASES --
[2010-10-14 15:13:28.00] LEASES Type : SHUTDOWN
[2010-10-14 15:13:28.00] LEASES Start : 2110-09-20 15:13:28.00
[2010-10-14 15:13:28.00] LEASES End : 2110-09-20 15:13:28.00
[2010-10-14 15:13:28.00] LEASES State : Scheduled
[2010-10-14 15:13:28.00] LEASES Resources :
N2: 0:100 1:1000 2:0
[2010-10-14 15:13:28.00] LEASES ##
[2010-10-14 15:13:28.00] LEASES --------------------------------------------------
[2010-10-14 15:13:28.00] ENACT.ONE.VM Sending request to start VM for L2V1 (ONE: vid=1110, hid=17)
[2010-10-14 15:13:28.01] ENACT.ONE.VM Request succesful.
[2010-10-14 15:13:28.01] LEASES __________________________________________________
[2010-10-14 15:13:28.01] LEASES Lease ID : 2
[2010-10-14 15:13:28.01] LEASES Type : Best-effort
[2010-10-14 15:13:28.01] LEASES Submission time: 2010-10-14 15:13:17.00
[2010-10-14 15:13:28.01] LEASES Start : REQ: Unspecified | SCH: None | ACT: 2010-10-14 15:13:28.00
[2010-10-14 15:13:28.01] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:13:28.01] LEASES State : Active
[2010-10-14 15:13:28.01] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:13:28.01] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1c051c10>
[2010-10-14 15:13:28.01] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:13:28.01] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:13:28.01] LEASES Type : VM
[2010-10-14 15:13:28.01] LEASES Nodes : V1 -> P2
[2010-10-14 15:13:28.01] LEASES Start : 2010-10-14 15:13:28.00
[2010-10-14 15:13:28.01] LEASES End : 2110-09-20 15:13:28.00
[2010-10-14 15:13:28.01] LEASES State : Active
[2010-10-14 15:13:28.01] LEASES Resources :
N2: 0:100 1:1000 2:0
[2010-10-14 15:13:28.01] LEASES --
[2010-10-14 15:13:28.01] LEASES Type : SHUTDOWN
[2010-10-14 15:13:28.01] LEASES Start : 2110-09-20 15:13:28.00
[2010-10-14 15:13:28.01] LEASES End : 2110-09-20 15:13:28.00
[2010-10-14 15:13:28.01] LEASES State : Scheduled
[2010-10-14 15:13:28.01] LEASES Resources :
N2: 0:100 1:1000 2:0
[2010-10-14 15:13:28.01] LEASES ##
[2010-10-14 15:13:28.01] LEASES --------------------------------------------------
[2010-10-14 15:13:28.01] VMSCHED LEASE-2 End of handleStartVM
[2010-10-14 15:13:28.01] VMSCHED Started VMs for lease 2 on nodes [2]
[2010-10-14 15:13:28.14] RM Requesting leases
[2010-10-14 15:13:28.14] RM Running scheduling function
[2010-10-14 15:13:28.14] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:29.00 to see if something interesting has happened by then.
[2010-10-14 15:13:29.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:29.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:29.00
[2010-10-14 15:13:29.06] RM Requesting leases
[2010-10-14 15:13:29.06] RM Running scheduling function
[2010-10-14 15:13:29.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:30.00 to see if something interesting has happened by then.
[2010-10-14 15:13:29.99] CLOCK Waking up to manage resources
[2010-10-14 15:13:29.99] CLOCK Wake-up time recorded as 2010-10-14 15:13:30.00
[2010-10-14 15:13:30.05] RM Requesting leases
[2010-10-14 15:13:30.05] RM Running scheduling function
[2010-10-14 15:13:30.05] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:31.00 to see if something interesting has happened by then.
[2010-10-14 15:13:31.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:31.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:31.00
[2010-10-14 15:13:31.05] RM Requesting leases
[2010-10-14 15:13:31.05] RM Running scheduling function
[2010-10-14 15:13:31.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:32.00 to see if something interesting has happened by then.
[2010-10-14 15:13:32.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:32.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:32.00
[2010-10-14 15:13:32.05] RM Requesting leases
[2010-10-14 15:13:32.05] RM Running scheduling function
[2010-10-14 15:13:32.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:33.00 to see if something interesting has happened by then.
[2010-10-14 15:13:33.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:33.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:33.00
[2010-10-14 15:13:33.10] RM Requesting leases
[2010-10-14 15:13:33.10] RM Running scheduling function
[2010-10-14 15:13:33.10] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:34.00 to see if something interesting has happened by then.
[2010-10-14 15:13:34.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:34.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:34.00
[2010-10-14 15:13:34.10] RM Requesting leases
[2010-10-14 15:13:34.10] RM Running scheduling function
[2010-10-14 15:13:34.10] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:35.00 to see if something interesting has happened by then.
[2010-10-14 15:13:35.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:35.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:35.00
[2010-10-14 15:13:35.06] RM Requesting leases
[2010-10-14 15:13:35.06] RM Running scheduling function
[2010-10-14 15:13:35.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:36.00 to see if something interesting has happened by then.
[2010-10-14 15:13:36.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:36.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:36.00
[2010-10-14 15:13:36.05] RM Requesting leases
[2010-10-14 15:13:36.05] RM Running scheduling function
[2010-10-14 15:13:36.05] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:37.00 to see if something interesting has happened by then.
[2010-10-14 15:13:37.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:37.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:37.00
[2010-10-14 15:13:37.05] RM Requesting leases
[2010-10-14 15:13:37.05] RM Running scheduling function
[2010-10-14 15:13:37.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:38.00 to see if something interesting has happened by then.
[2010-10-14 15:13:38.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:38.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:38.00
[2010-10-14 15:13:38.05] RM Requesting leases
[2010-10-14 15:13:38.05] RM Running scheduling function
[2010-10-14 15:13:38.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:39.00 to see if something interesting has happened by then.
[2010-10-14 15:13:39.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:39.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:39.00
[2010-10-14 15:13:39.05] RM Requesting leases
[2010-10-14 15:13:39.05] RM Running scheduling function
[2010-10-14 15:13:39.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:40.00 to see if something interesting has happened by then.
[2010-10-14 15:13:40.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:40.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:40.00
[2010-10-14 15:13:40.05] RM Requesting leases
[2010-10-14 15:13:40.05] RM Running scheduling function
[2010-10-14 15:13:40.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:41.00 to see if something interesting has happened by then.
[2010-10-14 15:13:41.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:41.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:41.00
[2010-10-14 15:13:41.06] RM Requesting leases
[2010-10-14 15:13:41.06] RM Running scheduling function
[2010-10-14 15:13:41.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:42.00 to see if something interesting has happened by then.
[2010-10-14 15:13:42.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:42.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:42.00
[2010-10-14 15:13:42.06] RM Requesting leases
[2010-10-14 15:13:42.06] RM Running scheduling function
[2010-10-14 15:13:42.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:43.00 to see if something interesting has happened by then.
[2010-10-14 15:13:43.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:43.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:43.00
[2010-10-14 15:13:43.06] RM Requesting leases
[2010-10-14 15:13:43.07] RM Running scheduling function
[2010-10-14 15:13:43.07] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:44.00 to see if something interesting has happened by then.
[2010-10-14 15:13:44.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:44.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:44.00
[2010-10-14 15:13:44.07] RM Requesting leases
[2010-10-14 15:13:44.07] RM Running scheduling function
[2010-10-14 15:13:44.07] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:45.00 to see if something interesting has happened by then.
[2010-10-14 15:13:45.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:45.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:45.00
[2010-10-14 15:13:45.06] RM Requesting leases
[2010-10-14 15:13:45.06] RM Running scheduling function
[2010-10-14 15:13:45.07] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:46.00 to see if something interesting has happened by then.
[2010-10-14 15:13:46.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:46.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:46.00
[2010-10-14 15:13:46.06] RM Requesting leases
[2010-10-14 15:13:46.06] RM Running scheduling function
[2010-10-14 15:13:46.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:47.00 to see if something interesting has happened by then.
[2010-10-14 15:13:47.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:47.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:47.00
[2010-10-14 15:13:47.07] RM Requesting leases
[2010-10-14 15:13:47.07] RM Running scheduling function
[2010-10-14 15:13:47.07] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:48.00 to see if something interesting has happened by then.
[2010-10-14 15:13:47.99] CLOCK Waking up to manage resources
[2010-10-14 15:13:47.99] CLOCK Wake-up time recorded as 2010-10-14 15:13:48.00
[2010-10-14 15:13:48.06] RM Requesting leases
[2010-10-14 15:13:48.06] LSCHED Lease #3 has been requested.
[2010-10-14 15:13:48.06] LEASES __________________________________________________
[2010-10-14 15:13:48.06] LEASES Lease ID : 3
[2010-10-14 15:13:48.06] LEASES Type : Best-effort
[2010-10-14 15:13:48.06] LEASES Submission time: 2010-10-14 15:13:47.00
[2010-10-14 15:13:48.06] LEASES Start : REQ: Unspecified | SCH: None | ACT: None
[2010-10-14 15:13:48.06] LEASES Duration : REQ: 36500:00:00:00.00 | ACC: 00:00:00.00 | ACT: None | KNW: None
[2010-10-14 15:13:48.06] LEASES State : New
[2010-10-14 15:13:48.06] LEASES Resource req : {1: Disk: 0 | CPU: 100 | Memory: 1000}
[2010-10-14 15:13:48.06] LEASES Software : <haizea.core.leases.UnmanagedSoftwareEnvironment object at 0x1bccc450>
[2010-10-14 15:13:48.06] LEASES VM RESOURCE RESERVATIONS
[2010-10-14 15:13:48.06] LEASES ~~~~~~~~~~~~~~~~~~~~~~~~
[2010-10-14 15:13:48.06] LEASES --------------------------------------------------
[2010-10-14 15:13:48.06] LSCHED Lease #3 has been marked as pending.
[2010-10-14 15:13:48.06] RM Running scheduling function
[2010-10-14 15:13:48.06] LSCHED Queued best-effort lease request #3, 1 nodes for 36500:00:00:00.00.
[2010-10-14 15:13:48.07] LSCHED Used up all future reservations and slot table is full. Skipping rest of queue.
[2010-10-14 15:13:48.07] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:49.00 to see if something interesting has happened by then.
[2010-10-14 15:13:49.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:49.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:49.00
[2010-10-14 15:13:49.06] RM Requesting leases
[2010-10-14 15:13:49.06] RM Running scheduling function
[2010-10-14 15:13:49.06] LSCHED Used up all future reservations and slot table is full. Skipping rest of queue.
[2010-10-14 15:13:49.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:50.00 to see if something interesting has happened by then.
[2010-10-14 15:13:50.00] CLOCK Waking up to manage resources
[2010-10-14 15:13:50.00] CLOCK Wake-up time recorded as 2010-10-14 15:13:50.00
[2010-10-14 15:13:50.06] RM Requesting leases
[2010-10-14 15:13:50.06] RM Running scheduling function
[2010-10-14 15:13:50.06] LSCHED Used up all future reservations and slot table is full. Skipping rest of queue.
[2010-10-14 15:13:50.06] CLOCK Going back to sleep. Waking up at 2010-10-14 15:13:51.00 to see if something interesting has happened 