Only Centralized B&B solves the attached simple problem to optimality.
- DPOP returns a suboptimal solution
- NCBB (networkless) loops due to message loss
- NCBB (TCP platform) throws an exception
- Adopt (networkless) freezes
- Adopt (TCP platform) throws an exception
- DCDPOP throws an exception
- Distributed Hill Climbing does not find a solution within 2 minutes.
Simple auction problem
Logged In: NO
Results of the algorithms on this problem (running on Mac OS 10.4.10):
DPOP: suboptimal solution
Estimated state space size: 256
Worst case num concurrent constraint checks: 8
Worst case num synchronous constraint checks: 64
Waiting for all other hosts to report on their portion of the constraint graph...
Calculating the variable ordering...
Global variable ordering: [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat [ delta22hat ] ] ] ] ] ] ] ]
VO Number of nodes: 8
VO Max depth: 7
VO Average depth: 3.5
VO Average # Children: 0.875
VO Number of leaves: 2
0 Seconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta11: Auctioneer for item i1.delta11@Auctioneer for item i1
0 Seconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta21: Auctioneer for item i1.delta21@Auctioneer for item i1
0 Seconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta22: Auctioneer for item i2.delta22@Auctioneer for item i2
0 Seconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta12: Auctioneer for item i2.delta12@Auctioneer for item i2
0 Seconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta11hat: Bidder b1.delta11hat@Bidder b1
0 Seconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta12hat: Bidder b1.delta12hat@Bidder b1
0 Seconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta22hat: Bidder b2.delta22hat@Bidder b2
0 Seconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta21hat: Bidder b2.delta21hat@Bidder b2
0 Seconds: Bidder b1.delta11hat: Cycle 1: Starting Phase 1
0 Seconds: Bidder b1.delta11hat: Cycle 1: My sub-pseudotree: [ delta11hat [ delta11 [ delta21 [ delta21hat [ delta22hat ] ] ] ] ]
0 Seconds: Bidder b1.delta11hat: Cycle 1: Sending domain update message to child Variable delta11
0 Seconds: Bidder b2.delta21hat: Cycle 1: Starting Phase 1
0 Seconds: Bidder b2.delta21hat: Cycle 1: My sub-pseudotree: [ delta21hat [ delta22hat ] ]
0 Seconds: Bidder b2.delta21hat: Cycle 1: Sending domain update message to child Variable delta22hat
0 Seconds: Bidder b1.delta12hat: Cycle 1: Starting Phase 1
0 Seconds: Bidder b1.delta12hat: Cycle 1: My sub-pseudotree: [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat [ delta22hat ] ] ] ] ] ]
0 Seconds: Bidder b1.delta12hat: Cycle 1: Sending domain update message to child Variable delta11hat
0 Seconds: Bidder b2.delta22hat: Cycle 1: Starting Phase 1
0 Seconds: Bidder b2.delta22hat: Cycle 1: My sub-pseudotree: [ delta22hat ]
0 Seconds: Bidder b2.delta22hat: Cycle 1: Expecting 2 domain update messages from my parent/pseudoparents (delta21hat, delta22)
0 Seconds: Bidder b2.delta22hat: Cycle 1: Still waiting for domain update messages from delta22, delta21hat
0 Seconds: Bidder b1.delta12hat: Cycle 1: Expecting 1 domain update messages from my parent/pseudoparents (delta12)
0 Seconds: Bidder b1.delta12hat: Cycle 1: Still waiting for domain update messages from delta12
0 Seconds: Auctioneer for item i1.delta21: Cycle 1: Starting Phase 1
0 Seconds: Auctioneer for item i1.delta21: Cycle 1: My sub-pseudotree: [ delta21 [ delta21hat [ delta22hat ] ] ]
0 Seconds: Auctioneer for item i1.delta21: Cycle 1: Sending domain update message to child Variable delta21hat
0 Seconds: Auctioneer for item i2.delta12: Cycle 1: Starting Phase 1
0 Seconds: Auctioneer for item i2.delta12: Cycle 1: My sub-pseudotree: [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat [ delta22hat ] ] ] ] ] ] ]
0 Seconds: Auctioneer for item i2.delta12: Cycle 1: Sending domain update message to child Variable delta12hat
0 Seconds: Auctioneer for item i1.delta11: Cycle 1: Starting Phase 1
0 Seconds: Auctioneer for item i1.delta11: Cycle 1: My sub-pseudotree: [ delta11 [ delta21 [ delta21hat [ delta22hat ] ] ] ]
0 Seconds: Auctioneer for item i1.delta11: Cycle 1: Sending domain update message to child Variable delta21
0 Seconds: Auctioneer for item i2.delta22: Cycle 1: Starting Phase 1
0 Seconds: Auctioneer for item i2.delta22: Cycle 1: My sub-pseudotree: [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat [ delta22hat ] ] ] ] ] ] ] ]
0 Seconds: Auctioneer for item i2.delta22: Cycle 1: Sending domain update message to child Variable delta12
1 Milliseconds: Bidder b2.delta21hat: Cycle 1: Expecting 1 domain update messages from my parent/pseudoparents (delta21)
1 Milliseconds: Bidder b2.delta21hat: Cycle 1: Still waiting for domain update messages from delta21
1 Milliseconds: Auctioneer for item i1.delta11: Cycle 1: Expecting 1 domain update messages from my parent/pseudoparents (delta11hat)
1 Milliseconds: Auctioneer for item i1.delta11: Cycle 1: Still waiting for domain update messages from delta11hat
3 Milliseconds: Auctioneer for item i2.delta22: Cycle 1: Sending domain update message to pseudo-child Variable delta22hat
100 Milliseconds: Bidder b1.delta12hat: Cycle 1: Still waiting for domain update messages from delta12
101 Milliseconds: Bidder b2.delta21hat: Cycle 1: Still waiting for domain update messages from delta21
101 Milliseconds: Auctioneer for item i1.delta11: Cycle 1: Still waiting for domain update messages from delta11hat
109 Milliseconds: Bidder b2.delta22hat: Cycle 2: Received domain update: delta21hat = {[0, 1]}
109 Milliseconds: Bidder b2.delta22hat: Cycle 2: Still waiting for domain update messages from delta22
200 Milliseconds: Bidder b1.delta12hat: Cycle 1: Still waiting for domain update messages from delta12
201 Milliseconds: Bidder b2.delta21hat: Cycle 1: Still waiting for domain update messages from delta21
201 Milliseconds: Auctioneer for item i1.delta11: Cycle 1: Still waiting for domain update messages from delta11hat
209 Milliseconds: Bidder b2.delta22hat: Cycle 2: Still waiting for domain update messages from delta22
246 Milliseconds 150 Microseconds: Auctioneer for item i2.delta22: Cycle 1: Expecting 0 domain update messages from my parent/pseudoparents ()
246 Milliseconds 150 Microseconds: Auctioneer for item i2.delta22: Cycle 1: Starting Phase 2
295 Milliseconds 919 Microseconds: Bidder b1.delta11hat: Cycle 2: Expecting 1 domain update messages from my parent/pseudoparents (delta12hat)
295 Milliseconds 919 Microseconds: Bidder b1.delta11hat: Cycle 2: Received domain update: delta12hat = {[0, 1]}
295 Milliseconds 919 Microseconds: Bidder b1.delta11hat: Cycle 2: Starting Phase 2
295 Milliseconds 919 Microseconds: Auctioneer for item i1.delta11: Cycle 2: Received domain update: delta11hat = {[0, 1]}
295 Milliseconds 919 Microseconds: Auctioneer for item i1.delta11: Cycle 2: Starting Phase 2
300 Milliseconds: Bidder b1.delta12hat: Cycle 1: Still waiting for domain update messages from delta12
301 Milliseconds: Bidder b2.delta21hat: Cycle 1: Still waiting for domain update messages from delta21
306 Milliseconds 150 Microseconds: Auctioneer for item i1.delta21: Cycle 2: Expecting 1 domain update messages from my parent/pseudoparents (delta11)
306 Milliseconds 150 Microseconds: Auctioneer for item i1.delta21: Cycle 2: Received domain update: delta11 = {[0, 1]}
306 Milliseconds 150 Microseconds: Auctioneer for item i1.delta21: Cycle 2: Starting Phase 2
306 Milliseconds 150 Microseconds: Bidder b2.delta22hat: Cycle 2: Received domain update: delta22 = {[0, 1]}
306 Milliseconds 150 Microseconds: Bidder b2.delta22hat: Cycle 2: Starting Phase 2
322 Milliseconds: Bidder b2.delta22hat: Cycle 3: Starting Phase 3
358 Milliseconds: Bidder b2.delta21hat: Cycle 3: Received domain update: delta21 = {[0, 1]}
358 Milliseconds: Bidder b2.delta21hat: Cycle 3: Starting Phase 2
400 Milliseconds: Bidder b1.delta12hat: Cycle 1: Still waiting for domain update messages from delta12
413 Milliseconds 150 Microseconds: Auctioneer for item i2.delta12: Cycle 2: Expecting 1 domain update messages from my parent/pseudoparents (delta22)
413 Milliseconds 150 Microseconds: Auctioneer for item i2.delta12: Cycle 2: Received domain update: delta22 = {[0, 1]}
413 Milliseconds 150 Microseconds: Auctioneer for item i2.delta12: Cycle 2: Starting Phase 2
413 Milliseconds 150 Microseconds: Bidder b1.delta12hat: Cycle 2: Received domain update: delta12 = {[0, 1]}
413 Milliseconds 150 Microseconds: Bidder b1.delta12hat: Cycle 2: Starting Phase 2
593 Milliseconds 877 Microseconds: Auctioneer for item i1.delta21: Cycle 5: Starting Phase 3
599 Milliseconds 877 Microseconds: Bidder b2.delta21hat: Cycle 4: Starting Phase 3
801 Milliseconds 919 Microseconds: Bidder b1.delta11hat: Cycle 7: Starting Phase 3
802 Milliseconds 303 Microseconds: Auctioneer for item i1.delta11: Cycle 6: Starting Phase 3
1 Seconds 37 Milliseconds 796 Microseconds: Auctioneer for item i2.delta22: Cycle 9: Choosing 0
1 Seconds 37 Milliseconds 796 Microseconds: Auctioneer for item i2.delta22: Cycle 10: Starting Phase 3
1 Seconds 37 Milliseconds 796 Microseconds: Auctioneer for item i2.delta22: Cycle 10: finished
1 Seconds 37 Milliseconds 796 Microseconds: Auctioneer for item i2.delta12: Cycle 10: Starting Phase 3
1 Seconds 37 Milliseconds 796 Microseconds: Auctioneer for item i2.delta12: Cycle 10: Choosing 0
1 Seconds 47 Milliseconds 796 Microseconds: Bidder b1.delta12hat: Cycle 8: Starting Phase 3
1 Seconds 254 Milliseconds 350 Microseconds: Bidder b1.delta12hat: Cycle 11: Choosing 0
1 Seconds 254 Milliseconds 350 Microseconds: Bidder b1.delta11hat: Cycle 12: Choosing 1
1 Seconds 254 Milliseconds 350 Microseconds: Bidder b1.delta12hat: Cycle 12: finished
1 Seconds 262 Milliseconds 350 Microseconds: Auctioneer for item i2.delta12: Cycle 11: finished
1 Seconds 281 Milliseconds 362 Microseconds: Auctioneer for item i1.delta11: Cycle 13: Choosing 1
1 Seconds 281 Milliseconds 362 Microseconds: Auctioneer for item i1.delta21: Cycle 14: Choosing 0
1 Seconds 281 Milliseconds 362 Microseconds: Auctioneer for item i1.delta11: Cycle 14: finished
1 Seconds 287 Milliseconds 362 Microseconds: Bidder b1.delta11hat: Cycle 13: finished
1 Seconds 299 Milliseconds 690 Microseconds: Bidder b2.delta21hat: Cycle 15: Choosing 0
1 Seconds 299 Milliseconds 690 Microseconds: Bidder b2.delta22hat: Cycle 16: Choosing 0
1 Seconds 299 Milliseconds 690 Microseconds: Bidder b2.delta22hat: Cycle 17: finished
1 Seconds 299 Milliseconds 690 Microseconds: Bidder b2.delta21hat: Cycle 16: finished
1 Seconds 306 Milliseconds 690 Microseconds: Auctioneer for item i1.delta21: Cycle 15: finished
*******************
NCBB (networkless): lost message - does not terminate
Estimated state space size: 256
Worst case num concurrent constraint checks: 8
Worst case num synchronous constraint checks: 64
Waiting for all other hosts to report on their portion of the constraint graph...
Calculating the variable ordering...
Global variable ordering: [ delta22hat [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ] ] ]
VO Number of nodes: 8
VO Max depth: 7
VO Average depth: 3.5
VO Average # Children: 0.875
VO Number of leaves: 2
0 Seconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta11: Auctioneer for item i1.delta11@Auctioneer for item i1
0 Seconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta21: Auctioneer for item i1.delta21@Auctioneer for item i1
0 Seconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta22: Auctioneer for item i2.delta22@Auctioneer for item i2
0 Seconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta12: Auctioneer for item i2.delta12@Auctioneer for item i2
0 Seconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta11hat: Bidder b1.delta11hat@Bidder b1
0 Seconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta12hat: Bidder b1.delta12hat@Bidder b1
0 Seconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta22hat: Bidder b2.delta22hat@Bidder b2
0 Seconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta21hat: Bidder b2.delta21hat@Bidder b2
0 Seconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta22: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@b5321
284 Milliseconds 19 Microseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta12: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@2df9e9
284 Milliseconds 19 Microseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta12: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@8af6b3
284 Milliseconds 19 Microseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta12hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@3e9d66
441 Milliseconds 644 Microseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@480bd3
517 Milliseconds 38 Microseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta12hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@114f11
612 Milliseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta12hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@31fe8a
675 Milliseconds 288 Microseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@bc94f3
738 Milliseconds 332 Microseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@5edaf9
836 Milliseconds 644 Microseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@b1f2d1
836 Milliseconds 644 Microseconds: Bidder b1.delta12hat: Cycle 8: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@881872
836 Milliseconds 644 Microseconds: Bidder b1.delta12hat: Cycle 8: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@bac230
911 Milliseconds 657 Microseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@ed3e21
962 Milliseconds 626 Microseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@92c91e
1 Seconds 38 Milliseconds 19 Microseconds: Bidder b1.delta12hat: Cycle 8: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@8bc86e
1 Seconds 71 Milliseconds 288 Microseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@6b3487
1 Seconds 112 Milliseconds: Bidder b1.delta11hat: Cycle 12: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@f36aa
1 Seconds 141 Milliseconds 26 Microseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@b15112
1 Seconds 187 Milliseconds 645 Microseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@43e90b
1 Seconds 270 Milliseconds 38 Microseconds: Bidder b1.delta12hat: Cycle 8: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@7bd9a8
1 Seconds 297 Milliseconds 657 Microseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@638298
1 Seconds 336 Milliseconds 19 Microseconds: Bidder b1.delta11hat: Cycle 12: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@a92d29
1 Seconds 336 Milliseconds 19 Microseconds: Auctioneer for item i1.delta11: Cycle 14: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@d56053
1 Seconds 336 Milliseconds 19 Microseconds: Auctioneer for item i1.delta11: Cycle 14: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@9c6cf2
1 Seconds 410 Milliseconds 664 Microseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@279409
1 Seconds 520 Milliseconds 26 Microseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@24ecfd
1 Seconds 567 Milliseconds 38 Microseconds: Bidder b1.delta11hat: Cycle 12: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@5b0bfd
1 Seconds 617 Milliseconds: Auctioneer for item i1.delta21: Cycle 19: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@580612
1 Seconds 838 Milliseconds 644 Microseconds: Bidder b2.delta21hat: Cycle 21: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@75880f
2 Seconds 44 Milliseconds 331 Microseconds: Bidder b2.delta21hat: Cycle 21: Upper bound on cost for our subtree: 0
2 Seconds 44 Milliseconds 331 Microseconds: Auctioneer for item i1.delta21: Cycle 22: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@985b54
2 Seconds 44 Milliseconds 331 Microseconds: Auctioneer for item i1.delta21: Cycle 22: Upper bound on cost for our subtree: 0
2 Seconds 44 Milliseconds 331 Microseconds: Auctioneer for item i1.delta11: Cycle 23: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@892099
2 Seconds 59 Milliseconds 644 Microseconds: Auctioneer for item i1.delta21: Cycle 23: updateContext()
2 Seconds 59 Milliseconds 644 Microseconds: Auctioneer for item i1.delta21: Cycle 23: Waiting for Variable delta11 to instruct us to start searching...
2 Seconds 59 Milliseconds 644 Microseconds: Auctioneer for item i1.delta21: Cycle 23: Waiting for Variable delta11 to instruct us to start searching...
2 Seconds 59 Milliseconds 644 Microseconds: Auctioneer for item i1.delta21: Cycle 23: Waiting for Variable delta11 to instruct us to start searching...
2 Seconds 59 Milliseconds 644 Microseconds: Auctioneer for item i1.delta21: Cycle 23: Waiting for Variable delta11 to instruct us to start searching...
2 Seconds 59 Milliseconds 644 Microseconds: Auctioneer for item i1.delta21: Cycle 23: Waiting for Variable delta11 to instruct us to start searching...
****************
NCBB (TCP platform): exception
Inserted Auctioneer for item i1 as ID for TCPPlatform-/128.178.155.50:1027
Inserted Bidder b1 as ID for TCPPlatform-/128.178.155.50:1027
Inserted Bidder b2 as ID for TCPPlatform-/128.178.155.50:1027
Inserted Auctioneer for item i2 as ID for TCPPlatform-/128.178.155.50:1027
Estimated state space size: 256
Worst case num concurrent constraint checks: 8
Worst case num synchronous constraint checks: 64
Waiting for all other hosts to report on their portion of the constraint graph...
Calculating the variable ordering...
Global variable ordering: [ delta22hat [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ] ] ]
VO Number of nodes: 8
VO Max depth: 7
VO Average depth: 3.5
VO Average # Children: 0.875
VO Number of leaves: 2
TCPPlatform: Registered local agent NCBBAgent(Auctioneer for item i1)@Auctioneer for item i1
TCPPlatform: Registered local agent NCBBAgent(Bidder b1)@Bidder b1
TCPPlatform: Registered local agent NCBBAgent(Bidder b2)@Bidder b2
TCPPlatform: Registered local agent NCBBAgent(Auctioneer for item i2)@Auctioneer for item i2
TCPPlatform: Registered local agent NCBBAgent(Auctioneer for item i2.delta22)@Auctioneer for item i2
TCPPlatform: Registered local agent NCBBAgent(Auctioneer for item i1.delta11)@Auctioneer for item i1
TCPPlatform: Registered local agent NCBBAgent(Bidder b2.delta22hat)@Bidder b2
TCPPlatform: Registered local agent NCBBAgent(Bidder b1.delta11hat)@Bidder b1
181 Milliseconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta22: Auctioneer for item i2.delta22@Auctioneer for item i2
TCPPlatform: Registered local agent NCBBAgent(Auctioneer for item i2.delta12)@Auctioneer for item i2
199 Milliseconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta11: Auctioneer for item i1.delta11@Auctioneer for item i1
TCPPlatform: Registered local agent NCBBAgent(Auctioneer for item i1.delta21)@Auctioneer for item i1
219 Milliseconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta22hat: Bidder b2.delta22hat@Bidder b2
TCPPlatform: Registered local agent NCBBAgent(Bidder b2.delta21hat)@Bidder b2
232 Milliseconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta11hat: Bidder b1.delta11hat@Bidder b1
TCPPlatform: Registered local agent NCBBAgent(Bidder b1.delta12hat)@Bidder b1
249 Milliseconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta12: Auctioneer for item i2.delta12@Auctioneer for item i2
293 Milliseconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta21: Auctioneer for item i1.delta21@Auctioneer for item i1
427 Milliseconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta21hat: Bidder b2.delta21hat@Bidder b2
491 Milliseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta22: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@690fb5
495 Milliseconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta12hat: Bidder b1.delta12hat@Bidder b1
586 Milliseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta12: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@8a206e
588 Milliseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta12: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@29b4d
689 Milliseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta12hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@b128bd
689 Milliseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta12hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@e9b824
697 Milliseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta12hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@44350
737 Milliseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@780b2b
737 Milliseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@257467
741 Milliseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@19bb8
746 Milliseconds: Bidder b1.delta12hat: Cycle 10: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@858433
838 Milliseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@aea919
838 Milliseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@913019
839 Milliseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@ed42d2
841 Milliseconds: Bidder b1.delta12hat: Cycle 10: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@e38c16
842 Milliseconds: Bidder b1.delta11hat: Cycle 15: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@d417a1
919 Milliseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@60b9cc
919 Milliseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@461967
922 Milliseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@12ee0c
924 Milliseconds: Bidder b1.delta12hat: Cycle 10: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@d74878
928 Milliseconds: Bidder b1.delta11hat: Cycle 15: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@7f1106
928 Milliseconds: Auctioneer for item i1.delta11: Cycle 21: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@2cce08
935 Milliseconds: Auctioneer for item i2.delta22: Cycle 3: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@da6014
935 Milliseconds: Bidder b2.delta22hat: Cycle 1: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@d9f5cd
936 Milliseconds: Auctioneer for item i2.delta12: Cycle 6: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@986b4b
938 Milliseconds: Bidder b1.delta12hat: Cycle 10: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@6026a7
939 Milliseconds: Bidder b1.delta11hat: Cycle 15: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@453a26
939 Milliseconds: Auctioneer for item i1.delta11: Cycle 21: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@ecaf99
940 Milliseconds: Auctioneer for item i1.delta21: Cycle 28: Sending message to Variable delta21hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$ValueMessage@2ba44d
1 Seconds: Bidder b2.delta21hat: Cycle 36: Sending message to Variable delta21: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@1c2969
1 Seconds 100 Milliseconds: Bidder b2.delta21hat: Cycle 36: Upper bound on cost for our subtree: 0
1 Seconds 101 Milliseconds: Auctioneer for item i1.delta21: Cycle 37: Sending message to Variable delta11: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@973441
1 Seconds 121 Milliseconds: Bidder b2.delta21hat: Cycle 37: updateContext()
1 Seconds 122 Milliseconds: Bidder b2.delta21hat: Cycle 37: Waiting for Variable delta21 to instruct us to start searching...
1 Seconds 204 Milliseconds: Auctioneer for item i1.delta21: Cycle 37: Upper bound on cost for our subtree: 0
1 Seconds 204 Milliseconds: Auctioneer for item i1.delta11: Cycle 38: Sending message to Variable delta11hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@7a231d
1 Seconds 223 Milliseconds: Bidder b2.delta21hat: Cycle 37: Waiting for Variable delta21 to instruct us to start searching...
1 Seconds 223 Milliseconds: Auctioneer for item i1.delta21: Cycle 38: updateContext()
1 Seconds 224 Milliseconds: Auctioneer for item i1.delta21: Cycle 38: Waiting for Variable delta11 to instruct us to start searching...
1 Seconds 302 Milliseconds: Bidder b1.delta11hat: Cycle 39: Sending message to Variable delta12hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@898097
1 Seconds 302 Milliseconds: Auctioneer for item i1.delta11: Cycle 38: Upper bound on cost for our subtree: 0
1 Seconds 323 Milliseconds: Bidder b2.delta21hat: Cycle 37: Waiting for Variable delta21 to instruct us to start searching...
1 Seconds 323 Milliseconds: Auctioneer for item i1.delta11: Cycle 39: updateContext()
1 Seconds 324 Milliseconds: Auctioneer for item i1.delta11: Cycle 39: Waiting for Variable delta11hat to instruct us to start searching...
1 Seconds 325 Milliseconds: Auctioneer for item i1.delta21: Cycle 38: Waiting for Variable delta11 to instruct us to start searching...
1 Seconds 401 Milliseconds: Bidder b1.delta11hat: Cycle 39: Upper bound on cost for our subtree: -5
1 Seconds 401 Milliseconds: Bidder b1.delta12hat: Cycle 40: Sending message to Variable delta12: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@8ee80d
1 Seconds 424 Milliseconds: Bidder b2.delta21hat: Cycle 37: Waiting for Variable delta21 to instruct us to start searching...
1 Seconds 424 Milliseconds: Auctioneer for item i1.delta11: Cycle 39: Waiting for Variable delta11hat to instruct us to start searching...
1 Seconds 425 Milliseconds: Auctioneer for item i1.delta21: Cycle 38: Waiting for Variable delta11 to instruct us to start searching...
1 Seconds 426 Milliseconds: Bidder b1.delta11hat: Cycle 40: updateContext()
1 Seconds 427 Milliseconds: Bidder b1.delta11hat: Cycle 40: Waiting for Variable delta12hat to instruct us to start searching...
1 Seconds 500 Milliseconds: Bidder b1.delta12hat: Cycle 40: Upper bound on cost for our subtree: -5
1 Seconds 500 Milliseconds: Auctioneer for item i2.delta12: Cycle 41: Sending message to Variable delta22: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@401936
1 Seconds 521 Milliseconds: Bidder b1.delta12hat: Cycle 41: updateContext()
1 Seconds 521 Milliseconds: Bidder b1.delta12hat: Cycle 41: Waiting for Variable delta12 to instruct us to start searching...
1 Seconds 525 Milliseconds: Bidder b2.delta21hat: Cycle 37: Waiting for Variable delta21 to instruct us to start searching...
1 Seconds 525 Milliseconds: Auctioneer for item i1.delta11: Cycle 39: Waiting for Variable delta11hat to instruct us to start searching...
1 Seconds 526 Milliseconds: Auctioneer for item i1.delta21: Cycle 38: Waiting for Variable delta11 to instruct us to start searching...
1 Seconds 529 Milliseconds: Bidder b1.delta11hat: Cycle 40: Waiting for Variable delta12hat to instruct us to start searching...
1 Seconds 599 Milliseconds: Auctioneer for item i2.delta12: Cycle 41: Upper bound on cost for our subtree: -5
1 Seconds 599 Milliseconds: Auctioneer for item i2.delta22: Cycle 42: Sending message to Variable delta22hat: org.dcopolis.algorithm.DistributedUpperBoundCalculator$CostMessage@f11342
1 Seconds 619 Milliseconds: Auctioneer for item i2.delta12: Cycle 42: updateContext()
1 Seconds 620 Milliseconds: Auctioneer for item i2.delta12: Cycle 42: Waiting for Variable delta22 to instruct us to start searching...
1 Seconds 621 Milliseconds: Bidder b1.delta12hat: Cycle 41: Waiting for Variable delta12 to instruct us to start searching...
1 Seconds 625 Milliseconds: Bidder b2.delta21hat: Cycle 37: Waiting for Variable delta21 to instruct us to start searching...
1 Seconds 626 Milliseconds: Auctioneer for item i1.delta11: Cycle 39: Waiting for Variable delta11hat to instruct us to start searching...
1 Seconds 626 Milliseconds: Auctioneer for item i1.delta21: Cycle 38: Waiting for Variable delta11 to instruct us to start searching...
1 Seconds 630 Milliseconds: Bidder b1.delta11hat: Cycle 40: Waiting for Variable delta12hat to instruct us to start searching...
1 Seconds 698 Milliseconds: Bidder b2.delta22hat: Cycle 43: Global upper bound on cost: -5
1 Seconds 698 Milliseconds: Auctioneer for item i2.delta22: Cycle 42: Upper bound on cost for our subtree: -5
1 Seconds 720 Milliseconds: Auctioneer for item i2.delta12: Cycle 42: Waiting for Variable delta22 to instruct us to start searching...
1 Seconds 722 Milliseconds: Bidder b1.delta12hat: Cycle 41: Waiting for Variable delta12 to instruct us to start searching...
1 Seconds 726 Milliseconds: Bidder b2.delta21hat: Cycle 37: Waiting for Variable delta21 to instruct us to start searching...
1 Seconds 726 Milliseconds: Auctioneer for item i1.delta11: Cycle 39: Waiting for Variable delta11hat to instruct us to start searching...
1 Seconds 727 Milliseconds: Auctioneer for item i1.delta21: Cycle 38: Waiting for Variable delta11 to instruct us to start searching...
1 Seconds 727 Milliseconds: Auctioneer for item i2.delta22: Cycle 43: updateContext()
1 Seconds 728 Milliseconds: Auctioneer for item i2.delta22: Cycle 43: Waiting for Variable delta22hat to instruct us to start searching...
1 Seconds 730 Milliseconds: Bidder b1.delta11hat: Cycle 40: Waiting for Variable delta12hat to instruct us to start searching...
1 Seconds 733 Milliseconds: Bidder b2.delta22hat: Cycle 44: search()
1 Seconds 733 Milliseconds: Bidder b2.delta22hat: Cycle 44: delta22hat: null --> 0
1 Seconds 733 Milliseconds: Bidder b2.delta22hat: Cycle 44: exiting search()
1 Seconds 734 Milliseconds: Bidder b2.delta22hat: Cycle 44: subtreeSearch(null, Variable delta22)
1 Seconds 734 Milliseconds: Bidder b2.delta22hat: Cycle 44: Sending message to Variable delta12: delta22hat(VALUE = null)
1 Seconds 736 Milliseconds: Bidder b2.delta22hat: Cycle 44: Sending message to Variable delta12hat: delta22hat(VALUE = null)
1 Seconds 736 Milliseconds: Bidder b2.delta22hat: Cycle 44: Sending message to Variable delta11hat: delta22hat(VALUE = null)
1 Seconds 737 Milliseconds: Bidder b2.delta22hat: Cycle 44: Sending message to Variable delta11: delta22hat(VALUE = null)
1 Seconds 739 Milliseconds: Bidder b2.delta22hat: Cycle 44: Sending message to Variable delta21: delta22hat(VALUE = null)
1 Seconds 740 Milliseconds: Bidder b2.delta22hat: Cycle 44: Sending message to Variable delta21hat: delta22hat(VALUE = null)
1 Seconds 740 Milliseconds: Bidder b2.delta22hat: Cycle 44: Sending message to Variable delta22: delta22hat(VALUE = null)
Exception in thread "Thread-14" java.lang.NullPointerException
at java.util.Hashtable.put(Hashtable.java:396)
at org.dcopolis.algorithm.ncbb.NCBBAgent.subtreeSearch(NCBBAgent.java:371)
at org.dcopolis.algorithm.ncbb.NCBBAgent.runSingle(NCBBAgent.java:108)
at org.dcopolis.algorithm.PseudoAgent.run(PseudoAgent.java:212)
at org.sefirs.SimulatedProcess$ProcessThread.run(SimulatedProcess.java:533)
Exception in thread "Thread-11" java.lang.IllegalArgumentException: Error: value "null" is not in the domain of variable delta22hat!
at org.dcopolis.problem.Context.setValue(Context.java:50)
at org.dcopolis.algorithm.ncbb.NCBBAgent.updateContext(NCBBAgent.java:132)
at org.dcopolis.algorithm.ncbb.NCBBAgent.runSingle(NCBBAgent.java:102)
at org.dcopolis.algorithm.PseudoAgent.run(PseudoAgent.java:212)
at org.sefirs.SimulatedProcess$ProcessThread.run(SimulatedProcess.java:533)
Exception in thread "Thread-17" java.lang.IllegalArgumentException: Error: value "null" is not in the domain of variable delta22hat!
at org.dcopolis.problem.Context.setValue(Context.java:50)
at org.dcopolis.algorithm.ncbb.NCBBAgent.updateContext(NCBBAgent.java:132)
at org.dcopolis.algorithm.ncbb.NCBBAgent.runSingle(NCBBAgent.java:102)
at org.dcopolis.algorithm.PseudoAgent.run(PseudoAgent.java:212)
at org.sefirs.SimulatedProcess$ProcessThread.run(SimulatedProcess.java:533)
Exception in thread "Thread-15" java.lang.IllegalArgumentException: Error: value "null" is not in the domain of variable delta22hat!
at org.dcopolis.problem.Context.setValue(Context.java:50)
at org.dcopolis.algorithm.ncbb.NCBBAgent.updateContext(NCBBAgent.java:132)
at org.dcopolis.algorithm.ncbb.NCBBAgent.runSingle(NCBBAgent.java:102)
at org.dcopolis.algorithm.PseudoAgent.run(PseudoAgent.java:212)
at org.sefirs.SimulatedProcess$ProcessThread.run(SimulatedProcess.java:533)
Exception in thread "Thread-12" java.lang.IllegalArgumentException: Error: value "null" is not in the domain of variable delta22hat!
at org.dcopolis.problem.Context.setValue(Context.java:50)
at org.dcopolis.algorithm.ncbb.NCBBAgent.updateContext(NCBBAgent.java:132)
at org.dcopolis.algorithm.ncbb.NCBBAgent.runSingle(NCBBAgent.java:102)
at org.dcopolis.algorithm.PseudoAgent.run(PseudoAgent.java:212)
at org.sefirs.SimulatedProcess$ProcessThread.run(SimulatedProcess.java:533)
Exception in thread "Thread-13" java.lang.IllegalArgumentException: Error: value "null" is not in the domain of variable delta22hat!
at org.dcopolis.problem.Context.setValue(Context.java:50)
at org.dcopolis.algorithm.ncbb.NCBBAgent.updateContext(NCBBAgent.java:132)
at org.dcopolis.algorithm.ncbb.NCBBAgent.runSingle(NCBBAgent.java:102)
at org.dcopolis.algorithm.PseudoAgent.run(PseudoAgent.java:212)
at org.sefirs.SimulatedProcess$ProcessThread.run(SimulatedProcess.java:533)
Exception in thread "Thread-10" java.lang.IllegalArgumentException: Error: value "null" is not in the domain of variable delta22hat!
at org.dcopolis.problem.Context.setValue(Context.java:50)
at org.dcopolis.algorithm.ncbb.NCBBAgent.updateContext(NCBBAgent.java:132)
at org.dcopolis.algorithm.ncbb.NCBBAgent.runSingle(NCBBAgent.java:102)
at org.dcopolis.algorithm.PseudoAgent.run(PseudoAgent.java:212)
at org.sefirs.SimulatedProcess$ProcessThread.run(SimulatedProcess.java:533)
Exception in thread "Thread-16" java.lang.IllegalArgumentException: Error: value "null" is not in the domain of variable delta22hat!
at org.dcopolis.problem.Context.setValue(Context.java:50)
at org.dcopolis.algorithm.ncbb.NCBBAgent.updateContext(NCBBAgent.java:132)
at org.dcopolis.algorithm.ncbb.NCBBAgent.runSingle(NCBBAgent.java:102)
at org.dcopolis.algorithm.PseudoAgent.run(PseudoAgent.java:212)
at org.sefirs.SimulatedProcess$ProcessThread.run(SimulatedProcess.java:533)
****************
Adopt (networkless): freezes
Estimated state space size: 256
Worst case num concurrent constraint checks: 8
Worst case num synchronous constraint checks: 64
Waiting for all other hosts to report on their portion of the constraint graph...
Calculating the variable ordering...
Global variable ordering: [ delta22hat [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ] ] ]
VO Number of nodes: 8
VO Max depth: 7
VO Average depth: 3.5
VO Average # Children: 0.875
VO Number of leaves: 2
0 Seconds: Auctioneer for item i1.delta11: Cycle 1: Variable Ordering: [ delta11 [ delta21 [ delta21hat ] ] ]
0 Seconds: Auctioneer for item i1.delta21: Cycle 1: Variable Ordering: [ delta21 [ delta21hat ] ]
0 Seconds: Bidder b1.delta12hat: Cycle 1: Variable Ordering: [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ]
0 Seconds: Bidder b1.delta11hat: Cycle 1: Variable Ordering: [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ]
0 Seconds: Bidder b2.delta22hat: Cycle 1: Variable Ordering: [ delta22hat [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ] ] ]
0 Seconds: Bidder b2.delta21hat: Cycle 1: Variable Ordering: [ delta21hat ]
0 Seconds: Auctioneer for item i2.delta22: Cycle 1: Variable Ordering: [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ] ]
0 Seconds: Auctioneer for item i2.delta12: Cycle 1: Variable Ordering: [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ]
0 Seconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta11: Auctioneer for item i1.delta11@Auctioneer for item i1
0 Seconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta21: Auctioneer for item i1.delta21@Auctioneer for item i1
0 Seconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta22: Auctioneer for item i2.delta22@Auctioneer for item i2
0 Seconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta12: Auctioneer for item i2.delta12@Auctioneer for item i2
0 Seconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta11hat: Bidder b1.delta11hat@Bidder b1
0 Seconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta12hat: Bidder b1.delta12hat@Bidder b1
0 Seconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta22hat: Bidder b2.delta22hat@Bidder b2
0 Seconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta21hat: Bidder b2.delta21hat@Bidder b2
0 Seconds: Bidder b1.delta11hat: Cycle 1: Running backtrack()...
0 Seconds: Bidder b2.delta21hat: Cycle 1: Running backtrack()...
0 Seconds: Bidder b1.delta12hat: Cycle 1: Running backtrack()...
0 Seconds: Bidder b2.delta22hat: Cycle 1: Running backtrack()...
0 Seconds: Auctioneer for item i1.delta21: Cycle 1: Running backtrack()...
0 Seconds: Auctioneer for item i2.delta12: Cycle 1: Running backtrack()...
0 Seconds: Auctioneer for item i1.delta11: Cycle 1: Running backtrack()...
0 Seconds: Auctioneer for item i2.delta22: Cycle 1: Running backtrack()...
*****************
Adopt (TCP platform): exception
Inserted Auctioneer for item i1 as ID for TCPPlatform-/128.178.155.50:1027
Inserted Bidder b1 as ID for TCPPlatform-/128.178.155.50:1027
Inserted Bidder b2 as ID for TCPPlatform-/128.178.155.50:1027
Inserted Auctioneer for item i2 as ID for TCPPlatform-/128.178.155.50:1027
Estimated state space size: 256
Worst case num concurrent constraint checks: 8
Worst case num synchronous constraint checks: 64
Waiting for all other hosts to report on their portion of the constraint graph...
Calculating the variable ordering...
Global variable ordering: [ delta22hat [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ] ] ]
VO Number of nodes: 8
VO Max depth: 7
VO Average depth: 3.5
VO Average # Children: 0.875
VO Number of leaves: 2
0 Seconds: Auctioneer for item i1.delta11: Cycle 1: Variable Ordering: [ delta11 [ delta21 [ delta21hat ] ] ]
0 Seconds: Auctioneer for item i1.delta21: Cycle 1: Variable Ordering: [ delta21 [ delta21hat ] ]
TCPPlatform: Registered local agent AdoptAgent(Auctioneer for item i1)@Auctioneer for item i1
0 Seconds: Bidder b1.delta12hat: Cycle 1: Variable Ordering: [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ]
0 Seconds: Bidder b1.delta11hat: Cycle 1: Variable Ordering: [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ]
TCPPlatform: Registered local agent AdoptAgent(Bidder b1)@Bidder b1
0 Seconds: Bidder b2.delta22hat: Cycle 1: Variable Ordering: [ delta22hat [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ] ] ]
0 Seconds: Bidder b2.delta21hat: Cycle 1: Variable Ordering: [ delta21hat ]
TCPPlatform: Registered local agent AdoptAgent(Bidder b2)@Bidder b2
0 Seconds: Auctioneer for item i2.delta22: Cycle 1: Variable Ordering: [ delta22 [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ] ]
0 Seconds: Auctioneer for item i2.delta12: Cycle 1: Variable Ordering: [ delta12 [ delta12hat [ delta11hat [ delta11 [ delta21 [ delta21hat ] ] ] ] ] ]
TCPPlatform: Registered local agent AdoptAgent(Auctioneer for item i2)@Auctioneer for item i2
TCPPlatform: Registered local agent AdoptAgent(Auctioneer for item i1.delta11)@Auctioneer for item i1
TCPPlatform: Registered local agent AdoptAgent(Bidder b1.delta11hat)@Bidder b1
TCPPlatform: Registered local agent AdoptAgent(Auctioneer for item i2.delta22)@Auctioneer for item i2
TCPPlatform: Registered local agent AdoptAgent(Bidder b2.delta22hat)@Bidder b2
189 Milliseconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta11: Auctioneer for item i1.delta11@Auctioneer for item i1
TCPPlatform: Registered local agent AdoptAgent(Auctioneer for item i1.delta21)@Auctioneer for item i1
207 Milliseconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta11hat: Bidder b1.delta11hat@Bidder b1
TCPPlatform: Registered local agent AdoptAgent(Bidder b1.delta12hat)@Bidder b1
223 Milliseconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta22: Auctioneer for item i2.delta22@Auctioneer for item i2
TCPPlatform: Registered local agent AdoptAgent(Auctioneer for item i2.delta12)@Auctioneer for item i2
239 Milliseconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta22hat: Bidder b2.delta22hat@Bidder b2
TCPPlatform: Registered local agent AdoptAgent(Bidder b2.delta21hat)@Bidder b2
256 Milliseconds: Auctioneer for item i1: Cycle 1: Added sub agent for variable Variable delta21: Auctioneer for item i1.delta21@Auctioneer for item i1
257 Milliseconds: Auctioneer for item i1.delta11: Cycle 1: Running backtrack()...
259 Milliseconds: Auctioneer for item i1.delta21: Cycle 1: Running backtrack()...
274 Milliseconds: Bidder b2: Cycle 1: Error: received a message for unknown variable Variable delta21hat
277 Milliseconds: Bidder b1: Cycle 1: Added sub agent for variable Variable delta12hat: Bidder b1.delta12hat@Bidder b1
281 Milliseconds: Bidder b1.delta11hat: Cycle 1: Running backtrack()...
284 Milliseconds: Bidder b2: Cycle 1: Error: received a message for unknown variable Variable delta21hat
286 Milliseconds: Bidder b1.delta12hat: Cycle 1: Running backtrack()...
303 Milliseconds: Auctioneer for item i2: Cycle 1: Added sub agent for variable Variable delta12: Auctioneer for item i2.delta12@Auctioneer for item i2
317 Milliseconds: Auctioneer for item i2.delta22: Cycle 1: Running backtrack()...
319 Milliseconds: Auctioneer for item i2.delta12: Cycle 1: Running backtrack()...
327 Milliseconds: Bidder b2: Cycle 1: Added sub agent for variable Variable delta21hat: Bidder b2.delta21hat@Bidder b2
336 Milliseconds: Bidder b2.delta22hat: Cycle 1: Running backtrack()...
342 Milliseconds: Bidder b2.delta21hat: Cycle 4: Running backtrack()...
java.lang.IllegalArgumentException: timeout value is negative
at java.lang.Thread.sleep(Native Method)
at org.sefirs.SimulatedProcess.sleepUntil(SimulatedProcess.java:185)
at org.sefirs.SimulatedProcess.sleep(SimulatedProcess.java:112)
at org.dcopolis.algorithm.adopt.AdoptAgent.runSingle(AdoptAgent.java:132)
at org.dcopolis.algorithm.PseudoAgent.run(PseudoAgent.java:212)
at org.sefirs.SimulatedProcess$ProcessThread.run(SimulatedProcess.java:533)
*****************
DCDPOP: NullPointerException
Estimated state space size: 256
Worst case num concurrent constraint checks: 8
Worst case num synchronous constraint checks: 64
Exception in thread "main" java.lang.NullPointerException
at org.dcopolis.algorithm.dcdpop.DCDPOPAlgorithm.newSpecializedAgent(DCDPOPAlgorithm.java:46)
at org.dcopolis.algorithm.dcdpop.DCDPOPAlgorithm.newSpecializedAgent(DCDPOPAlgorithm.java:1)
at org.dcopolis.algorithm.PseudoAgent.<init>(PseudoAgent.java:68)
at org.dcopolis.algorithm.dcdpop.DCDPOPAgent.<init>(DCDPOPAgent.java:59)
at org.dcopolis.algorithm.dcdpop.DCDPOPAlgorithm.newPseudoAgent(DCDPOPAlgorithm.java:50)
at org.dcopolis.algorithm.dcdpop.DCDPOPAlgorithm.newPseudoAgent(DCDPOPAlgorithm.java:1)
at org.dcopolis.algorithm.SingleVariablePerAgentAlgorithm.newAgent(SingleVariablePerAgentAlgorithm.java:81)
at org.dcopolis.algorithm.SingleVariablePerAgentAlgorithm.newAgent(SingleVariablePerAgentAlgorithm.java:1)
at org.dcopolis.DCOPolis.init(DCOPolis.java:275)
at org.dcopolis.DCOPolis.<init>(DCOPolis.java:145)
at org.dcopolis.DCOPolis.<init>(DCOPolis.java:67)
at org.dcopolis.DCOPolis.main(DCOPolis.java:608)
******************
Distributed Hill Climbing: cutoff after 2 min
Estimated state space size: 256
Worst case num concurrent constraint checks: 8
Worst case num synchronous constraint checks: 64
0 Seconds: Auctioneer for item i1: Cycle 1: Optimizing...
0 Seconds: Auctioneer for item i2: Cycle 1: Optimizing...
0 Seconds: Bidder b1: Cycle 1: Optimizing...
0 Seconds: Bidder b2: Cycle 1: Optimizing...
3 Seconds 213 Milliseconds 918 Microseconds: Bidder b2: Cycle 1: Optimizing...
3 Seconds 813 Milliseconds 918 Microseconds: Bidder b1: Cycle 1: Optimizing...
13 Seconds 233 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
13 Seconds 826 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
14 Seconds 117 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
14 Seconds 219 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
23 Seconds 333 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
23 Seconds 926 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
24 Seconds 217 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
24 Seconds 319 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
33 Seconds 433 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
34 Seconds 26 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
34 Seconds 317 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
34 Seconds 419 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
43 Seconds 533 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
44 Seconds 126 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
44 Seconds 417 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
44 Seconds 519 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
53 Seconds 633 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
54 Seconds 226 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
54 Seconds 517 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
54 Seconds 619 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
1 Minutes 3 Seconds 733 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
1 Minutes 4 Seconds 326 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
1 Minutes 4 Seconds 617 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
1 Minutes 4 Seconds 719 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
1 Minutes 13 Seconds 833 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
1 Minutes 14 Seconds 426 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
1 Minutes 14 Seconds 717 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
1 Minutes 14 Seconds 819 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
1 Minutes 23 Seconds 933 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
1 Minutes 24 Seconds 526 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
1 Minutes 24 Seconds 817 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
1 Minutes 24 Seconds 919 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
1 Minutes 34 Seconds 33 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
1 Minutes 34 Seconds 626 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
1 Minutes 34 Seconds 917 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
1 Minutes 35 Seconds 19 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
1 Minutes 44 Seconds 133 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
1 Minutes 44 Seconds 726 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
1 Minutes 45 Seconds 17 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
1 Minutes 45 Seconds 119 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
1 Minutes 54 Seconds 233 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
1 Minutes 54 Seconds 826 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
1 Minutes 55 Seconds 117 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
1 Minutes 55 Seconds 219 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
2 Minutes 4 Seconds 333 Milliseconds 836 Microseconds: Bidder b2: Cycle 1: Optimizing...
2 Minutes 4 Seconds 926 Milliseconds 836 Microseconds: Bidder b1: Cycle 1: Optimizing...
2 Minutes 5 Seconds 217 Milliseconds 474 Microseconds: Auctioneer for item i2: Cycle 1: Optimizing...
2 Minutes 5 Seconds 319 Milliseconds 474 Microseconds: Auctioneer for item i1: Cycle 1: Optimizing...
*********************
Centralized B&B: optimal
Estimated state space size: 256
Worst case num concurrent constraint checks: 8
Worst case num synchronous constraint checks: 64
0 Seconds: Auctioneer for item i1: Cycle 1: There are null assignments at the beginning (so it works).
.........0 Seconds: Auctioneer for item i1: Cycle 1:
The best set of assignments is:
0 Seconds: Auctioneer for item i1: Cycle 1: Variable delta22hat := 1
0 Seconds: Auctioneer for item i1: Cycle 1: Variable delta22 := 1
0 Seconds: Auctioneer for item i1: Cycle 1: Variable delta12 := 0
0 Seconds: Auctioneer for item i1: Cycle 1: Variable delta12hat := 0
0 Seconds: Auctioneer for item i1: Cycle 1: Variable delta11 := 0
0 Seconds: Auctioneer for item i1: Cycle 1: Variable delta21hat := 1
0 Seconds: Auctioneer for item i1: Cycle 1: Variable delta21 := 1
0 Seconds: Auctioneer for item i1: Cycle 1: Variable delta11hat := 0