starting.. Connecting.. Connected (to pid 6268) Remote Versions: Nevow: 0.9.31 Twisted: 8.2.0 allmydata-tahoe: 1.6.1-r4286 argparse: 0.9.1 foolscap: 0.4.2 platform: Linux-Ubuntu_9.10-i686-32bit_ELF pyOpenSSL: 0.9 pycryptopp: 0.5.15 pysqlite: 2.4.1 python: 2.6.4 pyutil: 1.3.34 setuptools: 0.6c12dev simplejson: 2.0.9 sqlite: 3.6.16 twisted: 8.2.0 zbase32: 1.1.1 zfec: 1.4.5 zope.interface: 3.5.2 17:47:41.078 L20 []#1439 : .getAttrs('/', followLinks=0) 17:47:41.078 L10 []#1440 : CONVERT '/' 17:47:41.078 L10 []#1441 : PATH [] 17:47:41.078 L10 []#1442 : _got_root( (, []) ) 17:47:41.078 L20 []#1443 SharemapUpdater(pwkes): starting (MODE_READ) 17:47:41.079 L10 []#1444 sending query to [b5lefkbu], readsize=4000 17:47:41.080 L10 []#1445 sending query to [jfkoivg7], readsize=4000 17:47:41.081 L10 []#1446 sending query to [rkkkx6sk], readsize=4000 17:47:41.083 L10 []#1447 sending query to [tkxzeoh2], readsize=4000 17:47:41.084 L10 []#1448 sending query to [wpxpieqx], readsize=4000 17:47:41.086 L20 []#1449 : .getAttrs('/', followLinks=1) 17:47:41.086 L10 []#1450 : CONVERT '/' 17:47:41.086 L10 []#1451 : PATH [] 17:47:41.086 L10 []#1452 : _got_root( (, []) ) 17:47:41.136 L10 []#1453 got result from [tkxzeoh2], 2 shares 17:47:41.136 L10 []#1454 _got_results: got shnum #8 from peerid tkxzeoh2 17:47:41.137 L20 []#1455 found valid version 4-dqvj from tkxzeoh2-sh8: 3-10/384/382 17:47:41.137 L10 []#1456 _got_results: got shnum #3 from peerid tkxzeoh2 17:47:41.137 L10 []#1457 _got_results done 17:47:41.137 L10 []#1458 _check_for_done, mode is 'MODE_READ', 4 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.137 L10 []#1459 1 completed, 6 to query: need more 17:47:41.137 L10 []#1460 there are 4 queries outstanding 17:47:41.137 L10 []#1461 sending 0 more queries: 17:47:41.159 L10 []#1462 got result from [wpxpieqx], 2 shares 17:47:41.159 L10 []#1463 _got_results: got shnum #1 from peerid wpxpieqx 17:47:41.159 L10 []#1464 _got_results: got shnum #6 from peerid wpxpieqx 17:47:41.159 L10 []#1465 _got_results done 17:47:41.159 L10 []#1466 _check_for_done, mode is 'MODE_READ', 3 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.160 L10 []#1467 2 completed, 6 to query: need more 17:47:41.160 L10 []#1468 there are 3 queries outstanding 17:47:41.160 L10 []#1469 sending 0 more queries: 17:47:41.160 L10 []#1470 got result from [b5lefkbu], 2 shares 17:47:41.161 L10 []#1471 _got_results: got shnum #9 from peerid b5lefkbu 17:47:41.161 L10 []#1472 _got_results: got shnum #4 from peerid b5lefkbu 17:47:41.161 L10 []#1473 _got_results done 17:47:41.161 L10 []#1474 _check_for_done, mode is 'MODE_READ', 2 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.161 L10 []#1475 3 completed, 6 to query: need more 17:47:41.161 L10 []#1476 there are 2 queries outstanding 17:47:41.161 L10 []#1477 sending 0 more queries: 17:47:41.425 L10 []#1478 got result from [rkkkx6sk], 2 shares 17:47:41.425 L10 []#1479 _got_results: got shnum #2 from peerid rkkkx6sk 17:47:41.426 L10 []#1480 _got_results: got shnum #7 from peerid rkkkx6sk 17:47:41.426 L10 []#1481 _got_results done 17:47:41.426 L10 []#1482 _check_for_done, mode is 'MODE_READ', 1 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.426 L10 []#1483 4 completed, 6 to query: need more 17:47:41.426 L10 []#1484 there are 1 queries outstanding 17:47:41.426 L10 []#1485 sending 0 more queries: 17:47:41.536 L10 []#1486 got result from [jfkoivg7], 2 shares 17:47:41.536 L10 []#1487 _got_results: got shnum #0 from peerid jfkoivg7 17:47:41.536 L10 []#1488 _got_results: got shnum #5 from peerid jfkoivg7 17:47:41.537 L10 []#1489 _got_results done 17:47:41.537 L10 []#1490 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.537 L20 []#1491 all queries are retired, no extra peers: done 17:47:41.537 L20 []#1492 servermap: 10*seq4-dqvj 17:47:41.551 L20 []#1493 SharemapUpdater(pwkes): starting (MODE_READ) 17:47:41.552 L10 []#1494 sending query to [b5lefkbu], readsize=4000 17:47:41.554 L10 []#1495 sending query to [jfkoivg7], readsize=4000 17:47:41.555 L10 []#1496 sending query to [rkkkx6sk], readsize=4000 17:47:41.556 L10 []#1497 sending query to [tkxzeoh2], readsize=4000 17:47:41.558 L10 []#1498 sending query to [wpxpieqx], readsize=4000 17:47:41.599 L10 []#1499 got result from [b5lefkbu], 2 shares 17:47:41.599 L10 []#1500 _got_results: got shnum #9 from peerid b5lefkbu 17:47:41.600 L20 []#1501 found valid version 4-dqvj from b5lefkbu-sh9: 3-10/384/382 17:47:41.600 L10 []#1502 _got_results: got shnum #4 from peerid b5lefkbu 17:47:41.600 L10 []#1503 _got_results done 17:47:41.600 L10 []#1504 _check_for_done, mode is 'MODE_READ', 4 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.600 L10 []#1505 1 completed, 6 to query: need more 17:47:41.600 L10 []#1506 there are 4 queries outstanding 17:47:41.600 L10 []#1507 sending 0 more queries: 17:47:41.601 L10 []#1508 got result from [tkxzeoh2], 2 shares 17:47:41.601 L10 []#1509 _got_results: got shnum #8 from peerid tkxzeoh2 17:47:41.602 L10 []#1510 _got_results: got shnum #3 from peerid tkxzeoh2 17:47:41.602 L10 []#1511 _got_results done 17:47:41.602 L10 []#1512 _check_for_done, mode is 'MODE_READ', 3 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.602 L10 []#1513 2 completed, 6 to query: need more 17:47:41.602 L10 []#1514 there are 3 queries outstanding 17:47:41.602 L10 []#1515 sending 0 more queries: 17:47:41.603 L10 []#1516 got result from [rkkkx6sk], 2 shares 17:47:41.603 L10 []#1517 _got_results: got shnum #2 from peerid rkkkx6sk 17:47:41.603 L10 []#1518 _got_results: got shnum #7 from peerid rkkkx6sk 17:47:41.603 L10 []#1519 _got_results done 17:47:41.603 L10 []#1520 _check_for_done, mode is 'MODE_READ', 2 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.603 L10 []#1521 3 completed, 6 to query: need more 17:47:41.603 L10 []#1522 there are 2 queries outstanding 17:47:41.604 L10 []#1523 sending 0 more queries: 17:47:41.628 L10 []#1524 got result from [wpxpieqx], 2 shares 17:47:41.628 L10 []#1525 _got_results: got shnum #1 from peerid wpxpieqx 17:47:41.628 L10 []#1526 _got_results: got shnum #6 from peerid wpxpieqx 17:47:41.628 L10 []#1527 _got_results done 17:47:41.628 L10 []#1528 _check_for_done, mode is 'MODE_READ', 1 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.629 L10 []#1529 4 completed, 6 to query: need more 17:47:41.629 L10 []#1530 there are 1 queries outstanding 17:47:41.629 L10 []#1531 sending 0 more queries: 17:47:41.630 L10 []#1532 got result from [jfkoivg7], 2 shares 17:47:41.630 L10 []#1533 _got_results: got shnum #0 from peerid jfkoivg7 17:47:41.630 L10 []#1534 _got_results: got shnum #5 from peerid jfkoivg7 17:47:41.630 L10 []#1535 _got_results done 17:47:41.630 L10 []#1536 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.630 L20 []#1537 all queries are retired, no extra peers: done 17:47:41.630 L20 []#1538 servermap: 10*seq4-dqvj 17:47:41.703 L20 []#1539 : .getAttrs('/', followLinks=0) 17:47:41.703 L10 []#1540 : CONVERT '/' 17:47:41.703 L10 []#1541 : PATH [] 17:47:41.703 L10 []#1542 : _got_root( (, []) ) 17:47:41.703 L20 []#1543 SharemapUpdater(pwkes): starting (MODE_READ) 17:47:41.704 L10 []#1544 sending query to [b5lefkbu], readsize=4000 17:47:41.705 L10 []#1545 sending query to [jfkoivg7], readsize=4000 17:47:41.706 L10 []#1546 sending query to [rkkkx6sk], readsize=4000 17:47:41.708 L10 []#1547 sending query to [tkxzeoh2], readsize=4000 17:47:41.709 L10 []#1548 sending query to [wpxpieqx], readsize=4000 17:47:41.710 L20 []#1549 : .getAttrs('/', followLinks=1) 17:47:41.710 L10 []#1550 : CONVERT '/' 17:47:41.711 L10 []#1551 : PATH [] 17:47:41.711 L10 []#1552 : _got_root( (, []) ) 17:47:41.774 L10 []#1553 got result from [wpxpieqx], 2 shares 17:47:41.774 L10 []#1554 _got_results: got shnum #1 from peerid wpxpieqx 17:47:41.775 L20 []#1555 found valid version 4-dqvj from wpxpieqx-sh1: 3-10/384/382 17:47:41.775 L10 []#1556 _got_results: got shnum #6 from peerid wpxpieqx 17:47:41.775 L10 []#1557 _got_results done 17:47:41.775 L10 []#1558 _check_for_done, mode is 'MODE_READ', 4 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.775 L10 []#1559 1 completed, 6 to query: need more 17:47:41.775 L10 []#1560 there are 4 queries outstanding 17:47:41.775 L10 []#1561 sending 0 more queries: 17:47:41.776 L10 []#1562 got result from [b5lefkbu], 2 shares 17:47:41.776 L10 []#1563 _got_results: got shnum #9 from peerid b5lefkbu 17:47:41.776 L10 []#1564 _got_results: got shnum #4 from peerid b5lefkbu 17:47:41.777 L10 []#1565 _got_results done 17:47:41.777 L10 []#1566 _check_for_done, mode is 'MODE_READ', 3 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.777 L10 []#1567 2 completed, 6 to query: need more 17:47:41.777 L10 []#1568 there are 3 queries outstanding 17:47:41.777 L10 []#1569 sending 0 more queries: 17:47:41.778 L10 []#1570 got result from [tkxzeoh2], 2 shares 17:47:41.778 L10 []#1571 _got_results: got shnum #8 from peerid tkxzeoh2 17:47:41.778 L10 []#1572 _got_results: got shnum #3 from peerid tkxzeoh2 17:47:41.778 L10 []#1573 _got_results done 17:47:41.778 L10 []#1574 _check_for_done, mode is 'MODE_READ', 2 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.778 L10 []#1575 3 completed, 6 to query: need more 17:47:41.778 L10 []#1576 there are 2 queries outstanding 17:47:41.778 L10 []#1577 sending 0 more queries: 17:47:41.779 L10 []#1578 got result from [rkkkx6sk], 2 shares 17:47:41.779 L10 []#1579 _got_results: got shnum #2 from peerid rkkkx6sk 17:47:41.779 L10 []#1580 _got_results: got shnum #7 from peerid rkkkx6sk 17:47:41.780 L10 []#1581 _got_results done 17:47:41.780 L10 []#1582 _check_for_done, mode is 'MODE_READ', 1 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.780 L10 []#1583 4 completed, 6 to query: need more 17:47:41.780 L10 []#1584 there are 1 queries outstanding 17:47:41.780 L10 []#1585 sending 0 more queries: 17:47:41.781 L10 []#1586 got result from [jfkoivg7], 2 shares 17:47:41.781 L10 []#1587 _got_results: got shnum #0 from peerid jfkoivg7 17:47:41.781 L10 []#1588 _got_results: got shnum #5 from peerid jfkoivg7 17:47:41.781 L10 []#1589 _got_results done 17:47:41.781 L10 []#1590 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.781 L20 []#1591 all queries are retired, no extra peers: done 17:47:41.781 L20 []#1592 servermap: 10*seq4-dqvj 17:47:41.801 L20 []#1593 SharemapUpdater(pwkes): starting (MODE_READ) 17:47:41.801 L10 []#1594 sending query to [b5lefkbu], readsize=4000 17:47:41.803 L10 []#1595 sending query to [jfkoivg7], readsize=4000 17:47:41.804 L10 []#1596 sending query to [rkkkx6sk], readsize=4000 17:47:41.805 L10 []#1597 sending query to [tkxzeoh2], readsize=4000 17:47:41.807 L10 []#1598 sending query to [wpxpieqx], readsize=4000 17:47:41.855 L10 []#1599 got result from [wpxpieqx], 2 shares 17:47:41.855 L10 []#1600 _got_results: got shnum #1 from peerid wpxpieqx 17:47:41.855 L20 []#1601 found valid version 4-dqvj from wpxpieqx-sh1: 3-10/384/382 17:47:41.856 L10 []#1602 _got_results: got shnum #6 from peerid wpxpieqx 17:47:41.856 L10 []#1603 _got_results done 17:47:41.856 L10 []#1604 _check_for_done, mode is 'MODE_READ', 4 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.856 L10 []#1605 1 completed, 6 to query: need more 17:47:41.856 L10 []#1606 there are 4 queries outstanding 17:47:41.856 L10 []#1607 sending 0 more queries: 17:47:41.857 L10 []#1608 got result from [b5lefkbu], 2 shares 17:47:41.857 L10 []#1609 _got_results: got shnum #9 from peerid b5lefkbu 17:47:41.857 L10 []#1610 _got_results: got shnum #4 from peerid b5lefkbu 17:47:41.857 L10 []#1611 _got_results done 17:47:41.857 L10 []#1612 _check_for_done, mode is 'MODE_READ', 3 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.857 L10 []#1613 2 completed, 6 to query: need more 17:47:41.857 L10 []#1614 there are 3 queries outstanding 17:47:41.857 L10 []#1615 sending 0 more queries: 17:47:41.858 L10 []#1616 got result from [tkxzeoh2], 2 shares 17:47:41.858 L10 []#1617 _got_results: got shnum #8 from peerid tkxzeoh2 17:47:41.858 L10 []#1618 _got_results: got shnum #3 from peerid tkxzeoh2 17:47:41.859 L10 []#1619 _got_results done 17:47:41.859 L10 []#1620 _check_for_done, mode is 'MODE_READ', 2 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.859 L10 []#1621 3 completed, 6 to query: need more 17:47:41.859 L10 []#1622 there are 2 queries outstanding 17:47:41.859 L10 []#1623 sending 0 more queries: 17:47:41.860 L10 []#1624 got result from [rkkkx6sk], 2 shares 17:47:41.860 L10 []#1625 _got_results: got shnum #2 from peerid rkkkx6sk 17:47:41.860 L10 []#1626 _got_results: got shnum #7 from peerid rkkkx6sk 17:47:41.860 L10 []#1627 _got_results done 17:47:41.860 L10 []#1628 _check_for_done, mode is 'MODE_READ', 1 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.860 L10 []#1629 4 completed, 6 to query: need more 17:47:41.861 L10 []#1630 there are 1 queries outstanding 17:47:41.861 L10 []#1631 sending 0 more queries: 17:47:41.875 L10 []#1632 got result from [jfkoivg7], 2 shares 17:47:41.875 L10 []#1633 _got_results: got shnum #0 from peerid jfkoivg7 17:47:41.875 L10 []#1634 _got_results: got shnum #5 from peerid jfkoivg7 17:47:41.876 L10 []#1635 _got_results done 17:47:41.876 L10 []#1636 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.876 L20 []#1637 all queries are retired, no extra peers: done 17:47:41.876 L20 []#1638 servermap: 10*seq4-dqvj 17:47:41.897 L20 []#1639 : .openFile('/test.txt', 42 = 'FXF_WRITE|FXF_CREAT|FXF_EXCL', {}) 17:47:41.897 L10 []#1640 : CONVERT '/test.txt' 17:47:41.897 L10 []#1641 : PATH [u'test.txt'] 17:47:41.898 L10 []#1642 : case 2: root = , childname = u'test.txt', path[:-1] = [] 17:47:41.898 L10 []#1643 : _got_parent() 17:47:41.898 L20 []#1644 SharemapUpdater(pwkes): starting (MODE_WRITE) 17:47:41.899 L10 []#1645 sending query to [b5lefkbu], readsize=4000 17:47:41.900 L10 []#1646 sending query to [jfkoivg7], readsize=4000 17:47:41.902 L10 []#1647 sending query to [rkkkx6sk], readsize=4000 17:47:41.903 L10 []#1648 sending query to [tkxzeoh2], readsize=4000 17:47:41.905 L10 []#1649 sending query to [wpxpieqx], readsize=4000 17:47:41.951 L10 []#1650 got result from [tkxzeoh2], 2 shares 17:47:41.951 L10 []#1651 _got_results: got shnum #8 from peerid tkxzeoh2 17:47:41.951 L20 []#1652 got valid privkey from shnum 8 on peerid tkxzeoh2 17:47:41.952 L20 []#1653 found valid version 4-dqvj from tkxzeoh2-sh8: 3-10/384/382 17:47:41.953 L10 []#1654 _got_results: got shnum #3 from peerid tkxzeoh2 17:47:41.953 L10 []#1655 _got_results done 17:47:41.953 L10 []#1656 _check_for_done, mode is 'MODE_WRITE', 4 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.953 L10 []#1657 no recoverable versions: need more 17:47:41.953 L10 []#1658 there are 4 queries outstanding 17:47:41.953 L10 []#1659 sending 0 more queries: 17:47:41.954 L10 []#1660 got result from [jfkoivg7], 2 shares 17:47:41.954 L10 []#1661 _got_results: got shnum #0 from peerid jfkoivg7 17:47:41.955 L10 []#1662 _got_results: got shnum #5 from peerid jfkoivg7 17:47:41.955 L10 []#1663 _got_results done 17:47:41.955 L10 []#1664 _check_for_done, mode is 'MODE_WRITE', 3 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.955 L10 []#1665 no boundary yet, 1??1? 17:47:41.955 L10 []#1666 there are 3 queries outstanding 17:47:41.955 L10 []#1667 sending 0 more queries: 17:47:41.956 L10 []#1668 got result from [b5lefkbu], 2 shares 17:47:41.956 L10 []#1669 _got_results: got shnum #9 from peerid b5lefkbu 17:47:41.956 L10 []#1670 _got_results: got shnum #4 from peerid b5lefkbu 17:47:41.956 L10 []#1671 _got_results done 17:47:41.975 L10 []#1672 _check_for_done, mode is 'MODE_WRITE', 2 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.975 L10 []#1673 no boundary yet, 1??11 17:47:41.975 L10 []#1674 there are 2 queries outstanding 17:47:41.975 L10 []#1675 sending 0 more queries: 17:47:41.979 L10 []#1676 got result from [rkkkx6sk], 2 shares 17:47:41.979 L10 []#1677 _got_results: got shnum #2 from peerid rkkkx6sk 17:47:41.979 L10 []#1678 _got_results: got shnum #7 from peerid rkkkx6sk 17:47:41.979 L10 []#1679 _got_results done 17:47:41.979 L10 []#1680 _check_for_done, mode is 'MODE_WRITE', 1 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:41.979 L10 []#1681 no boundary yet, 1?111 17:47:41.979 L10 []#1682 there are 1 queries outstanding 17:47:41.979 L10 []#1683 sending 0 more queries: 17:47:42.908 L10 []#1684 got result from [wpxpieqx], 2 shares 17:47:42.908 L10 []#1685 _got_results: got shnum #1 from peerid wpxpieqx 17:47:42.908 L10 []#1686 _got_results: got shnum #6 from peerid wpxpieqx 17:47:42.908 L10 []#1687 _got_results done 17:47:42.908 L10 []#1688 _check_for_done, mode is 'MODE_WRITE', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:42.908 L20 []#1689 all queries are retired, no extra peers: done 17:47:42.908 L20 []#1690 servermap: 10*seq4-dqvj 17:47:42.923 L20 []#1691 Retrieve(pwkes): starting 17:47:42.923 L10 []#1692 sending sh#0 request to [jfkoivg7] 17:47:42.923 L20 []#1693 got data from cache 17:47:42.924 L10 []#1694 sending sh#1 request to [wpxpieqx] 17:47:42.924 L20 []#1695 got data from cache 17:47:42.924 L10 []#1696 sending sh#2 request to [rkkkx6sk] 17:47:42.924 L20 []#1697 got data from cache 17:47:42.943 L10 []#1698 got results (1 shares) from [jfkoivg7] 17:47:42.943 L20 []#1699 _got_results: got shnum #0 from peerid jfkoivg7 17:47:42.943 L20 []#1700 data valid! len=128 17:47:42.943 L10 []#1701 _check_for_done: running=True, decoding=False 17:47:42.943 L10 []#1702 _maybe_send_more_queries, have=1, k=3, outstanding=2 17:47:42.943 L10 []#1703 enough queries are in flight, no more are needed 17:47:42.943 L10 []#1704 got results (1 shares) from [wpxpieqx] 17:47:42.944 L20 []#1705 _got_results: got shnum #1 from peerid wpxpieqx 17:47:42.944 L20 []#1706 data valid! len=128 17:47:42.944 L10 []#1707 _check_for_done: running=True, decoding=False 17:47:42.944 L10 []#1708 _maybe_send_more_queries, have=2, k=3, outstanding=1 17:47:42.944 L10 []#1709 enough queries are in flight, no more are needed 17:47:42.944 L10 []#1710 got results (1 shares) from [rkkkx6sk] 17:47:42.944 L20 []#1711 _got_results: got shnum #2 from peerid rkkkx6sk 17:47:42.945 L20 []#1712 data valid! len=128 17:47:42.945 L10 []#1713 _check_for_done: running=True, decoding=False 17:47:42.945 L20 []#1714 params (384, 3, 10), we have 3 shares 17:47:42.945 L20 []#1715 about to decode, shareids=[0, 1, 2] 17:47:42.945 L20 []#1716 decode done, 3 buffers 17:47:42.945 L20 []#1717 joined length 384, datalength 382 17:47:42.945 L20 []#1718 segment len=382 17:47:42.945 L20 []#1719 Retrieve done, success! 17:47:42.968 L20 []#1720 Publish(pwkes): starting 17:47:42.968 L20 []#1721 starting publish, datalen is 603 17:47:42.968 L10 []#1722 new seqnum will be 5 17:47:42.969 L20 []#1723 _encrypt_and_encode 17:47:42.969 L20 []#1724 _generate_shares 17:47:42.970 L20 []#1725 my new root_hash is vz5mqqojzant675tkbb53bbxuuzhxwzgfmebefhzznyswgxvczqq 17:47:42.979 L10 []#1726 entering loop 17:47:42.980 L10 []#1727 current goal: before update: , sh0 to [jfkoivg7], sh1 to [wpxpieqx], sh2 to [rkkkx6sk], sh3 to [tkxzeoh2], sh4 to [b5lefkbu], sh5 to [jfkoivg7], sh6 to [wpxpieqx], sh7 to [rkkkx6sk], sh8 to [tkxzeoh2], sh9 to [b5lefkbu] 17:47:42.980 L10 []#1728 we are planning to push new seqnum=#5 17:47:42.980 L10 []#1729 need to send 10 new shares 17:47:42.980 L20 []#1730 _send_shares 17:47:42.980 L10 []#1731 sending 5 shares 17:47:43.005 L10 []#1732 5 shares sent 17:47:43.109 L20 []#1733 _got_write_answer from tkxzeoh2 17:47:43.110 L10 []#1734 entering loop 17:47:43.111 L10 []#1735 current goal: before update: , sh0 to [jfkoivg7], sh1 to [wpxpieqx], sh2 to [rkkkx6sk], sh3 to [tkxzeoh2], sh4 to [b5lefkbu], sh5 to [jfkoivg7], sh6 to [wpxpieqx], sh7 to [rkkkx6sk], sh8 to [tkxzeoh2], sh9 to [b5lefkbu] 17:47:43.111 L10 []#1736 we are planning to push new seqnum=#5 17:47:43.111 L10 []#1737 8 queries still outstanding 17:47:43.327 L20 []#1738 _got_write_answer from jfkoivg7 17:47:43.331 L10 []#1739 entering loop 17:47:43.333 L10 []#1740 current goal: before update: , sh0 to [jfkoivg7], sh1 to [wpxpieqx], sh2 to [rkkkx6sk], sh3 to [tkxzeoh2], sh4 to [b5lefkbu], sh5 to [jfkoivg7], sh6 to [wpxpieqx], sh7 to [rkkkx6sk], sh8 to [tkxzeoh2], sh9 to [b5lefkbu] 17:47:43.333 L10 []#1741 we are planning to push new seqnum=#5 17:47:43.333 L10 []#1742 6 queries still outstanding 17:47:43.355 L20 []#1743 _got_write_answer from wpxpieqx 17:47:43.357 L10 []#1744 entering loop 17:47:43.358 L10 []#1745 current goal: before update: , sh0 to [jfkoivg7], sh1 to [wpxpieqx], sh2 to [rkkkx6sk], sh3 to [tkxzeoh2], sh4 to [b5lefkbu], sh5 to [jfkoivg7], sh6 to [wpxpieqx], sh7 to [rkkkx6sk], sh8 to [tkxzeoh2], sh9 to [b5lefkbu] 17:47:43.358 L10 []#1746 we are planning to push new seqnum=#5 17:47:43.358 L10 []#1747 4 queries still outstanding 17:47:43.487 L20 []#1748 _got_write_answer from rkkkx6sk 17:47:43.489 L10 []#1749 entering loop 17:47:43.490 L10 []#1750 current goal: before update: , sh0 to [jfkoivg7], sh1 to [wpxpieqx], sh2 to [rkkkx6sk], sh3 to [tkxzeoh2], sh4 to [b5lefkbu], sh5 to [jfkoivg7], sh6 to [wpxpieqx], sh7 to [rkkkx6sk], sh8 to [tkxzeoh2], sh9 to [b5lefkbu] 17:47:43.490 L10 []#1751 we are planning to push new seqnum=#5 17:47:43.490 L10 []#1752 2 queries still outstanding 17:47:44.001 L20 []#1753 _got_write_answer from b5lefkbu 17:47:44.003 L10 []#1754 entering loop 17:47:44.004 L10 []#1755 current goal: before update: , sh0 to [jfkoivg7], sh1 to [wpxpieqx], sh2 to [rkkkx6sk], sh3 to [tkxzeoh2], sh4 to [b5lefkbu], sh5 to [jfkoivg7], sh6 to [wpxpieqx], sh7 to [rkkkx6sk], sh8 to [tkxzeoh2], sh9 to [b5lefkbu] 17:47:44.004 L10 []#1756 we are planning to push new seqnum=#5 17:47:44.004 L20 []#1757 no queries outstanding, no placements needed: done 17:47:44.004 L20 []#1758 Publish done, success 17:47:44.018 L20 []#1759 SharemapUpdater(pwkes): starting (MODE_READ) 17:47:44.018 L10 []#1760 sending query to [b5lefkbu], readsize=4000 17:47:44.020 L10 []#1761 sending query to [jfkoivg7], readsize=4000 17:47:44.021 L10 []#1762 sending query to [rkkkx6sk], readsize=4000 17:47:44.023 L10 []#1763 sending query to [tkxzeoh2], readsize=4000 17:47:44.033 L10 []#1764 sending query to [wpxpieqx], readsize=4000 17:47:44.092 L10 []#1765 got result from [jfkoivg7], 2 shares 17:47:44.092 L10 []#1766 _got_results: got shnum #0 from peerid jfkoivg7 17:47:44.092 L20 []#1767 found valid version 5-vz5m from jfkoivg7-sh0: 3-10/603/603 17:47:44.092 L10 []#1768 _got_results: got shnum #5 from peerid jfkoivg7 17:47:44.093 L10 []#1769 _got_results done 17:47:44.093 L10 []#1770 _check_for_done, mode is 'MODE_READ', 4 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:44.093 L10 []#1771 1 completed, 6 to query: need more 17:47:44.093 L10 []#1772 there are 4 queries outstanding 17:47:44.093 L10 []#1773 sending 0 more queries: 17:47:44.113 L10 []#1774 got result from [wpxpieqx], 2 shares 17:47:44.113 L10 []#1775 _got_results: got shnum #1 from peerid wpxpieqx 17:47:44.113 L10 []#1776 _got_results: got shnum #6 from peerid wpxpieqx 17:47:44.113 L10 []#1777 _got_results done 17:47:44.113 L10 []#1778 _check_for_done, mode is 'MODE_READ', 3 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:44.114 L10 []#1779 2 completed, 6 to query: need more 17:47:44.114 L10 []#1780 there are 3 queries outstanding 17:47:44.114 L10 []#1781 sending 0 more queries: 17:47:44.114 L10 []#1782 got result from [b5lefkbu], 2 shares 17:47:44.115 L10 []#1783 _got_results: got shnum #9 from peerid b5lefkbu 17:47:44.115 L10 []#1784 _got_results: got shnum #4 from peerid b5lefkbu 17:47:44.115 L10 []#1785 _got_results done 17:47:44.115 L10 []#1786 _check_for_done, mode is 'MODE_READ', 2 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:44.115 L10 []#1787 3 completed, 6 to query: need more 17:47:44.115 L10 []#1788 there are 2 queries outstanding 17:47:44.115 L10 []#1789 sending 0 more queries: 17:47:44.116 L10 []#1790 got result from [rkkkx6sk], 2 shares 17:47:44.116 L10 []#1791 _got_results: got shnum #2 from peerid rkkkx6sk 17:47:44.116 L10 []#1792 _got_results: got shnum #7 from peerid rkkkx6sk 17:47:44.116 L10 []#1793 _got_results done 17:47:44.116 L10 []#1794 _check_for_done, mode is 'MODE_READ', 1 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:44.117 L10 []#1795 4 completed, 6 to query: need more 17:47:44.117 L10 []#1796 there are 1 queries outstanding 17:47:44.117 L10 []#1797 sending 0 more queries: 17:47:44.416 L10 []#1798 got result from [tkxzeoh2], 2 shares 17:47:44.417 L10 []#1799 _got_results: got shnum #8 from peerid tkxzeoh2 17:47:44.417 L10 []#1800 _got_results: got shnum #3 from peerid tkxzeoh2 17:47:44.417 L10 []#1801 _got_results done 17:47:44.417 L10 []#1802 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False 17:47:44.417 L20 []#1803 all queries are retired, no extra peers: done 17:47:44.417 L20 []#1804 servermap: 10*seq5-vz5m 17:47:44.432 L20 []#1805 Retrieve(pwkes): starting 17:47:44.432 L10 []#1806 sending sh#0 request to [jfkoivg7] 17:47:44.432 L20 []#1807 got data from cache 17:47:44.432 L10 []#1808 sending sh#1 request to [wpxpieqx] 17:47:44.432 L20 []#1809 got data from cache 17:47:44.433 L10 []#1810 sending sh#2 request to [rkkkx6sk] 17:47:44.433 L20 []#1811 got data from cache 17:47:44.452 L10 []#1812 got results (1 shares) from [jfkoivg7] 17:47:44.452 L20 []#1813 _got_results: got shnum #0 from peerid jfkoivg7 17:47:44.452 L20 []#1814 data valid! len=201 17:47:44.452 L10 []#1815 _check_for_done: running=True, decoding=False 17:47:44.453 L10 []#1816 _maybe_send_more_queries, have=1, k=3, outstanding=2 17:47:44.453 L10 []#1817 enough queries are in flight, no more are needed 17:47:44.453 L10 []#1818 got results (1 shares) from [wpxpieqx] 17:47:44.453 L20 []#1819 _got_results: got shnum #1 from peerid wpxpieqx 17:47:44.453 L20 []#1820 data valid! len=201 17:47:44.453 L10 []#1821 _check_for_done: running=True, decoding=False 17:47:44.453 L10 []#1822 _maybe_send_more_queries, have=2, k=3, outstanding=1 17:47:44.453 L10 []#1823 enough queries are in flight, no more are needed 17:47:44.453 L10 []#1824 got results (1 shares) from [rkkkx6sk] 17:47:44.454 L20 []#1825 _got_results: got shnum #2 from peerid rkkkx6sk 17:47:44.454 L20 []#1826 data valid! len=201 17:47:44.454 L10 []#1827 _check_for_done: running=True, decoding=False 17:47:44.454 L20 []#1828 params (603, 3, 10), we have 3 shares 17:47:44.454 L20 []#1829 about to decode, shareids=[0, 1, 2] 17:47:44.454 L20 []#1830 decode done, 3 buffers 17:47:44.454 L20 []#1831 joined length 603, datalength 603 17:47:44.454 L20 []#1832 segment len=603 17:47:44.454 L20 []#1833 Retrieve done, succes