16:52:19 DEBUG ClusterNodeState | -------------------------------------------------------------------------------- | HEADSET: | [VmTypeAvailability{type=VmType{name='m1.small', cpu=1, disk=2, mem=128}, max=8, available=8} | ] | -------------------------------------------------------------------------------- 16:52:19 DEBUG ClusterNodeState | ================================================================================ | AFTER ALLOCATE | ================================================================================ 16:52:19 DEBUG ClusterNodeState | [VmTypeAvailability{type=VmType{name='m1.small', cpu=1, disk=2, mem=128}, max=8, available=7} | , VmTypeAvailability{type=VmType{name='c1.medium', cpu=1, disk=5, mem=256}, max=8, available=7} | , VmTypeAvailability{type=VmType{name='m1.large', cpu=2, disk=10, mem=512}, max=4, available=3} | , VmTypeAvailability{type=VmType{name='m1.xlarge', cpu=2, disk=20, mem=1024}, max=4, available=3} | , VmTypeAvailability{type=VmType{name='c1.xlarge', cpu=4, disk=20, mem=2048}, max=2, available=1} | , VmTypeAvailability{type=VmType{name='ZERO', cpu=-1, disk=-1, mem=-1}, max=0, available=0} | ] 16:52:19 DEBUG ClusterNodeState | [ preallocate] ResourceToken [addresses=[], amount=1, cluster=test1, correlationId=65bfdc8a-b1ec-41bb-aa21-7a80a27f8ecf, creationTime=Wed Feb 10 16:52:19 EST 2010, instanceIds=[], networkTokens=[], sequenceNumber=7, userName=admin, vmType=c1.medium] 16:52:19 DEBUG AddressUtil | Found 0 addresses allocated to eucalyptus 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:fae437e0-ae02-4939-aad2-2ed5befa6a55:CREATE:END:3:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusImageManager.cacheImage(WalrusImageManager.java:413):b589b6a5-8fb3-498b-bd3e-f3d28863c1fe: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.util.EucalyptusProperties.getSystemConfiguration(EucalyptusProperties.java:117):6aa023ad-0f7f-4497-8d3c-42173b99c1ba: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:b13fdff8-9374-4fbe-bd5a-c8d7a6fb36eb:CREATE:END:0:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusImageManager.cacheImage(WalrusImageManager.java:988):4db6cc87-4ffd-462d-bd5e-a5529cf5d3d4: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:6:eucalyptus:edu.ucsb.eucalyptus.util.EucalyptusProperties.getSystemConfiguration(EucalyptusProperties.java:117):ffd61b46-1ff0-4ade-929d-1605c70c69cf: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:b13fdff8-9374-4fbe-bd5a-c8d7a6fb36eb:CREATE:END:1:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusImageManager.cacheImage(WalrusImageManager.java:1000):26ef0075-f6fc-44e5-b9dc-36e4983298a4: 16:52:19 DEBUG Address | :1265781120.0000:Address/Address.allocate.260:eucalyptus:eucalyptus:allocated:Address [cluster=test1, instanceAddress=0.0.0.0, instanceId=available, name=192.168.1.100, pending=false, state=allocated, userId=nobody]: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.entities.Address.addAddress(Address.java:308):75f426de-7eab-4791-8239-4c6050256b31: 16:52:19 DEBUG Address | :1265781120.0000:Address/Addresses.getDynamicSystemAddresses.127:eucalyptus:eucalyptus:allocated:Address [cluster=test1, instanceAddress=0.0.0.0, instanceId=available, name=192.168.1.100, pending=false, state=allocated, userId=eucalyptus]: 16:52:19 DEBUG Address | :1265781120.0000:Address/Addresses.getDynamicSystemAddresses.128:eucalyptus:eucalyptus:assigning:Address [cluster=test1, instanceAddress=pending, instanceId=pending, name=192.168.1.100, pending=true, state=assigned, userId=eucalyptus]: 16:52:19 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_config:com.eucalyptus.config.Configuration.getClusterConfigurations(Configuration.java:251):147c0e81-d630-4b22-b8a9-07b2e299b9e1: 16:52:19 DEBUG ClusterState | :1265781120.0000:cluster/ClusterState.trim.116:eucalyptus:eucalyptus:CONFIG_VLANS:10:4095:[11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30, 31, 32, 33, 34, 35, 36, 37, 38, 39, 40, 41, 42, 43, 44, 45, 46, 47, 48, 49, 50, 51, 52, 53, 54, 55, 56, 57, 58, 59, 60, 61, 62, 63, 64, 65, 66, 67, 68, 69, 70, 71, 72, 73, 74, 75, 76, 77, 78, 79, 80, 81, 82, 83, 84, 85, 86, 87, 88, 89, 90, 91, 92, 93, 94, 95, 96, 97, 98, 99, 100, 101, 102, 103, 104, 105, 106, 107, 108, 109, 110, 111, 112, 113, 114, 115, 116, 117, 118, 119, 120, 121, 122, 123, 124, 125, 126, 127, 128, 129, 130, 131, 132, 133, 134, 135, 136, 137, 138, 139, 140, 141, 142, 143, 144, 145, 146, 147, 148, 149, 150, 151, 152, 153, 154, 155, 156, 157, 158, 159, 160, 161, 162, 163, 164, 165, 166, 167, 168, 169, 170, 171, 172, 173, 174, 175, 176, 177, 178, 179, 180, 181, 182, 183, 184, 185, 186, 187, 188, 189, 190, 191, 192, 193, 194, 195, 196, 197, 198, 199, 200, 201, 202, 203, 204, 205, 206, 207, 208, 209, 210, 211, 212, 213, 214, 215, 216, 217, 218, 219, 220, 221, 222, 223, 224, 225, 226, 227, 228, 229, 230, 231, 232, 233, 234, 235, 236, 237, 238, 239, 240, 241, 242, 243, 244, 245, 246, 247, 248, 249, 250, 251, 252, 253, 254, 255, 256, 257, 258, 259, 260, 261, 262, 263, 264, 265, 266, 267, 268, 269, 270, 271, 272, 273, 274, 275, 276, 277, 278, 279, 280, 281, 282, 283, 284, 285, 286, 287, 288, 289, 290, 291, 292, 293, 294, 295, 296, 297, 298, 299, 300, 301, 302, 303, 304, 305, 306, 307, 308, 309, 310, 311, 312, 313, 314, 315, 316, 317, 318, 319, 320, 321, 322, 323, 324, 325, 326, 327, 328, 329, 330, 331, 332, 333, 334, 335, 336, 337, 338, 339, 340, 341, 342, 343, 344, 345, 346, 347, 348, 349, 350, 351, 352, 353, 354, 355, 356, 357, 358, 359, 360, 361, 362, 363, 364, 365, 366, 367, 368, 369, 370, 371, 372, 373, 374, 375, 376, 377, 378, 379, 380, 381, 382, 383, 384, 385, 386, 387, 388, 389, 390, 391, 392, 393, 394, 395, 396, 397, 398, 399, 400, 401, 402, 403, 404, 405, 406, 407, 408, 409, 410, 411, 412, 413, 414, 415, 416, 417, 418, 419, 420, 421, 422, 423, 424, 425, 426, 427, 428, 429, 430, 431, 432, 433, 434, 435, 436, 437, 438, 439, 440, 441, 442, 443, 444, 445, 446, 447, 448, 449, 450, 451, 452, 453, 454, 455, 456, 457, 458, 459, 460, 461, 462, 463, 464, 465, 466, 467, 468, 469, 470, 471, 472, 473, 474, 475, 476, 477, 478, 479, 480, 481, 482, 483, 484, 485, 486, 487, 488, 489, 490, 491, 492, 493, 494, 495, 496, 497, 498, 499, 500, 501, 502, 503, 504, 505, 506, 507, 508, 509, 510, 511, 512, 513, 514, 515, 516, 517, 518, 519, 520, 521, 522, 523, 524, 525, 526, 527, 528, 529, 530, 531, 532, 533, 534, 535, 536, 537, 538, 539, 540, 541, 542, 543, 544, 545, 546, 547, 548, 549, 550, 551, 552, 553, 554, 555, 556, 557, 558, 559, 560, 561, 562, 563, 564, 565, 566, 567, 568, 569, 570, 571, 572, 573, 574, 575, 576, 577, 578, 579, 580, 581, 582, 583, 584, 585, 586, 587, 588, 589, 590, 591, 592, 593, 594, 595, 596, 597, 598, 599, 600, 601, 602, 603, 604, 605, 606, 607, 608, 609, 610, 611, 612, 613, 614, 615, 616, 617, 618, 619, 620, 621, 622, 623, 624, 625, 626, 627, 628, 629, 630, 631, 632, 633, 634, 635, 636, 637, 638, 639, 640, 641, 642, 643, 644, 645, 646, 647, 648, 649, 650, 651, 652, 653, 654, 655, 656, 657, 658, 659, 660, 661, 662, 663, 664, 665, 666, 667, 668, 669, 670, 671, 672, 673, 674, 675, 676, 677, 678, 679, 680, 681, 682, 683, 684, 685, 686, 687, 688, 689, 690, 691, 692, 693, 694, 695, 696, 697, 698, 699, 700, 701, 702, 703, 704, 705, 706, 707, 708, 709, 710, 711, 712, 713, 714, 715, 716, 717, 718, 719, 720, 721, 722, 723, 724, 725, 726, 727, 728, 729, 730, 731, 732, 733, 734, 735, 736, 737, 738, 739, 740, 741, 742, 743, 744, 745, 746, 747, 748, 749, 750, 751, 752, 753, 754, 755, 756, 757, 758, 759, 760, 761, 762, 763, 764, 765, 766, 767, 768, 769, 770, 771, 772, 773, 774, 775, 776, 777, 778, 779, 780, 781, 782, 783, 784, 785, 786, 787, 788, 789, 790, 791, 792, 793, 794, 795, 796, 797, 798, 799, 800, 801, 802, 803, 804, 805, 806, 807, 808, 809, 810, 811, 812, 813, 814, 815, 816, 817, 818, 819, 820, 821, 822, 823, 824, 825, 826, 827, 828, 829, 830, 831, 832, 833, 834, 835, 836, 837, 838, 839, 840, 841, 842, 843, 844, 845, 846, 847, 848, 849, 850, 851, 852, 853, 854, 855, 856, 857, 858, 859, 860, 861, 862, 863, 864, 865, 866, 867, 868, 869, 870, 871, 872, 873, 874, 875, 876, 877, 878, 879, 880, 881, 882, 883, 884, 885, 886, 887, 888, 889, 890, 891, 892, 893, 894, 895, 896, 897, 898, 899, 900, 901, 902, 903, 904, 905, 906, 907, 908, 909, 910, 911, 912, 913, 914, 915, 916, 917, 918, 919, 920, 921, 922, 923, 924, 925, 926, 927, 928, 929, 930, 931, 932, 933, 934, 935, 936, 937, 938, 939, 940, 941, 942, 943, 944, 945, 946, 947, 948, 949, 950, 951, 952, 953, 954, 955, 956, 957, 958, 959, 960, 961, 962, 963, 964, 965, 966, 967, 968, 969, 970, 971, 972, 973, 974, 975, 976, 977, 978, 979, 980, 981, 982, 983, 984, 985, 986, 987, 988, 989, 990, 991, 992, 993, 994, 995, 996, 997, 998, 999, 1000, 1001, 1002, 1003, 1004, 1005, 1006, 1007, 1008, 1009, 1010, 1011, 1012, 1013, 1014, 1015, 1016, 1017, 1018, 1019, 1020, 1021, 1022, 1023, 1024, 1025, 1026, 1027, 1028, 1029, 1030, 1031, 1032, 1033, 1034, 1035, 1036, 1037, 1038, 1039, 1040, 1041, 1042, 1043, 1044, 1045, 1046, 1047, 1048, 1049, 1050, 1051, 1052, 1053, 1054, 1055, 1056, 1057, 1058, 1059, 1060, 1061, 1062, 1063, 1064, 1065, 1066, 1067, 1068, 1069, 1070, 1071, 1072, 1073, 1074, 1075, 1076, 1077, 1078, 1079, 1080, 1081, 1082, 1083, 1084, 1085, 1086, 1087, 1088, 1089, 1090, 1091, 1092, 1093, 1094, 1095, 1096, 1097, 1098, 1099, 1100, 1101, 1102, 1103, 1104, 1105, 1106, 1107, 1108, 1109, 1110, 1111, 1112, 1113, 1114, 1115, 1116, 1117, 1118, 1119, 1120, 1121, 1122, 1123, 1124, 1125, 1126, 1127, 1128, 1129, 1130, 1131, 1132, 1133, 1134, 1135, 1136, 1137, 1138, 1139, 1140, 1141, 1142, 1143, 1144, 1145, 1146, 1147, 1148, 1149, 1150, 1151, 1152, 1153, 1154, 1155, 1156, 1157, 1158, 1159, 1160, 1161, 1162, 1163, 1164, 1165, 1166, 1167, 1168, 1169, 1170, 1171, 1172, 1173, 1174, 1175, 1176, 1177, 1178, 1179, 1180, 1181, 1182, 1183, 1184, 1185, 1186, 1187, 1188, 1189, 1190, 1191, 1192, 1193, 1194, 1195, 1196, 1197, 1198, 1199, 1200, 1201, 1202, 1203, 1204, 1205, 1206, 1207, 1208, 1209, 1210, 1211, 1212, 1213, 1214, 1215, 1216, 1217, 1218, 1219, 1220, 1221, 1222, 1223, 1224, 1225, 1226, 1227, 1228, 1229, 1230, 1231, 1232, 1233, 1234, 1235, 1236, 1237, 1238, 1239, 1240, 1241, 1242, 1243, 1244, 1245, 1246, 1247, 1248, 1249, 1250, 1251, 1252, 1253, 1254, 1255, 1256, 1257, 1258, 1259, 1260, 1261, 1262, 1263, 1264, 1265, 1266, 1267, 1268, 1269, 1270, 1271, 1272, 1273, 1274, 1275, 1276, 1277, 1278, 1279, 1280, 1281, 1282, 1283, 1284, 1285, 1286, 1287, 1288, 1289, 1290, 1291, 1292, 1293, 1294, 1295, 1296, 1297, 1298, 1299, 1300, 1301, 1302, 1303, 1304, 1305, 1306, 1307, 1308, 1309, 1310, 1311, 1312, 1313, 1314, 1315, 1316, 1317, 1318, 1319, 1320, 1321, 1322, 1323, 1324, 1325, 1326, 1327, 1328, 1329, 1330, 1331, 1332, 1333, 1334, 1335, 1336, 1337, 1338, 1339, 1340, 1341, 1342, 1343, 1344, 1345, 1346, 1347, 1348, 1349, 1350, 1351, 1352, 1353, 1354, 1355, 1356, 1357, 1358, 1359, 1360, 1361, 1362, 1363, 1364, 1365, 1366, 1367, 1368, 1369, 1370, 1371, 1372, 1373, 1374, 1375, 1376, 1377, 1378, 1379, 1380, 1381, 1382, 1383, 1384, 1385, 1386, 1387, 1388, 1389, 1390, 1391, 1392, 1393, 1394, 1395, 1396, 1397, 1398, 1399, 1400, 1401, 1402, 1403, 1404, 1405, 1406, 1407, 1408, 1409, 1410, 1411, 1412, 1413, 1414, 1415, 1416, 1417, 1418, 1419, 1420, 1421, 1422, 1423, 1424, 1425, 1426, 1427, 1428, 1429, 1430, 1431, 1432, 1433, 1434, 1435, 1436, 1437, 1438, 1439, 1440, 1441, 1442, 1443, 1444, 1445, 1446, 1447, 1448, 1449, 1450, 1451, 1452, 1453, 1454, 1455, 1456, 1457, 1458, 1459, 1460, 1461, 1462, 1463, 1464, 1465, 1466, 1467, 1468, 1469, 1470, 1471, 1472, 1473, 1474, 1475, 1476, 1477, 1478, 1479, 1480, 1481, 1482, 1483, 1484, 1485, 1486, 1487, 1488, 1489, 1490, 1491, 1492, 1493, 1494, 1495, 1496, 1497, 1498, 1499, 1500, 1501, 1502, 1503, 1504, 1505, 1506, 1507, 1508, 1509, 1510, 1511, 1512, 1513, 1514, 1515, 1516, 1517, 1518, 1519, 1520, 1521, 1522, 1523, 1524, 1525, 1526, 1527, 1528, 1529, 1530, 1531, 1532, 1533, 1534, 1535, 1536, 1537, 1538, 1539, 1540, 1541, 1542, 1543, 1544, 1545, 1546, 1547, 1548, 1549, 1550, 1551, 1552, 1553, 1554, 1555, 1556, 1557, 1558, 1559, 1560, 1561, 1562, 1563, 1564, 1565, 1566, 1567, 1568, 1569, 1570, 1571, 1572, 1573, 1574, 1575, 1576, 1577, 1578, 1579, 1580, 1581, 1582, 1583, 1584, 1585, 1586, 1587, 1588, 1589, 1590, 1591, 1592, 1593, 1594, 1595, 1596, 1597, 1598, 1599, 1600, 1601, 1602, 1603, 1604, 1605, 1606, 1607, 1608, 1609, 1610, 1611, 1612, 1613, 1614, 1615, 1616, 1617, 1618, 1619, 1620, 1621, 1622, 1623, 1624, 1625, 1626, 1627, 1628, 1629, 1630, 1631, 1632, 1633, 1634, 1635, 1636, 1637, 1638, 1639, 1640, 1641, 1642, 1643, 1644, 1645, 1646, 1647, 1648, 1649, 1650, 1651, 1652, 1653, 1654, 1655, 1656, 1657, 1658, 1659, 1660, 1661, 1662, 1663, 1664, 1665, 1666, 1667, 1668, 1669, 1670, 1671, 1672, 1673, 1674, 1675, 1676, 1677, 1678, 1679, 1680, 1681, 1682, 1683, 1684, 1685, 1686, 1687, 1688, 1689, 1690, 1691, 1692, 1693, 1694, 1695, 1696, 1697, 1698, 1699, 1700, 1701, 1702, 1703, 1704, 1705, 1706, 1707, 1708, 1709, 1710, 1711, 1712, 1713, 1714, 1715, 1716, 1717, 1718, 1719, 1720, 1721, 1722, 1723, 1724, 1725, 1726, 1727, 1728, 1729, 1730, 1731, 1732, 1733, 1734, 1735, 1736, 1737, 1738, 1739, 1740, 1741, 1742, 1743, 1744, 1745, 1746, 1747, 1748, 1749, 1750, 1751, 1752, 1753, 1754, 1755, 1756, 1757, 1758, 1759, 1760, 1761, 1762, 1763, 1764, 1765, 1766, 1767, 1768, 1769, 1770, 1771, 1772, 1773, 1774, 1775, 1776, 1777, 1778, 1779, 1780, 1781, 1782, 1783, 1784, 1785, 1786, 1787, 1788, 1789, 1790, 1791, 1792, 1793, 1794, 1795, 1796, 1797, 1798, 1799, 1800, 1801, 1802, 1803, 1804, 1805, 1806, 1807, 1808, 1809, 1810, 1811, 1812, 1813, 1814, 1815, 1816, 1817, 1818, 1819, 1820, 1821, 1822, 1823, 1824, 1825, 1826, 1827, 1828, 1829, 1830, 1831, 1832, 1833, 1834, 1835, 1836, 1837, 1838, 1839, 1840, 1841, 1842, 1843, 1844, 1845, 1846, 1847, 1848, 1849, 1850, 1851, 1852, 1853, 1854, 1855, 1856, 1857, 1858, 1859, 1860, 1861, 1862, 1863, 1864, 1865, 1866, 1867, 1868, 1869, 1870, 1871, 1872, 1873, 1874, 1875, 1876, 1877, 1878, 1879, 1880, 1881, 1882, 1883, 1884, 1885, 1886, 1887, 1888, 1889, 1890, 1891, 1892, 1893, 1894, 1895, 1896, 1897, 1898, 1899, 1900, 1901, 1902, 1903, 1904, 1905, 1906, 1907, 1908, 1909, 1910, 1911, 1912, 1913, 1914, 1915, 1916, 1917, 1918, 1919, 1920, 1921, 1922, 1923, 1924, 1925, 1926, 1927, 1928, 1929, 1930, 1931, 1932, 1933, 1934, 1935, 1936, 1937, 1938, 1939, 1940, 1941, 1942, 1943, 1944, 1945, 1946, 1947, 1948, 1949, 1950, 1951, 1952, 1953, 1954, 1955, 1956, 1957, 1958, 1959, 1960, 1961, 1962, 1963, 1964, 1965, 1966, 1967, 1968, 1969, 1970, 1971, 1972, 1973, 1974, 1975, 1976, 1977, 1978, 1979, 1980, 1981, 1982, 1983, 1984, 1985, 1986, 1987, 1988, 1989, 1990, 1991, 1992, 1993, 1994, 1995, 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005, 2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013, 2014, 2015, 2016, 2017, 2018, 2019, 2020, 2021, 2022, 2023, 2024, 2025, 2026, 2027, 2028, 2029, 2030, 2031, 2032, 2033, 2034, 2035, 2036, 2037, 2038, 2039, 2040, 2041, 2042, 2043, 2044, 2045, 2046, 2047, 2048, 2049, 2050, 2051, 2052, 2053, 2054, 2055, 2056, 2057, 2058, 2059, 2060, 2061, 2062, 2063, 2064, 2065, 2066, 2067, 2068, 2069, 2070, 2071, 2072, 2073, 2074, 2075, 2076, 2077, 2078, 2079, 2080, 2081, 2082, 2083, 2084, 2085, 2086, 2087, 2088, 2089, 2090, 2091, 2092, 2093, 2094, 2095, 2096, 2097, 2098, 2099, 2100, 2101, 2102, 2103, 2104, 2105, 2106, 2107, 2108, 2109, 2110, 2111, 2112, 2113, 2114, 2115, 2116, 2117, 2118, 2119, 2120, 2121, 2122, 2123, 2124, 2125, 2126, 2127, 2128, 2129, 2130, 2131, 2132, 2133, 2134, 2135, 2136, 2137, 2138, 2139, 2140, 2141, 2142, 2143, 2144, 2145, 2146, 2147, 2148, 2149, 2150, 2151, 2152, 2153, 2154, 2155, 2156, 2157, 2158, 2159, 2160, 2161, 2162, 2163, 2164, 2165, 2166, 2167, 2168, 2169, 2170, 2171, 2172, 2173, 2174, 2175, 2176, 2177, 2178, 2179, 2180, 2181, 2182, 2183, 2184, 2185, 2186, 2187, 2188, 2189, 2190, 2191, 2192, 2193, 2194, 2195, 2196, 2197, 2198, 2199, 2200, 2201, 2202, 2203, 2204, 2205, 2206, 2207, 2208, 2209, 2210, 2211, 2212, 2213, 2214, 2215, 2216, 2217, 2218, 2219, 2220, 2221, 2222, 2223, 2224, 2225, 2226, 2227, 2228, 2229, 2230, 2231, 2232, 2233, 2234, 2235, 2236, 2237, 2238, 2239, 2240, 2241, 2242, 2243, 2244, 2245, 2246, 2247, 2248, 2249, 2250, 2251, 2252, 2253, 2254, 2255, 2256, 2257, 2258, 2259, 2260, 2261, 2262, 2263, 2264, 2265, 2266, 2267, 2268, 2269, 2270, 2271, 2272, 2273, 2274, 2275, 2276, 2277, 2278, 2279, 2280, 2281, 2282, 2283, 2284, 2285, 2286, 2287, 2288, 2289, 2290, 2291, 2292, 2293, 2294, 2295, 2296, 2297, 2298, 2299, 2300, 2301, 2302, 2303, 2304, 2305, 2306, 2307, 2308, 2309, 2310, 2311, 2312, 2313, 2314, 2315, 2316, 2317, 2318, 2319, 2320, 2321, 2322, 2323, 2324, 2325, 2326, 2327, 2328, 2329, 2330, 2331, 2332, 2333, 2334, 2335, 2336, 2337, 2338, 2339, 2340, 2341, 2342, 2343, 2344, 2345, 2346, 2347, 2348, 2349, 2350, 2351, 2352, 2353, 2354, 2355, 2356, 2357, 2358, 2359, 2360, 2361, 2362, 2363, 2364, 2365, 2366, 2367, 2368, 2369, 2370, 2371, 2372, 2373, 2374, 2375, 2376, 2377, 2378, 2379, 2380, 2381, 2382, 2383, 2384, 2385, 2386, 2387, 2388, 2389, 2390, 2391, 2392, 2393, 2394, 2395, 2396, 2397, 2398, 2399, 2400, 2401, 2402, 2403, 2404, 2405, 2406, 2407, 2408, 2409, 2410, 2411, 2412, 2413, 2414, 2415, 2416, 2417, 2418, 2419, 2420, 2421, 2422, 2423, 2424, 2425, 2426, 2427, 2428, 2429, 2430, 2431, 2432, 2433, 2434, 2435, 2436, 2437, 2438, 2439, 2440, 2441, 2442, 2443, 2444, 2445, 2446, 2447, 2448, 2449, 2450, 2451, 2452, 2453, 2454, 2455, 2456, 2457, 2458, 2459, 2460, 2461, 2462, 2463, 2464, 2465, 2466, 2467, 2468, 2469, 2470, 2471, 2472, 2473, 2474, 2475, 2476, 2477, 2478, 2479, 2480, 2481, 2482, 2483, 2484, 2485, 2486, 2487, 2488, 2489, 2490, 2491, 2492, 2493, 2494, 2495, 2496, 2497, 2498, 2499, 2500, 2501, 2502, 2503, 2504, 2505, 2506, 2507, 2508, 2509, 2510, 2511, 2512, 2513, 2514, 2515, 2516, 2517, 2518, 2519, 2520, 2521, 2522, 2523, 2524, 2525, 2526, 2527, 2528, 2529, 2530, 2531, 2532, 2533, 2534, 2535, 2536, 2537, 2538, 2539, 2540, 2541, 2542, 2543, 2544, 2545, 2546, 2547, 2548, 2549, 2550, 2551, 2552, 2553, 2554, 2555, 2556, 2557, 2558, 2559, 2560, 2561, 2562, 2563, 2564, 2565, 2566, 2567, 2568, 2569, 2570, 2571, 2572, 2573, 2574, 2575, 2576, 2577, 2578, 2579, 2580, 2581, 2582, 2583, 2584, 2585, 2586, 2587, 2588, 2589, 2590, 2591, 2592, 2593, 2594, 2595, 2596, 2597, 2598, 2599, 2600, 2601, 2602, 2603, 2604, 2605, 2606, 2607, 2608, 2609, 2610, 2611, 2612, 2613, 2614, 2615, 2616, 2617, 2618, 2619, 2620, 2621, 2622, 2623, 2624, 2625, 2626, 2627, 2628, 2629, 2630, 2631, 2632, 2633, 2634, 2635, 2636, 2637, 2638, 2639, 2640, 2641, 2642, 2643, 2644, 2645, 2646, 2647, 2648, 2649, 2650, 2651, 2652, 2653, 2654, 2655, 2656, 2657, 2658, 2659, 2660, 2661, 2662, 2663, 2664, 2665, 2666, 2667, 2668, 2669, 2670, 2671, 2672, 2673, 2674, 2675, 2676, 2677, 2678, 2679, 2680, 2681, 2682, 2683, 2684, 2685, 2686, 2687, 2688, 2689, 2690, 2691, 2692, 2693, 2694, 2695, 2696, 2697, 2698, 2699, 2700, 2701, 2702, 2703, 2704, 2705, 2706, 2707, 2708, 2709, 2710, 2711, 2712, 2713, 2714, 2715, 2716, 2717, 2718, 2719, 2720, 2721, 2722, 2723, 2724, 2725, 2726, 2727, 2728, 2729, 2730, 2731, 2732, 2733, 2734, 2735, 2736, 2737, 2738, 2739, 2740, 2741, 2742, 2743, 2744, 2745, 2746, 2747, 2748, 2749, 2750, 2751, 2752, 2753, 2754, 2755, 2756, 2757, 2758, 2759, 2760, 2761, 2762, 2763, 2764, 2765, 2766, 2767, 2768, 2769, 2770, 2771, 2772, 2773, 2774, 2775, 2776, 2777, 2778, 2779, 2780, 2781, 2782, 2783, 2784, 2785, 2786, 2787, 2788, 2789, 2790, 2791, 2792, 2793, 2794, 2795, 2796, 2797, 2798, 2799, 2800, 2801, 2802, 2803, 2804, 2805, 2806, 2807, 2808, 2809, 2810, 2811, 2812, 2813, 2814, 2815, 2816, 2817, 2818, 2819, 2820, 2821, 2822, 2823, 2824, 2825, 2826, 2827, 2828, 2829, 2830, 2831, 2832, 2833, 2834, 2835, 2836, 2837, 2838, 2839, 2840, 2841, 2842, 2843, 2844, 2845, 2846, 2847, 2848, 2849, 2850, 2851, 2852, 2853, 2854, 2855, 2856, 2857, 2858, 2859, 2860, 2861, 2862, 2863, 2864, 2865, 2866, 2867, 2868, 2869, 2870, 2871, 2872, 2873, 2874, 2875, 2876, 2877, 2878, 2879, 2880, 2881, 2882, 2883, 2884, 2885, 2886, 2887, 2888, 2889, 2890, 2891, 2892, 2893, 2894, 2895, 2896, 2897, 2898, 2899, 2900, 2901, 2902, 2903, 2904, 2905, 2906, 2907, 2908, 2909, 2910, 2911, 2912, 2913, 2914, 2915, 2916, 2917, 2918, 2919, 2920, 2921, 2922, 2923, 2924, 2925, 2926, 2927, 2928, 2929, 2930, 2931, 2932, 2933, 2934, 2935, 2936, 2937, 2938, 2939, 2940, 2941, 2942, 2943, 2944, 2945, 2946, 2947, 2948, 2949, 2950, 2951, 2952, 2953, 2954, 2955, 2956, 2957, 2958, 2959, 2960, 2961, 2962, 2963, 2964, 2965, 2966, 2967, 2968, 2969, 2970, 2971, 2972, 2973, 2974, 2975, 2976, 2977, 2978, 2979, 2980, 2981, 2982, 2983, 2984, 2985, 2986, 2987, 2988, 2989, 2990, 2991, 2992, 2993, 2994, 2995, 2996, 2997, 2998, 2999, 3000, 3001, 3002, 3003, 3004, 3005, 3006, 3007, 3008, 3009, 3010, 3011, 3012, 3013, 3014, 3015, 3016, 3017, 3018, 3019, 3020, 3021, 3022, 3023, 3024, 3025, 3026, 3027, 3028, 3029, 3030, 3031, 3032, 3033, 3034, 3035, 3036, 3037, 3038, 3039, 3040, 3041, 3042, 3043, 3044, 3045, 3046, 3047, 3048, 3049, 3050, 3051, 3052, 3053, 3054, 3055, 3056, 3057, 3058, 3059, 3060, 3061, 3062, 3063, 3064, 3065, 3066, 3067, 3068, 3069, 3070, 3071, 3072, 3073, 3074, 3075, 3076, 3077, 3078, 3079, 3080, 3081, 3082, 3083, 3084, 3085, 3086, 3087, 3088, 3089, 3090, 3091, 3092, 3093, 3094, 3095, 3096, 3097, 3098, 3099, 3100, 3101, 3102, 3103, 3104, 3105, 3106, 3107, 3108, 3109, 3110, 3111, 3112, 3113, 3114, 3115, 3116, 3117, 3118, 3119, 3120, 3121, 3122, 3123, 3124, 3125, 3126, 3127, 3128, 3129, 3130, 3131, 3132, 3133, 3134, 3135, 3136, 3137, 3138, 3139, 3140, 3141, 3142, 3143, 3144, 3145, 3146, 3147, 3148, 3149, 3150, 3151, 3152, 3153, 3154, 3155, 3156, 3157, 3158, 3159, 3160, 3161, 3162, 3163, 3164, 3165, 3166, 3167, 3168, 3169, 3170, 3171, 3172, 3173, 3174, 3175, 3176, 3177, 3178, 3179, 3180, 3181, 3182, 3183, 3184, 3185, 3186, 3187, 3188, 3189, 3190, 3191, 3192, 3193, 3194, 3195, 3196, 3197, 3198, 3199, 3200, 3201, 3202, 3203, 3204, 3205, 3206, 3207, 3208, 3209, 3210, 3211, 3212, 3213, 3214, 3215, 3216, 3217, 3218, 3219, 3220, 3221, 3222, 3223, 3224, 3225, 3226, 3227, 3228, 3229, 3230, 3231, 3232, 3233, 3234, 3235, 3236, 3237, 3238, 3239, 3240, 3241, 3242, 3243, 3244, 3245, 3246, 3247, 3248, 3249, 3250, 3251, 3252, 3253, 3254, 3255, 3256, 3257, 3258, 3259, 3260, 3261, 3262, 3263, 3264, 3265, 3266, 3267, 3268, 3269, 3270, 3271, 3272, 3273, 3274, 3275, 3276, 3277, 3278, 3279, 3280, 3281, 3282, 3283, 3284, 3285, 3286, 3287, 3288, 3289, 3290, 3291, 3292, 3293, 3294, 3295, 3296, 3297, 3298, 3299, 3300, 3301, 3302, 3303, 3304, 3305, 3306, 3307, 3308, 3309, 3310, 3311, 3312, 3313, 3314, 3315, 3316, 3317, 3318, 3319, 3320, 3321, 3322, 3323, 3324, 3325, 3326, 3327, 3328, 3329, 3330, 3331, 3332, 3333, 3334, 3335, 3336, 3337, 3338, 3339, 3340, 3341, 3342, 3343, 3344, 3345, 3346, 3347, 3348, 3349, 3350, 3351, 3352, 3353, 3354, 3355, 3356, 3357, 3358, 3359, 3360, 3361, 3362, 3363, 3364, 3365, 3366, 3367, 3368, 3369, 3370, 3371, 3372, 3373, 3374, 3375, 3376, 3377, 3378, 3379, 3380, 3381, 3382, 3383, 3384, 3385, 3386, 3387, 3388, 3389, 3390, 3391, 3392, 3393, 3394, 3395, 3396, 3397, 3398, 3399, 3400, 3401, 3402, 3403, 3404, 3405, 3406, 3407, 3408, 3409, 3410, 3411, 3412, 3413, 3414, 3415, 3416, 3417, 3418, 3419, 3420, 3421, 3422, 3423, 3424, 3425, 3426, 3427, 3428, 3429, 3430, 3431, 3432, 3433, 3434, 3435, 3436, 3437, 3438, 3439, 3440, 3441, 3442, 3443, 3444, 3445, 3446, 3447, 3448, 3449, 3450, 3451, 3452, 3453, 3454, 3455, 3456, 3457, 3458, 3459, 3460, 3461, 3462, 3463, 3464, 3465, 3466, 3467, 3468, 3469, 3470, 3471, 3472, 3473, 3474, 3475, 3476, 3477, 3478, 3479, 3480, 3481, 3482, 3483, 3484, 3485, 3486, 3487, 3488, 3489, 3490, 3491, 3492, 3493, 3494, 3495, 3496, 3497, 3498, 3499, 3500, 3501, 3502, 3503, 3504, 3505, 3506, 3507, 3508, 3509, 3510, 3511, 3512, 3513, 3514, 3515, 3516, 3517, 3518, 3519, 3520, 3521, 3522, 3523, 3524, 3525, 3526, 3527, 3528, 3529, 3530, 3531, 3532, 3533, 3534, 3535, 3536, 3537, 3538, 3539, 3540, 3541, 3542, 3543, 3544, 3545, 3546, 3547, 3548, 3549, 3550, 3551, 3552, 3553, 3554, 3555, 3556, 3557, 3558, 3559, 3560, 3561, 3562, 3563, 3564, 3565, 3566, 3567, 3568, 3569, 3570, 3571, 3572, 3573, 3574, 3575, 3576, 3577, 3578, 3579, 3580, 3581, 3582, 3583, 3584, 3585, 3586, 3587, 3588, 3589, 3590, 3591, 3592, 3593, 3594, 3595, 3596, 3597, 3598, 3599, 3600, 3601, 3602, 3603, 3604, 3605, 3606, 3607, 3608, 3609, 3610, 3611, 3612, 3613, 3614, 3615, 3616, 3617, 3618, 3619, 3620, 3621, 3622, 3623, 3624, 3625, 3626, 3627, 3628, 3629, 3630, 3631, 3632, 3633, 3634, 3635, 3636, 3637, 3638, 3639, 3640, 3641, 3642, 3643, 3644, 3645, 3646, 3647, 3648, 3649, 3650, 3651, 3652, 3653, 3654, 3655, 3656, 3657, 3658, 3659, 3660, 3661, 3662, 3663, 3664, 3665, 3666, 3667, 3668, 3669, 3670, 3671, 3672, 3673, 3674, 3675, 3676, 3677, 3678, 3679, 3680, 3681, 3682, 3683, 3684, 3685, 3686, 3687, 3688, 3689, 3690, 3691, 3692, 3693, 3694, 3695, 3696, 3697, 3698, 3699, 3700, 3701, 3702, 3703, 3704, 3705, 3706, 3707, 3708, 3709, 3710, 3711, 3712, 3713, 3714, 3715, 3716, 3717, 3718, 3719, 3720, 3721, 3722, 3723, 3724, 3725, 3726, 3727, 3728, 3729, 3730, 3731, 3732, 3733, 3734, 3735, 3736, 3737, 3738, 3739, 3740, 3741, 3742, 3743, 3744, 3745, 3746, 3747, 3748, 3749, 3750, 3751, 3752, 3753, 3754, 3755, 3756, 3757, 3758, 3759, 3760, 3761, 3762, 3763, 3764, 3765, 3766, 3767, 3768, 3769, 3770, 3771, 3772, 3773, 3774, 3775, 3776, 3777, 3778, 3779, 3780, 3781, 3782, 3783, 3784, 3785, 3786, 3787, 3788, 3789, 3790, 3791, 3792, 3793, 3794, 3795, 3796, 3797, 3798, 3799, 3800, 3801, 3802, 3803, 3804, 3805, 3806, 3807, 3808, 3809, 3810, 3811, 3812, 3813, 3814, 3815, 3816, 3817, 3818, 3819, 3820, 3821, 3822, 3823, 3824, 3825, 3826, 3827, 3828, 3829, 3830, 3831, 3832, 3833, 3834, 3835, 3836, 3837, 3838, 3839, 3840, 3841, 3842, 3843, 3844, 3845, 3846, 3847, 3848, 3849, 3850, 3851, 3852, 3853, 3854, 3855, 3856, 3857, 3858, 3859, 3860, 3861, 3862, 3863, 3864, 3865, 3866, 3867, 3868, 3869, 3870, 3871, 3872, 3873, 3874, 3875, 3876, 3877, 3878, 3879, 3880, 3881, 3882, 3883, 3884, 3885, 3886, 3887, 3888, 3889, 3890, 3891, 3892, 3893, 3894, 3895, 3896, 3897, 3898, 3899, 3900, 3901, 3902, 3903, 3904, 3905, 3906, 3907, 3908, 3909, 3910, 3911, 3912, 3913, 3914, 3915, 3916, 3917, 3918, 3919, 3920, 3921, 3922, 3923, 3924, 3925, 3926, 3927, 3928, 3929, 3930, 3931, 3932, 3933, 3934, 3935, 3936, 3937, 3938, 3939, 3940, 3941, 3942, 3943, 3944, 3945, 3946, 3947, 3948, 3949, 3950, 3951, 3952, 3953, 3954, 3955, 3956, 3957, 3958, 3959, 3960, 3961, 3962, 3963, 3964, 3965, 3966, 3967, 3968, 3969, 3970, 3971, 3972, 3973, 3974, 3975, 3976, 3977, 3978, 3979, 3980, 3981, 3982, 3983, 3984, 3985, 3986, 3987, 3988, 3989, 3990, 3991, 3992, 3993, 3994, 3995, 3996, 3997, 3998, 3999, 4000, 4001, 4002, 4003, 4004, 4005, 4006, 4007, 4008, 4009, 4010, 4011, 4012, 4013, 4014, 4015, 4016, 4017, 4018, 4019, 4020, 4021, 4022, 4023, 4024, 4025, 4026, 4027, 4028, 4029, 4030, 4031, 4032, 4033, 4034, 4035, 4036, 4037, 4038, 4039, 4040, 4041, 4042, 4043, 4044, 4045, 4046, 4047, 4048, 4049, 4050, 4051, 4052, 4053, 4054, 4055, 4056, 4057, 4058, 4059, 4060, 4061, 4062, 4063, 4064, 4065, 4066, 4067, 4068, 4069, 4070, 4071, 4072, 4073, 4074, 4075, 4076, 4077, 4078, 4079, 4080, 4081, 4082, 4083, 4084, 4085, 4086, 4087, 4088, 4089, 4090, 4091, 4092, 4093, 4094]: 16:52:19 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:19 DEBUG ClusterState | [ preallocate] NetworkToken [cluster=test1, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10] 16:52:19 INFO SLAs | [ accepted] NetworkToken [cluster=test1, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10] 16:52:19 DEBUG Network | :1265781120.0000:Network/DelegatingMethodAccessorImpl.invoke.43:eucalyptus:eucalyptus:preallocate:network=admin-default:cluster=test1:networkIndex=2: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:b13fdff8-9374-4fbe-bd5a-c8d7a6fb36eb:CREATE:END:11:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusImageManager.cacheImage(WalrusImageManager.java:413):f5414d58-ad17-4603-9fe3-5d2de445d5a8: 16:52:19 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[2], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[2], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:19 DEBUG ClusterAllocator | ================================================================================ | Setting up rules for: admin-default | ================================================================================ 16:52:19 DEBUG ClusterAllocator | -------------------------------------------------------------------------------- | Network [availableNetworkIndexes=[3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[2], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[2], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] | -------------------------------------------------------------------------------- 16:52:19 DEBUG ClusterAllocator | -------------------------------------------------------------------------------- | VmRunType [imageInfo=VmImageInfo [ancestorIds=[], imageId=emi-E027107D, imageLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/image.manifest.xml, kernelId=eki-F65C10F3, kernelLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/kernel.manifest.xml, productCodes=[], ramdiskId=eri-0ABC115A, ramdiskLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/ramdisk.manifest.xml, size=2147483648], instanceIds=[i-421507FB], keyInfo=VmKeyInfo [fingerprint=, name=, value=], launchIndex=0, macAddresses=[d0:0d:42:15:07:FB], max=1, min=1, networkIndexList=[2], networkNames=[default], reservationId=r-4C370790, userData=, vlan=10, vmTypeInfo=VmTypeInfo{name='c1.medium', memory=256, disk=5, cores=1}] | -------------------------------------------------------------------------------- 16:52:19 INFO ReplyQueue | Message: edu.ucsb.eucalyptus.msgs.RunInstancesResponseType:65bfdc8a-b1ec-41bb-aa21-7a80a27f8ecf 16:52:19 INFO ServiceSinkHandler | :1265781120.0000:eucalyptus/ServiceSinkHandler.sendDownstreamNewEvent.162:admin:65bfdc8a-b1ec-41bb-aa21-7a80a27f8ecf:MSG_SERVICED:RunInstancesResponseType: 16:52:19 DEBUG QueuedEventCallback | -> Sending StartNetworkType network to: http://192.168.1.4:8774/axis2/services/EucalyptusCC 16:52:19 DEBUG QueuedEventCallback | | | 65bfdc8a-b1ec-41bb-aa21-7a80a27f8ecf | admin | eucalyptus | 10 | default | | : 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:StartNetworkCallback/MultiClusterCallback.fireEventAsyncToAllClusters.28:eucalyptus:eucalyptus:MSG_PENDING:test1: | | 65bfdc8a-b1ec-41bb-aa21-7a80a27f8ecf | admin | eucalyptus | 10 | default | | : 16:52:19 DEBUG ClusterMessageQueue | Queued message of type StartNetworkCallback for cluster test1 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:VmRunCallback/ClusterAllocator.queueEvents.312:eucalyptus:eucalyptus:MSG_PENDING:test1:VmRunType [imageInfo=VmImageInfo [ancestorIds=[], imageId=emi-E027107D, imageLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/image.manifest.xml, kernelId=eki-F65C10F3, kernelLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/kernel.manifest.xml, productCodes=[], ramdiskId=eri-0ABC115A, ramdiskLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/ramdisk.manifest.xml, size=2147483648], instanceIds=[i-421507FB], keyInfo=VmKeyInfo [fingerprint=, name=, value=], launchIndex=0, macAddresses=[d0:0d:42:15:07:FB], max=1, min=1, networkIndexList=[2], networkNames=[default], reservationId=r-4C370790, userData=, vlan=10, vmTypeInfo=VmTypeInfo{name='c1.medium', memory=256, disk=5, cores=1}]: 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:VmRunCallback/ClusterAllocator.queueEvents.312:eucalyptus:eucalyptus:MSG_PENDING:test1:VmRunType [imageInfo=VmImageInfo [ancestorIds=[], imageId=emi-E027107D, imageLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/image.manifest.xml, kernelId=eki-F65C10F3, kernelLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/kernel.manifest.xml, productCodes=[], ramdiskId=eri-0ABC115A, ramdiskLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/ramdisk.manifest.xml, size=2147483648], instanceIds=[i-421507FB], keyInfo=VmKeyInfo [fingerprint=, name=, value=], launchIndex=0, macAddresses=[d0:0d:42:15:07:FB], max=1, min=1, networkIndexList=[2], networkNames=[default], reservationId=r-4C370790, userData=, vlan=10, vmTypeInfo=VmTypeInfo{name='c1.medium', memory=256, disk=5, cores=1}]: 16:52:19 DEBUG ClusterMessageQueue | Queued message of type VmRunCallback for cluster test1 16:52:19 DEBUG ChannelStateMonitor | :1265781120.0000:ServiceSinkHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781139217:508:/192.168.1.4:8773:/172.19.1.1:36167: 16:52:19 DEBUG ClusterMessageQueue | -> Dequeued message of type StartNetworkCallback 16:52:19 DEBUG ChannelStateMonitor | :1265781120.0000:StartNetworkCallback/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49462:/192.168.1.4:8774: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.util.EucalyptusProperties.getSystemConfiguration(EucalyptusProperties.java:117):41154dc7-50a2-44d4-b7e2-88d146e4dd4b: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:6ccdc742-896b-4191-8506-94eb2e2d2737:CREATE:END:34:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusImageManager.checkImage(WalrusImageManager.java:946):2e6c5b6b-d678-492d-a4ef-119d1925d5f2: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:6ccdc742-896b-4191-8506-94eb2e2d2737:CREATE:END:35:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusImageManager.checkManifest(WalrusImageManager.java:283):630a2f73-5f39-474b-980e-3051a6251bac: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:6ccdc742-896b-4191-8506-94eb2e2d2737:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getUser(CredentialProvider.java:268):bb338bcc-ac7e-4a08-b226-53f2ec9f6f43: 16:52:19 DEBUG NioResponseHandler | StartNetworkCallback Got response of: 16:52:19 DEBUG NioResponseHandler | Waiting for response. 16:52:19 DEBUG NioResponseHandler | :1265781120.0000:NioResponseHandler/NioResponseHandler.waitForResponse.192:eucalyptus:eucalyptus:MSG_SERVICED:class edu.ucsb.eucalyptus.msgs.StartNetworkResponseType: 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:StartNetworkCallback/ClusterMessageQueue.run.127:eucalyptus:eucalyptus:QUEUE_TIME:test1:30: 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:StartNetworkCallback/ClusterMessageQueue.run.128:eucalyptus:eucalyptus:SERVICE_TIME:test1:106: 16:52:19 DEBUG ChannelStateMonitor | :1265781120.0000:StartNetworkCallback/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781139328:105:/192.168.1.4:49462:lifelessdesktop.local/192.168.1.4:8774: 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:StartNetworkCallback/ClusterMessageQueue.run.129:eucalyptus:eucalyptus:QUEUE_LENGTH:test1:1: 16:52:19 DEBUG ClusterMessageQueue | -> Dequeued message of type VmRunCallback 16:52:19 DEBUG ChannelStateMonitor | :1265781120.0000:VmRunCallback/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49465:/192.168.1.4:8774: 16:52:19 DEBUG VmRunCallback | -------------------------------------------------------------------------------- | VmRunType [imageInfo=VmImageInfo [ancestorIds=[], imageId=emi-E027107D, imageLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/image.manifest.xml, kernelId=eki-F65C10F3, kernelLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/kernel.manifest.xml, productCodes=[], ramdiskId=eri-0ABC115A, ramdiskLocation=http://192.168.1.4:8773/services/Walrus/image-store-1265758245/ramdisk.manifest.xml, size=2147483648], instanceIds=[i-421507FB], keyInfo=VmKeyInfo [fingerprint=, name=, value=], launchIndex=0, macAddresses=[d0:0d:42:15:07:FB], max=1, min=1, networkIndexList=[2], networkNames=[default], reservationId=r-4C370790, userData=, vlan=10, vmTypeInfo=VmTypeInfo{name='c1.medium', memory=256, disk=5, cores=1}] | -------------------------------------------------------------------------------- 16:52:19 DEBUG ClusterNodeState | [ submitted] ResourceToken [addresses=[192.168.1.100], amount=1, cluster=test1, correlationId=65bfdc8a-b1ec-41bb-aa21-7a80a27f8ecf, creationTime=Wed Feb 10 16:52:19 EST 2010, instanceIds=[i-421507FB], networkTokens=[NetworkToken [cluster=test1, indexes=[2], name=admin-default, networkName=default, userName=admin, vlan=10]], sequenceNumber=7, userName=admin, vmType=c1.medium] 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:b8049b28-0034-4767-98e2-c972f460b891:CREATE:END:0:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusImageManager.cacheImage(WalrusImageManager.java:988):a2982502-456f-4bf5-82ad-0c90b6f9b31d: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:b8049b28-0034-4767-98e2-c972f460b891:CREATE:END:0:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusImageManager.cacheImage(WalrusImageManager.java:1000):153d719a-6643-4776-8370-69c6a6ca5a82: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:b8049b28-0034-4767-98e2-c972f460b891:CREATE:END:0:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusImageManager.cacheImage(WalrusImageManager.java:413):9c18c8ce-e419-401f-bcc9-ccccc541ae1c: 16:52:19 DEBUG ChannelStateMonitor | :1265781120.0000:NioServerHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:8773:/192.168.1.1:56214: 16:52:19 DEBUG FilteredPipeline | :1265781120.0000:WalrusRESTPipeline/FilteredPipeline.accepts.102:eucalyptus:eucalyptus:PIPELINE_UNROLL:WalrusRESTPipeline: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getCertificateAlias(CredentialProvider.java:120):fdd0e661-3398-4801-ad84-7191df757ae6: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getUser(CredentialProvider.java:268):28f05c99-b777-40cd-9c7c-6961c6b497b0: 16:52:19 INFO WalrusRESTBinding | | | | | | b791f72d-ed5e-474e-b1c4-5a0ed1d1f945 | WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw | 2010-02-10T05:52:19.46Z | | image-store-1265758245 | kernel.manifest.xml | | | true | true | false | 16:52:19 INFO ServiceSinkHandler | :1265781120.0000:eucalyptus/ServiceSinkHandler.handleUpstream.189:eucalyptus:eucalyptus:MSG_RECEIVED:GetObjectType: 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:admin:b791f72d-ed5e-474e-b1c4-5a0ed1d1f945:CREATE:END:1:eucalyptus_walrus:edu.ucsb.eucalyptus.cloud.ws.WalrusManager.getObject(WalrusManager.java:1417):d4cfd25d-8b55-4ff8-891a-986f6b961906: 16:52:19 INFO WalrusStatistics | Service: Walrus Version: 1.6-devel Bytes In: 191615859 Bytes Out: 3476 Buckets: 1 Space Used: 191615859 16:52:19 DEBUG ChannelStateMonitor | :1265781120.0000:ServiceSinkHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781139506:59:/192.168.1.4:8773:/192.168.1.1:56214: 16:52:19 INFO ReplyQueue | Message: edu.ucsb.eucalyptus.msgs.GetObjectResponseType:b791f72d-ed5e-474e-b1c4-5a0ed1d1f945 16:52:19 DEBUG ClusterNodeState | [ redeemed] ResourceToken [addresses=[192.168.1.100], amount=1, cluster=test1, correlationId=65bfdc8a-b1ec-41bb-aa21-7a80a27f8ecf, creationTime=Wed Feb 10 16:52:19 EST 2010, instanceIds=[i-421507FB], networkTokens=[NetworkToken [cluster=test1, indexes=[2], name=admin-default, networkName=default, userName=admin, vlan=10]], sequenceNumber=7, userName=admin, vmType=c1.medium] 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.util.EucalyptusProperties.getSystemConfiguration(EucalyptusProperties.java:117):afdde2c3-46c9-41f1-8cc9-74587d711dbc: 16:52:19 DEBUG AddressUtil | Found 1 addresses allocated to eucalyptus 16:52:19 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.util.EucalyptusProperties.getSystemConfiguration(EucalyptusProperties.java:117):497453ae-7a72-46a3-891e-153f739b3ef4: 16:52:19 DEBUG ClusterAllocator | :1265781120.0000:Address/VmRunCallback.assignAddresses.135:eucalyptus:eucalyptus:ASSIGN_ADDRESSES:Address [cluster=test1, instanceAddress=172.19.1.2, instanceId=i-421507FB, name=192.168.1.100, pending=true, state=assigned, userId=eucalyptus]:172.19.1.2:i-421507FB: 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:AssignAddressCallback/Clusters.dispatchClusterEvent.132:eucalyptus:eucalyptus:MSG_PENDING:test1: | | ea749418-d2f3-4258-ad47-d9df5b5e321a | i-421507FB | 192.168.1.100 | 172.19.1.2 | : 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:AssignAddressCallback/Clusters.dispatchClusterEvent.132:eucalyptus:eucalyptus:MSG_PENDING:test1: | | ea749418-d2f3-4258-ad47-d9df5b5e321a | i-421507FB | 192.168.1.100 | 172.19.1.2 | : 16:52:19 DEBUG ClusterMessageQueue | Queued message of type AssignAddressCallback for cluster test1 16:52:19 DEBUG NioResponseHandler | VmRunCallback Got response of: Dequeued message of type AssignAddressCallback 16:52:19 DEBUG ChannelStateMonitor | :1265781120.0000:VmRunCallback/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781139697:368:/192.168.1.4:49465:lifelessdesktop.local/192.168.1.4:8774: 16:52:19 DEBUG ChannelStateMonitor | :1265781120.0000:AssignAddressCallback/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49472:/192.168.1.4:8774: 16:52:19 DEBUG AssignAddressCallback | :1265781120.0000:AssignAddressCallback/AssignAddressCallback.prepare.104:eucalyptus:eucalyptus:assigning:Address [cluster=test1, instanceAddress=172.19.1.2, instanceId=i-421507FB, name=192.168.1.100, pending=true, state=assigned, userId=eucalyptus]: 16:52:19 DEBUG AssignAddressCallback | :1265781120.0000:AssignAddressCallback/AssignAddressCallback.verify.120:eucalyptus:eucalyptus:assigned: 16:52:19 DEBUG NioResponseHandler | Waiting for response. 16:52:19 DEBUG NioResponseHandler | :1265781120.0000:NioResponseHandler/NioResponseHandler.waitForResponse.192:eucalyptus:eucalyptus:MSG_SERVICED:class edu.ucsb.eucalyptus.msgs.AssignAddressResponseType: 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:AssignAddressCallback/ClusterMessageQueue.run.127:eucalyptus:eucalyptus:QUEUE_TIME:test1:0: 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:AssignAddressCallback/ClusterMessageQueue.run.128:eucalyptus:eucalyptus:SERVICE_TIME:test1:71: 16:52:19 DEBUG ChannelStateMonitor | :1265781120.0000:AssignAddressCallback/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781139775:70:/192.168.1.4:49472:lifelessdesktop.local/192.168.1.4:8774: 16:52:19 DEBUG ClusterMessageQueue | :1265781120.0000:AssignAddressCallback/ClusterMessageQueue.run.129:eucalyptus:eucalyptus:QUEUE_LENGTH:test1:0: 16:52:20 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):eb8e8dc6-b88f-468d-b9ef-5c5a5aa59c1a: 16:52:20 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):18392ce9-d599-45c2-a537-946128579e19: 16:52:20 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):0a63af93-4bf2-4b63-abb5-4624e3a40e98: 16:52:20 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:20 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:20 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:20 DEBUG header | >> "Content-Length: 562[\r][\n]" 16:52:20 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:20 DEBUG header | >> "[\r][\n]" 16:52:20 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781170&Nonce=194607468308374&Signature=zuMAJ1T1lRF3kZnuLqczdaM8la5c90jJN3z6wmfJ9Fk%3D" 16:52:20 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:20 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:20 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:20 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:20 GMT[\r][\n]" 16:52:20 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:20 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:20 DEBUG header | << "[\r][\n]" 16:52:20 DEBUG content | << "4" 16:52:20 DEBUG content | << "1" 16:52:20 DEBUG content | << "b" 16:52:20 DEBUG content | << "[\r]" 16:52:20 DEBUG content | << "[\n]" 16:52:20 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 46.484064111366656, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:20 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:20 DEBUG content | << "[\r]" 16:52:20 DEBUG content | << "[\n]" 16:52:20 DEBUG content | << "0" 16:52:20 DEBUG content | << "[\r]" 16:52:20 DEBUG content | << "[\n]" 16:52:20 DEBUG content | << "[\r]" 16:52:20 DEBUG content | << "[\n]" 16:52:20 DEBUG header | << "[\r][\n]" 16:52:22 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):5047c124-f9f0-48ee-9854-8e342b0e34c4: 16:52:22 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):1fd15ed1-c80a-435e-91b9-514382b5bee0: 16:52:22 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):c597d806-1818-4d42-b7a6-69a06eaf257b: 16:52:22 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:22 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:22 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:22 DEBUG header | >> "Content-Length: 566[\r][\n]" 16:52:22 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:22 DEBUG header | >> "[\r][\n]" 16:52:22 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781172&Nonce=194609002366256&Signature=AwIeWfA6Jo7T3XYHl0aLp61OFIJ%2BvXbfypthc%2FG3emc%3D" 16:52:22 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:22 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:22 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:22 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:22 GMT[\r][\n]" 16:52:22 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:22 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:22 DEBUG header | << "[\r][\n]" 16:52:22 DEBUG content | << "4" 16:52:22 DEBUG content | << "1" 16:52:22 DEBUG content | << "b" 16:52:22 DEBUG content | << "[\r]" 16:52:22 DEBUG content | << "[\n]" 16:52:22 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 46.717326101268341, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:22 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:22 DEBUG content | << "[\r]" 16:52:22 DEBUG content | << "[\n]" 16:52:22 DEBUG content | << "0" 16:52:22 DEBUG content | << "[\r]" 16:52:22 DEBUG content | << "[\n]" 16:52:22 DEBUG content | << "[\r]" 16:52:22 DEBUG content | << "[\n]" 16:52:22 DEBUG header | << "[\r][\n]" 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusGL HTTP/1.1: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:23 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):9d9cc5c0-941f-41b8-b9da-33ea28e7c68d: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x07ced6d4, /192.168.1.4:49475 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5046: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x23fea2b4, /192.168.1.4:49476 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5168: 16:52:23 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49475:/192.168.1.4:8774: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: /192.168.1.4:8774] WRITTEN_AMOUNT: 5108: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:23 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49480:/192.168.1.4:8774: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x39e5b1e6, /192.168.1.4:49479 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5084: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x3109fae3, /192.168.1.4:49478 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5602: 16:52:23 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781143954:9:/192.168.1.4:49480:/192.168.1.4:8774: 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:23 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: | | b9ee2bbe-5e0d-41ab-89f5-dbb57a7e140c | eucalyptus | true | 1 | 32 | | 10 | default | admin | 0 | 1 | 2 | 31 | | 16:52:23 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[2], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[2], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:23 DEBUG ChannelStateMonitor | :1265781120.0000:NetworkStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781143961:29:/192.168.1.4:49476:/192.168.1.4:8774: 16:52:23 INFO ClusterUtil | --------------------------------------------------------------- 16:52:23 INFO ClusterUtil | -> [ test1 ] Cluster certificate valid=true 16:52:23 INFO ClusterUtil | -> [ test1 ] Node certificate valid=true 16:52:23 INFO ClusterUtil | --------------------------------------------------------------- 16:52:23 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781143966:34:/192.168.1.4:49475:/192.168.1.4:8774: 16:52:24 DEBUG ClusterNodeState | -------------------------------------------------------------------------------- | Resource update for cluster=test1, outstanding=0 pending=0 submitted=0 redeemed=1 | -------------------------------------------------------------------------------- 16:52:24 DEBUG ClusterNodeState | -> BEFORE: [ m1.small available=7/8 c1.medium available=7/8 m1.large available=3/4 m1.xlarge available=3/4 c1.xlarge available=1/2 ] 16:52:24 DEBUG ClusterNodeState | -> AFTER: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:24 DEBUG NetworkStateHandler | Adding node service tags: [http://192.168.1.4:8775/axis2/services/EucalyptusNC] 16:52:24 DEBUG ChannelStateMonitor | :1265781120.0000:ResourceStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781144031:76:/192.168.1.4:49478:/192.168.1.4:8774: 16:52:24 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:45:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):45382037-a295-436d-a3d8-777eed563d8c: 16:52:24 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):a2d93b6a-3f0b-457e-aad7-35ca64d18ef2: 16:52:24 INFO VmInstance | i-421507FB state change: PENDING -> SHUTTING_DOWN 16:52:24 DEBUG SystemState | :1265781120.0000:SystemState/SystemState.updateVmInstance.276:eucalyptus:eucalyptus:VM_TERMINATING:NETWORK_INDEX:admin-default:2: 16:52:24 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[2], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[2], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:24 DEBUG Network | :1265781120.0000:Network/DelegatingMethodAccessorImpl.invoke.43:eucalyptus:eucalyptus:returned:network=admin-default:networkIndex=2: 16:52:24 DEBUG SystemState | :1265781120.0000:SystemState/SystemState.updateVmInstance.277:eucalyptus:eucalyptus:VM_TERMINATING:i-421507FB: 16:52:24 DEBUG SystemState | :1265781120.0000:SystemState/SystemState.updateVmInstance.277:eucalyptus:eucalyptus:VM_TERMINATING:SYSTEM_ADDRESS:Address [cluster=test1, instanceAddress=172.19.1.2, instanceId=i-421507FB, name=192.168.1.100, pending=false, state=assigned, userId=eucalyptus]: 16:52:24 DEBUG Address | :1265781120.0000:Address/AddressUtil.markAddressUnassigned.91:eucalyptus:eucalyptus:unassigning:Address [cluster=test1, instanceAddress=172.19.1.2, instanceId=i-421507FB, name=192.168.1.100, pending=true, state=allocated, userId=eucalyptus]: 16:52:24 DEBUG ClusterMessageQueue | :1265781120.0000:UnassignAddressCallback/Clusters.dispatchClusterEvent.132:eucalyptus:eucalyptus:MSG_PENDING:test1: | | ace11c81-b463-460a-96e0-1d8cee1fbc69 | 192.168.1.100 | 172.19.1.2 | : 16:52:24 DEBUG ClusterMessageQueue | :1265781120.0000:UnassignAddressCallback/Clusters.dispatchClusterEvent.132:eucalyptus:eucalyptus:MSG_PENDING:test1: | | ace11c81-b463-460a-96e0-1d8cee1fbc69 | 192.168.1.100 | 172.19.1.2 | : 16:52:24 DEBUG ClusterMessageQueue | Queued message of type UnassignAddressCallback for cluster test1 16:52:24 DEBUG ClusterMessageQueue | -> Dequeued message of type UnassignAddressCallback 16:52:24 DEBUG Address | :1265781120.0000:Address/Address.release.282:eucalyptus:eucalyptus:allocated:Address [cluster=test1, instanceAddress=0.0.0.0, instanceId=available, name=192.168.1.100, pending=false, state=allocated, userId=eucalyptus]: 16:52:24 DEBUG ChannelStateMonitor | :1265781120.0000:UnassignAddressCallback/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49483:/192.168.1.4:8774: 16:52:24 DEBUG UnassignAddressCallback | :1265781120.0000:UnassignAddressCallback/UnassignAddressCallback.prepare.97:eucalyptus:eucalyptus:unassigning:Address [cluster=test1, instanceAddress=0.0.0.0, instanceId=available, name=192.168.1.100, pending=false, state=unallocated, userId=nobody]: 16:52:24 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.cloud.entities.Address.removeAddress(Address.java:296):b182daea-71bc-41b0-9031-ddabb6c5b46f: 16:52:24 DEBUG Address | :1265781120.0000:Address/AddressUtil.releaseAddress.141:eucalyptus:eucalyptus:unallocated:Address [cluster=test1, instanceAddress=0.0.0.0, instanceId=available, name=192.168.1.100, pending=false, state=unallocated, userId=nobody]: 16:52:24 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781144071:116:/192.168.1.4:49479:/192.168.1.4:8774: 16:52:24 INFO UnassignAddressCallback | [ unassigned] -------------------------------------------------------------------------------- | | | ace11c81-b463-460a-96e0-1d8cee1fbc69 | 192.168.1.100 | 172.19.1.2 | | -------------------------------------------------------------------------------- 16:52:24 DEBUG NioResponseHandler | UnassignAddressCallback Got response of: 16:52:24 DEBUG NioResponseHandler | Waiting for response. 16:52:24 DEBUG NioResponseHandler | :1265781120.0000:NioResponseHandler/NioResponseHandler.waitForResponse.192:eucalyptus:eucalyptus:MSG_SERVICED:class edu.ucsb.eucalyptus.msgs.UnassignAddressResponseType: 16:52:24 DEBUG ClusterMessageQueue | :1265781120.0000:UnassignAddressCallback/ClusterMessageQueue.run.127:eucalyptus:eucalyptus:QUEUE_TIME:test1:289: 16:52:24 DEBUG ClusterMessageQueue | :1265781120.0000:UnassignAddressCallback/ClusterMessageQueue.run.128:eucalyptus:eucalyptus:SERVICE_TIME:test1:80: 16:52:24 DEBUG ChannelStateMonitor | :1265781120.0000:UnassignAddressCallback/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781144146:79:/192.168.1.4:49483:lifelessdesktop.local/192.168.1.4:8774: 16:52:24 DEBUG ClusterMessageQueue | :1265781120.0000:UnassignAddressCallback/ClusterMessageQueue.run.129:eucalyptus:eucalyptus:QUEUE_LENGTH:test1:0: 16:52:25 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):b6f93028-88bd-4c66-be8d-81abf9b6cd31: 16:52:25 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):5ac7aeb4-93ff-456e-8ca2-308e67dccbba: 16:52:25 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):1dcf92d9-afcd-4633-ba8b-cc41e11f140d: 16:52:25 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:25 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:25 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:25 DEBUG header | >> "Content-Length: 570[\r][\n]" 16:52:25 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:25 DEBUG header | >> "[\r][\n]" 16:52:25 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781175&Nonce=194612466799031&Signature=%2Bq3rrTszD6IY9mzMCJaC%2B9KHWdrlrgGWz%2F5U%2BN2Dqyw%3D" 16:52:25 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:25 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:25 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:25 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:25 GMT[\r][\n]" 16:52:25 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:25 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:25 DEBUG header | << "[\r][\n]" 16:52:25 DEBUG content | << "4" 16:52:25 DEBUG content | << "1" 16:52:25 DEBUG content | << "b" 16:52:25 DEBUG content | << "[\r]" 16:52:25 DEBUG content | << "[\n]" 16:52:25 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 47.273566231033904, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:25 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:25 DEBUG content | << "[\r]" 16:52:25 DEBUG content | << "[\n]" 16:52:25 DEBUG content | << "0" 16:52:25 DEBUG content | << "[\r]" 16:52:25 DEBUG content | << "[\n]" 16:52:25 DEBUG content | << "[\r]" 16:52:25 DEBUG content | << "[\n]" 16:52:25 DEBUG header | << "[\r][\n]" 16:52:27 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):5867d057-b2f4-4db2-acbd-4a9200a72110: 16:52:27 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):fd0b73a8-4195-4428-b763-15077b683cc0: 16:52:27 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):95382959-e29b-489a-84b0-634060d292c1: 16:52:27 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:27 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:27 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:27 DEBUG header | >> "Content-Length: 564[\r][\n]" 16:52:27 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:27 DEBUG header | >> "[\r][\n]" 16:52:27 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781177&Nonce=194614001574687&Signature=r%2BRBRC6yVyWiUlGmbOwiHAQm4YfkC040krbxSR1B93A%3D" 16:52:27 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:27 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:27 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:27 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:27 GMT[\r][\n]" 16:52:27 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:27 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:27 DEBUG header | << "[\r][\n]" 16:52:27 DEBUG content | << "4" 16:52:27 DEBUG content | << "1" 16:52:27 DEBUG content | << "b" 16:52:27 DEBUG content | << "[\r]" 16:52:27 DEBUG content | << "[\n]" 16:52:27 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 47.533743065924249, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:27 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:27 DEBUG content | << "[\r]" 16:52:27 DEBUG content | << "[\n]" 16:52:27 DEBUG content | << "0" 16:52:27 DEBUG content | << "[\r]" 16:52:27 DEBUG content | << "[\n]" 16:52:27 DEBUG content | << "[\r]" 16:52:27 DEBUG content | << "[\n]" 16:52:27 DEBUG header | << "[\r][\n]" 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusGL HTTP/1.1: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:29 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):516a15e5-7b8b-4e0c-9227-07a7e958829f: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x6621339e, /192.168.1.4:49490 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5168: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: /192.168.1.4:8774] WRITTEN_AMOUNT: 5046: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49489:/192.168.1.4:8774: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x481016f9, /192.168.1.4:49491 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5602: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x2ac43a13, /192.168.1.4:49492 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5084: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49492:/192.168.1.4:8774: 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:ResourceStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49491:/192.168.1.4:8774: 16:52:29 INFO ClusterUtil | --------------------------------------------------------------- 16:52:29 INFO ClusterUtil | -> [ test1 ] Cluster certificate valid=true 16:52:29 DEBUG NetworkStateHandler | | | 74c20925-2f4b-4ebb-8c3b-2813bb5c66c6 | eucalyptus | true | 1 | 32 | | 10 | default | admin | 0 | 1 | 2 | 31 | | 16:52:29 INFO ClusterUtil | -> [ test1 ] Node certificate valid=true 16:52:29 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:29 INFO ClusterUtil | --------------------------------------------------------------- 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781149960:40:/192.168.1.4:49489:/192.168.1.4:8774: 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:NetworkStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781149960:40:/192.168.1.4:49490:/192.168.1.4:8774: 16:52:29 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):b8a89612-3ae9-4794-a9bd-eae7bdbcf2b1: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x6c68069e, /192.168.1.4:49493 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5108: 16:52:29 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49493:/192.168.1.4:8774: 16:52:29 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):f3dff6f0-123d-4d11-9bb9-57d3d1f7e2f1: 16:52:29 INFO VmInstance | i-421507FB state change: SHUTTING_DOWN -> TERMINATED 16:52:29 DEBUG ClusterMessageQueue | :1265781120.0000:TerminateCallback/Clusters.dispatchClusterEvent.116:eucalyptus:eucalyptus:MSG_PENDING:test1: | | | 3fa973b2-ffdc-4e6f-b3c3-9ae82e911176 | eucalyptus | eucalyptus | | | | i-421507FB | | | : 16:52:29 DEBUG ClusterMessageQueue | :1265781120.0000:TerminateCallback/Clusters.dispatchClusterEvent.116:eucalyptus:eucalyptus:MSG_PENDING:test1: | | | 3fa973b2-ffdc-4e6f-b3c3-9ae82e911176 | eucalyptus | eucalyptus | | | | i-421507FB | | | : 16:52:29 DEBUG ClusterMessageQueue | Queued message of type TerminateCallback for cluster test1 16:52:29 DEBUG ClusterMessageQueue | -> Dequeued message of type TerminateCallback 16:52:29 DEBUG SystemState | :1265781120.0000:SystemState/SystemState.cleanUp.199:eucalyptus:eucalyptus:VM_TERMINATING:i-421507FB: 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781149979:8:/192.168.1.4:49493:/192.168.1.4:8774: 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781149979:21:/192.168.1.4:49492:/192.168.1.4:8774: 16:52:29 DEBUG ClusterNodeState | -------------------------------------------------------------------------------- | Resource update for cluster=test1, outstanding=0 pending=0 submitted=0 redeemed=0 | -------------------------------------------------------------------------------- 16:52:29 DEBUG ClusterNodeState | -> BEFORE: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:29 DEBUG ClusterNodeState | -> AFTER: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:29 DEBUG NetworkStateHandler | Adding node service tags: [http://192.168.1.4:8775/axis2/services/EucalyptusNC] 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:ResourceStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781149988:32:/192.168.1.4:49491:/192.168.1.4:8774: 16:52:29 DEBUG ChannelStateMonitor | :1265781120.0000:TerminateCallback/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49497:lifelessdesktop.local/192.168.1.4:8774: 16:52:30 DEBUG NioResponseHandler | TerminateCallback Got response of: .106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):5880ba0e-ff25-4071-b986-f99195cfda7f: 16:52:30 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):8970f2af-9cf8-4d73-8a3a-ea69b6e34331: 16:52:30 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):7a96ff64-80c1-4dc3-b693-63dbc354fb55: 16:52:30 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:30 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:30 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:30 DEBUG header | >> "Content-Length: 564[\r][\n]" 16:52:30 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:30 DEBUG header | >> "[\r][\n]" 16:52:30 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781180&Nonce=194617466882932&Signature=quqmOu6%2BwlIB68TkRq1qS4UVL7P67tGpTTJh6OT6jqI%3D" 16:52:30 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:30 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:30 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:30 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:30 GMT[\r][\n]" 16:52:30 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:30 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:30 DEBUG header | << "[\r][\n]" 16:52:30 DEBUG content | << "4" 16:52:30 DEBUG content | << "1" 16:52:30 DEBUG content | << "b" 16:52:30 DEBUG content | << "[\r]" 16:52:30 DEBUG content | << "[\n]" 16:52:30 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 48.143812885667124, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:30 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:30 DEBUG content | << "[\r]" 16:52:30 DEBUG content | << "[\n]" 16:52:30 DEBUG content | << "0" 16:52:30 DEBUG content | << "[\r]" 16:52:30 DEBUG content | << "[\n]" 16:52:30 DEBUG content | << "[\r]" 16:52:30 DEBUG content | << "[\n]" 16:52:30 DEBUG header | << "[\r][\n]" 16:52:32 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):d9d56b4d-808e-47cf-8a29-5e04c67a4d55: 16:52:32 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):0159776d-fb78-430b-b6a8-8c0b32378c59: 16:52:32 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):45a32f31-2239-4d62-bedc-36231382bb6b: 16:52:32 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:32 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:32 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:32 DEBUG header | >> "Content-Length: 564[\r][\n]" 16:52:32 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:32 DEBUG header | >> "[\r][\n]" 16:52:32 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781182&Nonce=194619006134842&Signature=XnzIvxnaAr%2FJjIPJ0wh2Dz3O0WAAdy5ZJ0qhllfLf3U%3D" 16:52:32 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:32 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:32 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:32 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:32 GMT[\r][\n]" 16:52:32 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:32 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:32 DEBUG header | << "[\r][\n]" 16:52:32 DEBUG content | << "4" 16:52:32 DEBUG content | << "1" 16:52:32 DEBUG content | << "b" 16:52:32 DEBUG content | << "[\r]" 16:52:32 DEBUG content | << "[\n]" 16:52:32 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 48.430904565546122, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:32 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:32 DEBUG content | << "[\r]" 16:52:32 DEBUG content | << "[\n]" 16:52:32 DEBUG content | << "0" 16:52:32 DEBUG content | << "[\r]" 16:52:32 DEBUG content | << "[\n]" 16:52:32 DEBUG content | << "[\r]" 16:52:32 DEBUG content | << "[\n]" 16:52:32 DEBUG header | << "[\r][\n]" 16:52:35 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):d123a2a6-65e6-4a06-9870-b1cf73aec141: 16:52:35 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):0eab1354-182f-4d70-9880-d96b60078f87: 16:52:35 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):714f4bde-65c2-410a-a1d5-b372a5b1000e: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusGL HTTP/1.1: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:35 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:35 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:35 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:35 DEBUG header | >> "Content-Length: 564[\r][\n]" 16:52:35 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:35 DEBUG header | >> "[\r][\n]" 16:52:35 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781185&Nonce=194622467823591&Signature=tEOyTQDrqSYuIA25b74tNg8VoFd30%2FGVxTRhKEsTsLk%3D" 16:52:35 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):448ac507-4050-4289-9c15-5bd88d627d9a: 16:52:35 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:35 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:35 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:35 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:35 GMT[\r][\n]" 16:52:35 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:35 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:35 DEBUG header | << "[\r][\n]" 16:52:35 DEBUG content | << "4" 16:52:35 DEBUG content | << "1" 16:52:35 DEBUG content | << "a" 16:52:35 DEBUG content | << "[\r]" 16:52:35 DEBUG content | << "[\n]" 16:52:35 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 49.10377569026253, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images/" 16:52:35 DEBUG content | << "mdb-appliance-20100120"}]}" 16:52:35 DEBUG content | << "[\r]" 16:52:35 DEBUG content | << "[\n]" 16:52:35 DEBUG content | << "0" 16:52:35 DEBUG content | << "[\r]" 16:52:35 DEBUG content | << "[\n]" 16:52:35 DEBUG content | << "[\r]" 16:52:35 DEBUG content | << "[\n]" 16:52:35 DEBUG header | << "[\r][\n]" 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x29db8b9f, /192.168.1.4:49511 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5046: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:35 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49511:/192.168.1.4:8774: 16:52:35 INFO ClusterUtil | --------------------------------------------------------------- 16:52:35 INFO ClusterUtil | -> [ test1 ] Cluster certificate valid=true 16:52:35 INFO ClusterUtil | -> [ test1 ] Node certificate valid=true 16:52:35 INFO ClusterUtil | --------------------------------------------------------------- 16:52:35 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781155922:3:/192.168.1.4:49511:/192.168.1.4:8774: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x76de2210, /192.168.1.4:49512 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5168: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: /192.168.1.4:8774] WRITTEN_AMOUNT: 5602: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: /192.168.1.4:8774] WRITTEN_AMOUNT: 5084: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:35 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49514:/192.168.1.4:8774: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x6f3f746a, /192.168.1.4:49515 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5108: 16:52:35 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:35 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49515:/192.168.1.4:8774: 16:52:35 DEBUG NetworkStateHandler | | | 46bd9d72-2a12-425f-a273-dba109820625 | eucalyptus | true | 1 | 32 | | 10 | default | admin | 0 | 1 | 31 | | 16:52:35 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:35 DEBUG ChannelStateMonitor | :1265781120.0000:NetworkStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781155959:31:/192.168.1.4:49512:/192.168.1.4:8774: 16:52:35 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781155968:10:/192.168.1.4:49515:/192.168.1.4:8774: 16:52:35 DEBUG ClusterNodeState | -------------------------------------------------------------------------------- | Resource update for cluster=test1, outstanding=0 pending=0 submitted=0 redeemed=0 | -------------------------------------------------------------------------------- 16:52:36 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):56c2b3c2-6cb3-43c0-bd9d-15ebc3167be3: 16:52:36 DEBUG ClusterNodeState | -> BEFORE: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:36 DEBUG ClusterNodeState | -> AFTER: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:36 DEBUG NetworkStateHandler | Adding node service tags: [http://192.168.1.4:8775/axis2/services/EucalyptusNC] 16:52:36 DEBUG ChannelStateMonitor | :1265781120.0000:ResourceStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781156003:67:/192.168.1.4:49513:/192.168.1.4:8774: 16:52:36 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):720f8971-b78e-4435-b188-dc496638a017: 16:52:36 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781156009:56:/192.168.1.4:49514:/192.168.1.4:8774: 16:52:37 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):0c6aeb47-e0b6-4508-a7dc-21540642c535: 16:52:37 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):b7cf2f37-9af5-4710-a403-4e92e5add0aa: 16:52:37 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):79d6fe74-ffd1-4bf0-9900-d2edd14e5c26: 16:52:37 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:37 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:37 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:37 DEBUG header | >> "Content-Length: 566[\r][\n]" 16:52:37 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:37 DEBUG header | >> "[\r][\n]" 16:52:37 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781187&Nonce=194624005806841&Signature=Bl1jsNetyhV41p08HRiKQP%2F24%2Fq0NTimsniWx5TAajU%3D" 16:52:37 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:37 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:37 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:37 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:37 GMT[\r][\n]" 16:52:37 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:37 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:37 DEBUG header | << "[\r][\n]" 16:52:37 DEBUG content | << "4" 16:52:37 DEBUG content | << "1" 16:52:37 DEBUG content | << "b" 16:52:37 DEBUG content | << "[\r]" 16:52:37 DEBUG content | << "[\n]" 16:52:37 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 49.408810600133961, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:37 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:37 DEBUG content | << "[\r]" 16:52:37 DEBUG content | << "[\n]" 16:52:37 DEBUG content | << "0" 16:52:37 DEBUG content | << "[\r]" 16:52:37 DEBUG content | << "[\n]" 16:52:37 DEBUG content | << "[\r]" 16:52:37 DEBUG content | << "[\n]" 16:52:37 DEBUG header | << "[\r][\n]" 16:52:40 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):e05ef83b-006c-4dac-adeb-ee6a8bbb9e47: 16:52:40 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):5769690b-dc59-4bbf-bcc2-60f82a692645: 16:52:40 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):afe47032-4e0f-43b7-8f80-55a1379d9039: 16:52:40 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:40 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:40 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:40 DEBUG header | >> "Content-Length: 564[\r][\n]" 16:52:40 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:40 DEBUG header | >> "[\r][\n]" 16:52:40 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781190&Nonce=194627480641640&Signature=lGpK41Km7adJl6EGeOKGZqKbx%2BE0q3gpN9sCgOFzyQI%3D" 16:52:40 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:40 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:40 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:40 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:40 GMT[\r][\n]" 16:52:40 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:40 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:40 DEBUG header | << "[\r][\n]" 16:52:40 DEBUG content | << "4" 16:52:40 DEBUG content | << "1" 16:52:40 DEBUG content | << "b" 16:52:40 DEBUG content | << "[\r]" 16:52:40 DEBUG content | << "[\n]" 16:52:40 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 50.144483029823903, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:40 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:40 DEBUG content | << "[\r]" 16:52:40 DEBUG content | << "[\n]" 16:52:40 DEBUG content | << "0" 16:52:40 DEBUG content | << "[\r]" 16:52:40 DEBUG content | << "[\n]" 16:52:40 DEBUG content | << "[\r]" 16:52:40 DEBUG content | << "[\n]" 16:52:40 DEBUG header | << "[\r][\n]" 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusGL HTTP/1.1: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:41 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:3:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):91b6ca1e-45d1-4b64-85b2-89db2645f84a: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x4da2a70c, /192.168.1.4:49522 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5046: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:41 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49522:/192.168.1.4:8774: 16:52:41 INFO ClusterUtil | --------------------------------------------------------------- 16:52:41 INFO ClusterUtil | -> [ test1 ] Cluster certificate valid=true 16:52:41 INFO ClusterUtil | -> [ test1 ] Node certificate valid=true 16:52:41 INFO ClusterUtil | --------------------------------------------------------------- 16:52:41 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781161927:3:/192.168.1.4:49522:/192.168.1.4:8774: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x5a3dd86a, /192.168.1.4:49523 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5168: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: /192.168.1.4:8774] WRITTEN_AMOUNT: 5602: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: /192.168.1.4:8774] WRITTEN_AMOUNT: 5108: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:41 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49526:/192.168.1.4:8774: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x71728c7a, /192.168.1.4:49525 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5084: 16:52:41 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:41 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49525:/192.168.1.4:8774: 16:52:41 DEBUG NetworkStateHandler | | | df1a772b-44fa-4a23-8e9f-9cfa4a876f35 | eucalyptus | true | 1 | 32 | | 10 | default | admin | 0 | 1 | 31 | | 16:52:41 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:41 DEBUG ChannelStateMonitor | :1265781120.0000:NetworkStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781161963:31:/192.168.1.4:49523:/192.168.1.4:8774: 16:52:41 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781161965:11:/192.168.1.4:49526:/192.168.1.4:8774: 16:52:41 DEBUG ClusterNodeState | -------------------------------------------------------------------------------- | Resource update for cluster=test1, outstanding=0 pending=0 submitted=0 redeemed=0 | -------------------------------------------------------------------------------- 16:52:41 DEBUG ClusterNodeState | -> BEFORE: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:41 DEBUG ClusterNodeState | -> AFTER: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:41 DEBUG NetworkStateHandler | Adding node service tags: [http://192.168.1.4:8775/axis2/services/EucalyptusNC] 16:52:41 DEBUG ChannelStateMonitor | :1265781120.0000:ResourceStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781161969:30:/192.168.1.4:49524:/192.168.1.4:8774: 16:52:41 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):8a2ba256-eca9-4b85-91c2-9c301a5ab8c9: 16:52:41 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):c6527919-3f0a-4308-8b9a-e60c645d5ef8: 16:52:41 INFO VmInstance | i-46EF07F7 state change: TERMINATED -> BURIED 16:52:42 DEBUG ClusterMessageQueue | :1265781120.0000:TerminateCallback/Clusters.dispatchClusterEvent.116:eucalyptus:eucalyptus:MSG_PENDING:test1: | | | e312b4d7-6f55-4dab-9e2b-75befab2e783 | eucalyptus | eucalyptus | | | | i-46EF07F7 | | | : 16:52:42 DEBUG ClusterMessageQueue | :1265781120.0000:TerminateCallback/Clusters.dispatchClusterEvent.116:eucalyptus:eucalyptus:MSG_PENDING:test1: | | | e312b4d7-6f55-4dab-9e2b-75befab2e783 | eucalyptus | eucalyptus | | | | i-46EF07F7 | | | : 16:52:42 DEBUG ClusterMessageQueue | Queued message of type TerminateCallback for cluster test1 16:52:42 DEBUG SystemState | :1265781120.0000:SystemState/SystemState.cleanUp.199:eucalyptus:eucalyptus:VM_TERMINATING:i-46EF07F7: 16:52:42 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781162007:50:/192.168.1.4:49525:/192.168.1.4:8774: 16:52:42 DEBUG ClusterMessageQueue | -> Dequeued message of type TerminateCallback 16:52:42 DEBUG ChannelStateMonitor | :1265781120.0000:TerminateCallback/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49529:/192.168.1.4:8774: 16:52:42 DEBUG NioResponseHandler | TerminateCallback Got response of: .106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):a805615e-6915-40f4-baa9-c68e40d4fb73: 16:52:42 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):0585f5b2-5ed5-44a4-bd38-a431e94498ff: 16:52:42 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):b3fbd791-8c38-4d40-a3d1-ff397e4de2db: 16:52:42 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:42 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:42 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:42 DEBUG header | >> "Content-Length: 566[\r][\n]" 16:52:42 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:42 DEBUG header | >> "[\r][\n]" 16:52:42 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781192&Nonce=194628999254390&Signature=HACLd7rKK0NlTjhU6Gv5OWvb5eG3HxXu%2FIB3x%2BMDLHQ%3D" 16:52:42 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:42 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:42 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:42 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:42 GMT[\r][\n]" 16:52:42 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:42 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:42 DEBUG header | << "[\r][\n]" 16:52:42 DEBUG content | << "4" 16:52:42 DEBUG content | << "1" 16:52:42 DEBUG content | << "b" 16:52:42 DEBUG content | << "[\r]" 16:52:42 DEBUG content | << "[\n]" 16:52:42 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 50.476432784683993, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:42 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:42 DEBUG content | << "[\r]" 16:52:42 DEBUG content | << "[\n]" 16:52:42 DEBUG content | << "0" 16:52:42 DEBUG content | << "[\r]" 16:52:42 DEBUG content | << "[\n]" 16:52:42 DEBUG content | << "[\r]" 16:52:42 DEBUG content | << "[\n]" 16:52:42 DEBUG header | << "[\r][\n]" 16:52:45 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):5e47a8bf-a833-41d3-b73f-f15bf29de914: 16:52:45 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):79f75b68-7d6a-4f2a-b105-7b6376bff96f: 16:52:45 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):f4cd8c5b-d8b1-4e3a-8c11-f2239513cb9f: 16:52:45 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:45 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:45 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:45 DEBUG header | >> "Content-Length: 564[\r][\n]" 16:52:45 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:45 DEBUG header | >> "[\r][\n]" 16:52:45 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781195&Nonce=194632466882719&Signature=Riv7IQscwdX8WTq%2FYNGkZzd8j4zcFldRZt5aVloz8mo%3D" 16:52:45 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:45 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:45 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:45 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:45 GMT[\r][\n]" 16:52:45 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:45 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:45 DEBUG header | << "[\r][\n]" 16:52:45 DEBUG content | << "4" 16:52:45 DEBUG content | << "1" 16:52:45 DEBUG content | << "b" 16:52:45 DEBUG content | << "[\r]" 16:52:45 DEBUG content | << "[\n]" 16:52:45 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 51.265934904351241, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:45 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:45 DEBUG content | << "[\r]" 16:52:45 DEBUG content | << "[\n]" 16:52:45 DEBUG content | << "0" 16:52:45 DEBUG content | << "[\r]" 16:52:45 DEBUG content | << "[\n]" 16:52:45 DEBUG content | << "[\r]" 16:52:45 DEBUG content | << "[\n]" 16:52:45 DEBUG header | << "[\r][\n]" 16:52:47 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):605c03b3-297e-4234-84ed-d0465ab8221d: 16:52:47 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):364de79f-9c23-484a-9f67-a9029a074e83: 16:52:47 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):ca7a8c57-020c-46e0-9b08-52c2c8f5b602: 16:52:47 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:47 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:47 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:47 DEBUG header | >> "Content-Length: 564[\r][\n]" 16:52:47 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:47 DEBUG header | >> "[\r][\n]" 16:52:47 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781197&Nonce=194633995827069&Signature=LqTQdP%2Fw40YxwwWvjhdl3I0jVACmSUL1GWMXaEAdQwU%3D" 16:52:47 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:47 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:47 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:47 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:47 GMT[\r][\n]" 16:52:47 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:47 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:47 DEBUG header | << "[\r][\n]" 16:52:47 DEBUG content | << "4" 16:52:47 DEBUG content | << "1" 16:52:47 DEBUG content | << "b" 16:52:47 DEBUG content | << "[\r]" 16:52:47 DEBUG content | << "[\n]" 16:52:47 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 51.624799504199991, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:47 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:47 DEBUG content | << "[\r]" 16:52:47 DEBUG content | << "[\n]" 16:52:47 DEBUG content | << "0" 16:52:47 DEBUG content | << "[\r]" 16:52:47 DEBUG content | << "[\n]" 16:52:47 DEBUG content | << "[\r]" 16:52:47 DEBUG content | << "[\n]" 16:52:47 DEBUG header | << "[\r][\n]" 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusGL HTTP/1.1: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:47 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):c5489269-5365-4e41-bae3-b0d957796444: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x5b88a1fa, /192.168.1.4:49539 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5046: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:47 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49539:/192.168.1.4:8774: 16:52:47 INFO ClusterUtil | --------------------------------------------------------------- 16:52:47 INFO ClusterUtil | -> [ test1 ] Cluster certificate valid=true 16:52:47 INFO ClusterUtil | -> [ test1 ] Node certificate valid=true 16:52:47 INFO ClusterUtil | --------------------------------------------------------------- 16:52:47 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781167949:6:/192.168.1.4:49539:/192.168.1.4:8774: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x0fcd0216, /192.168.1.4:49541 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5602: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x71d1fa49, /192.168.1.4:49540 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5168: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: /192.168.1.4:8774] WRITTEN_AMOUNT: 5084: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:47 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49542:/192.168.1.4:8774: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x7309c410, /192.168.1.4:49543 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5108: 16:52:47 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:47 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49543:/192.168.1.4:8774: 16:52:47 DEBUG ClusterNodeState | -------------------------------------------------------------------------------- | Resource update for cluster=test1, outstanding=0 pending=0 submitted=0 redeemed=0 | -------------------------------------------------------------------------------- 16:52:47 DEBUG ClusterNodeState | -> BEFORE: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:47 DEBUG ClusterNodeState | -> AFTER: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:47 DEBUG NetworkStateHandler | Adding node service tags: [http://192.168.1.4:8775/axis2/services/EucalyptusNC] 16:52:47 DEBUG NetworkStateHandler | | | 00acf7e1-2134-48eb-98db-5658e0c63306 | eucalyptus | true | 1 | 32 | | 10 | default | admin | 0 | 1 | 31 | | 16:52:47 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:47 DEBUG ChannelStateMonitor | :1265781120.0000:ResourceStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781167982:30:/192.168.1.4:49541:/192.168.1.4:8774: 16:52:47 DEBUG ChannelStateMonitor | :1265781120.0000:NetworkStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781167983:32:/192.168.1.4:49540:/192.168.1.4:8774: 16:52:47 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781167990:16:/192.168.1.4:49543:/192.168.1.4:8774: 16:52:48 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):6dce54ea-7167-49eb-8817-71e470301457: 16:52:48 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):10c228a7-dc8e-4157-84af-851aeaf25790: 16:52:48 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781168016:58:/192.168.1.4:49542:/192.168.1.4:8774: 16:52:50 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):c4615635-cb01-4cec-a37f-1bd99a1ebab9: 16:52:50 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):a7e545bb-4a67-41db-a451-d16a04804a3f: 16:52:50 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):f0ebeae9-5dd7-4bf1-9e68-fe3363de8273: 16:52:50 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:50 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:50 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:50 DEBUG header | >> "Content-Length: 564[\r][\n]" 16:52:50 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:50 DEBUG header | >> "[\r][\n]" 16:52:50 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781200&Nonce=194637466175632&Signature=tXBve%2BFtxeiRxH141syIGCwtzMwk43TBB0WjTWkEnYQ%3D" 16:52:50 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:50 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:50 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:50 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:50 GMT[\r][\n]" 16:52:50 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:50 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:50 DEBUG header | << "[\r][\n]" 16:52:50 DEBUG content | << "4" 16:52:50 DEBUG content | << "1" 16:52:50 DEBUG content | << "b" 16:52:50 DEBUG content | << "[\r]" 16:52:50 DEBUG content | << "[\n]" 16:52:50 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 52.477102928840772, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:50 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:50 DEBUG content | << "[\r]" 16:52:50 DEBUG content | << "[\n]" 16:52:50 DEBUG content | << "0" 16:52:50 DEBUG content | << "[\r]" 16:52:50 DEBUG content | << "[\n]" 16:52:50 DEBUG content | << "[\r]" 16:52:50 DEBUG content | << "[\n]" 16:52:50 DEBUG header | << "[\r][\n]" 16:52:52 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus:edu.ucsb.eucalyptus.admin.server.EucalyptusManagement.getWebUser(EucalyptusManagement.java:254):f1d58c1f-8f48-445a-a092-893abe04db3c: 16:52:52 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getQueryId(CredentialProvider.java:135):ca1c66d8-440a-448b-87f4-937041231098: 16:52:52 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:0:eucalyptus_auth:com.eucalyptus.auth.CredentialProvider.getSecretKey(CredentialProvider.java:150):3115105e-3f3f-40c3-8258-a69d244cb9a0: 16:52:52 DEBUG header | >> "POST /api/states HTTP/1.1[\r][\n]" 16:52:52 DEBUG header | >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]" 16:52:52 DEBUG header | >> "Host: localhost:52780[\r][\n]" 16:52:52 DEBUG header | >> "Content-Length: 562[\r][\n]" 16:52:52 DEBUG header | >> "Content-Type: application/x-www-form-urlencoded[\r][\n]" 16:52:52 DEBUG header | >> "[\r][\n]" 16:52:52 DEBUG content | >> "image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-amd64-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fkarmic-i386-20091027&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmediawiki-demo-i386-0.1&image-uri=https%3A%2F%2Fimagestore.canonical.com%2Fapi%2Fimages%2Fmdb-appliance-20100120&SignatureMethod=HmacSHA256&SignatureVersion=2&Version=2009-10-01&ClientId=WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw&Expires=1265781202&Nonce=194639003812174&Signature=7Q9HHRXGjyRwH2nVRqAnMQ6OFYrtt2GFwsXuePGdDkY%3D" 16:52:52 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:52 DEBUG header | << "HTTP/1.1 200 OK[\r][\n]" 16:52:52 DEBUG header | << "Transfer-Encoding: chunked[\r][\n]" 16:52:52 DEBUG header | << "Date: Wed, 10 Feb 2010 05:52:52 GMT[\r][\n]" 16:52:52 DEBUG header | << "Content-Type: text/html[\r][\n]" 16:52:52 DEBUG header | << "Server: TwistedWeb/8.2.0[\r][\n]" 16:52:52 DEBUG header | << "[\r][\n]" 16:52:52 DEBUG content | << "4" 16:52:52 DEBUG content | << "1" 16:52:52 DEBUG content | << "b" 16:52:52 DEBUG content | << "[\r]" 16:52:52 DEBUG content | << "[\n]" 16:52:52 DEBUG content | << "{"states": [{"status": "installed", "eki": "eki-F65C10F3", "image-uri": "https://imagestore.canonical.com/api/images/karmic-amd64-20091027", "emi": "emi-E027107D", "actions": {}, "eri": "eri-0ABC115A"}, {"status": "downloading", "progress-percentage": 52.862882373678183, "actions": {"cancel": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9rYXJtaWMtaTM4Ni0yMDA5MTAyNw==/cancel"}, "image-uri": "https://imagestore.canonical.com/api/images/karmic-i386-20091027"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZWRpYXdpa2ktZGVtby1pMzg2LTAuMQ==/install"}, "image-uri": "https://imagestore.canonical.com/api/images/mediawiki-demo-i386-0.1"}, {"status": "uninstalled", "actions": {"install": "http://localhost:52780/api/images/aHR0cHM6Ly9pbWFnZXN0b3JlLmNhbm9uaWNhbC5jb20vYXBpL2ltYWdlcy9tZGItYXBwbGlhbmNlLTIwMTAwMTIw/install"}, "image-uri": "https://imagestore.canonical.com/api/images" 16:52:52 DEBUG content | << "/mdb-appliance-20100120"}]}" 16:52:52 DEBUG content | << "[\r]" 16:52:52 DEBUG content | << "[\n]" 16:52:52 DEBUG content | << "0" 16:52:52 DEBUG content | << "[\r]" 16:52:52 DEBUG content | << "[\n]" 16:52:52 DEBUG content | << "[\r]" 16:52:52 DEBUG content | << "[\n]" 16:52:52 DEBUG header | << "[\r][\n]" 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusGL HTTP/1.1: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:53 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:1:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):5c0af409-fa6d-4f94-a4ce-87d9784ae9d2: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeRequested.197:eucalyptus:eucalyptus:MSG_PENDING:POST http://192.168.1.4:8774/axis2/services/EucalyptusCC HTTP/1.1: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x7a6b4198, /192.168.1.4:49548 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5046: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x0e674223, /192.168.1.4:49549 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5168: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: [ test1 ] Cluster certificate valid=true 16:52:53 INFO ClusterUtil | -> [ test1 ] Node certificate valid=true 16:52:53 INFO ClusterUtil | --------------------------------------------------------------- 16:52:53 DEBUG ChannelStateMonitor | :1265781120.0000:ClusterCertificateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781173940:18:/192.168.1.4:49548:/192.168.1.4:8774: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x32344d40, /192.168.1.4:49550 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5602: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: /192.168.1.4:8774] WRITTEN_AMOUNT: 5084: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:53 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49551:/192.168.1.4:8774: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.writeComplete.186:eucalyptus:eucalyptus:MSG_SERVICED:[id: 0x23c9ada6, /192.168.1.4:49552 => /192.168.1.4:8774] WRITTEN_AMOUNT: 5108: 16:52:53 DEBUG tClusterMessageDispatcher | :1265781120.0000:AbstractClusterMessageDispatcher/AbstractClusterMessageDispatcher.operationComplete.133:eucalyptus:eucalyptus:MSG_SENT: : 16:52:53 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.channelConnected.50:eucalyptus:eucalyptus:SOCKET_OPEN:/192.168.1.4:49552:/192.168.1.4:8774: 16:52:53 DEBUG ChannelStateMonitor | :1265781120.0000:AddressStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781173968:8:/192.168.1.4:49552:/192.168.1.4:8774: 16:52:53 DEBUG ClusterNodeState | -------------------------------------------------------------------------------- | Resource update for cluster=test1, outstanding=0 pending=0 submitted=0 redeemed=0 | -------------------------------------------------------------------------------- 16:52:53 DEBUG ClusterNodeState | -> BEFORE: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:53 DEBUG ClusterNodeState | -> AFTER: [ m1.small available=8/8 c1.medium available=8/8 m1.large available=4/4 m1.xlarge available=4/4 c1.xlarge available=2/2 ] 16:52:53 DEBUG NetworkStateHandler | Adding node service tags: [http://192.168.1.4:8775/axis2/services/EucalyptusNC] 16:52:53 DEBUG ChannelStateMonitor | :1265781120.0000:ResourceStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781173972:31:/192.168.1.4:49550:/192.168.1.4:8774: 16:52:53 DEBUG NetworkStateHandler | | | 5fdb7c6c-5c74-465f-a012-a0b50c4940f2 | eucalyptus | true | 1 | 32 | | 10 | default | admin | 0 | 1 | 31 | | 16:52:53 DEBUG StatefulNamedRegistry | Network [availableNetworkIndexes=[2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30], assignedNetworkIndexes=[], name=admin-default, networkName=default, clusterTokens={test1=NetworkToken [cluster=test1, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10]}, rules=[], userName=admin] 16:52:53 DEBUG ChannelStateMonitor | :1265781120.0000:NetworkStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781173978:56:/192.168.1.4:49549:/192.168.1.4:8774: 16:52:53 DEBUG EntityWrapper | :1265781120.0000:db/EntityWrapper..106:eucalyptus:eucalyptus:CREATE:END:2:eucalyptus:edu.ucsb.eucalyptus.cloud.cluster.VmTypes.update(VmTypes.java:111):f6f8a955-7a2a-4975-bd5b-3873c5a41862: 16:52:54 DEBUG ChannelStateMonitor | :1265781120.0000:VmStateHandler/ChannelStateMonitor.split.36:eucalyptus:eucalyptus:SOCKET_CLOSE:1265781174000:47:/192.168.1.4:49551:/192.168.1.4:8774: