Menu

#2 All algorithms break on this simple problem

open
nobody
None
5
2007-10-16
2007-10-16
Anonymous
No

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.

Discussion

  • Nobody/Anonymous

    Simple auction problem

     
  • Nobody/Anonymous

    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

     

Log in to post a comment.