bot-community growing stagnates for teeproxy/Cantillon

[brenzi]

the current bot-community script lets communities grow slower than theoretically possible because some newbies get skipped for some reason

accounts registering, total supply
10, 9.999993239437556
12, 21.99998512676262
15, 36.99997498591895
18, 53.99995132397919
22, 72.9999335211784
27, 93.99983481719336
33, 113.99971583180132
40, 129.9994579176139
47, 145.9992052998484
55, 164.99891257126885
64, 185.99867899830747
73, 201.99806672643024
83, 219.99756904584297
93, 234.99735339273863
103, 252.99670958088132
114, 268.99556347154794
125, 285.99400216563197
137, 304.9933506066467
149, 324.9925816506939
161, 341.99065689730736
173, 358.9887874779888
185, 376.98647685753116
198, 393.9835994167923
211, 411.98183984453834
225, 423.97746040034997
239, 437.97327824061904
253, 452.9695774788613
268, 470.9679359701643
283, 487.9640632435755
298, 506.9589468268743
313, 523.9536518452268
329, 540.9477862360828
345, 557.9404156004059
361, 572.9297931812868
378, 594.9265290416599

should be close to +25% every ceremony

[brenzi]

strangely, teeproxy behaves different than notee:

teeproxy (stagnation around 11-25 UBI payouts. not growing):

10, 0.0 OK adding 0 newbies
10, 9.999993239437556 OK +2 newbies
12, 21.999985126762624 OK +3 newbies
15, 36.99997498591895 OK +3 newbies
18, 54.99996281690655 OK +4 newbies
22, 72.99991729586691 !!!!!!! should be 76.999. 4 newbies have been skipped. nonzero = 18.
27, 90.99984664817705
32, 108.9997452401757
38, 127.99960766379766
...

notee (as expected)

10, 0
10, 10.0 OK
12, 21.999993239437554 OK
15, 36.99997836620476 OK
18, 54.99995335213833 OK
22, 76.99991616907641 OK
27, 103.99986411280231 OK
33, 136.99979380304472 OK
...

[brenzi]

could it be that only the first TrustedCall per synched block is executed?

Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave] Scanning block 432614 for relevant xt
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave] Found CallWorker extrinsic in block:
Dec 10 20:52:11 devsgx03 encointer-worker[19235]:     Call: [8, 2]
Dec 10 20:52:11 devsgx03 encointer-worker[19235]:     Request:
Dec 10 20:52:11 devsgx03 encointer-worker[19235]:     shard: ARhC6n8nE4wgeMtDtmapfFR4VctJNE6J2YPphJhRTrNq
Dec 10 20:52:11 devsgx03 encointer-worker[19235]:     cyphertext: [25, 218, 239, 228, 186, 48, 122, 228, 120, 251, 153, 80, 55, 126, 56, 158, 214, 70, 129, 1, 250, 107, 223, 188, 46, 162, 211, 138, 107, 145, 62, 192, 117, 223, 253, 69, 144, 121, 115, 0, 156, 58, 50, 28, 82, 142, 93, 230, 23, 12, 70, 210, 101, 30, 226, 84, 80, 108, 57, 85, 178, 138, 165, 170, 72, 8, 159, 142, 53, 33, 176, 208, 52, 118, 154, 141, 146, 51, 18, 75, 89, 18, 80, 4, 241, 139, 93, 84, 215, 154, 21, 150, 184, 95, 142, 25, 31, 72, 19, 121, 207, 100, 154, 22, 178, 188, 183, 249, 24, 26, 186, 51, 80, 5, 88, 231, 191, 40, 218, 127, 184, 130, 130, 181, 171, 171, 118, 168, 212, 204, 252, 85, 97, 229, 207, 69, 214, 52, 51, 233, 238, 98, 176, 246, 171, 14, 138, 233, 250, 245, 220, 114, 40, 161, 77, 123, 98, 107, 229, 152, 136, 240, 5, 148, 113, 130, 201, 170, 4, 126, 83, 112, 169, 38, 88, 50, 161, 185, 82, 50, 223, 165, 164, 30, 205, 243, 179, 51, 223, 4, 27, 37, 243, 32, 28, 64, 154, 136, 28, 220, 6, 94, 96, 227, 197, 5, 18, 78, 29, 167, 164, 74, 200, 216, 162, 109, 8, 123, 201, 214, 211, 215, 114, 160, 228, 99, 133, 149, 219, 96, 97, 24, 59, 26, 223, 193, 9, 67, 132, 161, 84, 40, 24, 24, 210, 181, 240, 206, 20, 136, 101, 247, 93, 98, 168, 4, 16, 221, 201, 82, 208, 15, 90, 35, 204, 219, 38, 122, 87, 21, 246, 217, 160, 56, 115, 97, 236, 233, 134, 18, 145, 5, 243, 236, 205, 202, 22, 92, 228, 94, 213, 117, 191, 81, 89, 239, 59, 217, 60, 138, 3, 72, 179, 157, 208, 31, 216, 11, 176, 85, 157, 121, 97, 164, 125, 45, 163, 27, 129, 232, 164, 253, 95, 6, 224, 60, 51, 197, 216, 240, 114, 47, 91, 11, 214, 106, 69, 108, 205, 211, 33, 167, 166, 129, 51, 27, 114, 125, 131, 100, 203, 29, 154, 60, 31, 204, 169, 136, 23, 23, 162, 80, 188, 106, 218, 127, 246, 6, 82, 32, 237, 152, 173, 2, 207, 63, 3, 24, 165, 211, 79, 153, 91, 145, 129, 194, 96, 219, 113, 158, 21, 61, 26, 207, 232, 195, 66, 2, 117, 229, 186, 245, 128, 105, 208, 161, 204, 135, 28, 32, 104, 116, 93, 216, 206, 216, 48, 182, 95, 242, 198, 37, 13, 117, 23, 231, 165, 15, 74, 104, 215, 245, 81, 146, 87, 175, 235, 114, 162, 21, 85, 234, 44, 50, 69, 203, 53, 23, 158, 61, 182, 230, 56, 123, 12, 46, 33, 66, 69, 118, 163, 138, 92, 220, 194, 128, 11, 60, 46, 177, 105, 206, 218, 185, 184, 25, 34, 89, 145, 154, 138, 9, 185, 195, 4, 177, 157, 149, 120, 63, 134, 121, 79, 194, 66, 42, 78, 138, 59, 199, 179, 35, 112, 183, 21, 26, 47, 16, 171, 147, 52, 44, 122, 176, 64, 13, 195, 26, 12, 12, 254, 169, 181, 71, 199, 227, 131, 58, 183, 249, 69, 72, 88, 204, 12, 72, 251, 29, 59, 111, 233, 178, 12, 111, 125, 229, 133, 104, 127, 215, 145, 132, 72, 61, 32, 169, 51, 204, 176, 51, 40, 158, 81, 240, 236, 196, 145, 12, 37, 231, 183, 25, 206, 147, 206, 194, 121, 94, 234, 55, 88, 36, 112, 99, 143, 160, 106, 160, 23, 66, 170, 82, 143, 48, 41, 137, 52, 45, 35, 188, 199, 13, 158, 39, 58, 116, 250, 72, 87, 250, 181, 17, 119, 158, 61, 152, 153, 10, 26, 243, 120, 42, 245, 154, 144, 113, 248, 113, 212, 27, 119, 188, 84, 122, 119, 236, 68, 56, 183, 53, 90, 50, 244, 117, 80, 255, 187, 164, 173, 110, 174, 104, 194, 193, 213, 7, 255, 250, 138, 25, 249, 235, 37, 145, 87, 34, 46, 184, 171, 132, 46, 109, 143, 149, 254, 153, 67, 77, 30, 192, 43, 241, 155, 168, 34, 135, 249, 45, 25, 163, 45, 213, 148, 232, 93, 176, 207, 250, 55, 142, 94, 96, 9, 161, 31, 73, 134, 126, 237, 81, 85, 56, 187, 21, 196, 160, 155, 48, 233, 244, 55, 112, 2, 33, 147, 200, 13, 98, 131, 85, 145, 71, 75, 124, 249, 117, 197, 203, 65, 182, 205, 169, 160, 61, 252, 3, 250, 130, 155, 96, 25, 163, 71, 6, 31, 243, 139, 136, 0, 199, 105, 110, 41, 141, 158, 190, 80, 32]
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave] decrypt the call
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave] query mrenclave of self
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave::attestation]     [Enclave] EPID group id = [173, 11, 0, 0]
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave::attestation]     [Enclave] Report creation successful. mr_signer.m = [131, 215, 25, 231, 125, 234, 202, 20, 112, 246, 186, 246, 42, 77, 119, 67, 3, 200, 153, 219, 105, 2, 15, 156, 112, 238, 29, 252, 8, 199, 206, 158]
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave] MRENCLAVE of self is Az5c2vRddt3JMnQerJDoQJQLpd3SJp6nWXEMPPtX5pf5
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave] Update STF storage!
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave] execute STF
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_stf::sgx] CurrentPhase Some(Ok(ATTESTING))
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_stf::sgx] CurrentCeremonyIndex Some(Ok(1443))
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_stf::sgx] Attestations [<wasm:stripped>, <wasm:stripped>]
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_stf::sgx] NextPhaseTimestamp Some(Ok(1607630400000))
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_stf::sgx] PhaseDurations for ATTESTING Some(Ok(600000))
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG pallet_encointer_ceremonies] meetup 1 at location <wasm:stripped> should happen at 1607686200000 for cid 0x8c0c90f80f7a578d8c892d356fa1bed0eb26d6850ce8555828876635c2deb8a6
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG pallet_encointer_ceremonies] sucessfully registered 2 attestations for
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave] Call hash 0xe3577d61955fd3c1624bd5554cf72e65c0893749979a1c16fb8fed7877a02745
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave] Restored ECC pubkey:
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave::io::light_validation] backup chain relay state
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:11Z DEBUG substratee_worker_enclave::io::light_validation] Seal Chain Relay State. Current state: LightValidationTest { num_relays: 1, tracked_relays: {1: RelayInfo { last_finalized_block_header_number: 432614, current_validator_set: [(<wasm:stripped>, 1), (<wasm:stripped>, 1)], current_validator_set_id: 0 amount of transaction in tx_inclusion_queue: 1 }} }
Dec 10 20:52:11 devsgx03 encointer-worker[19235]: Sync chain relay: Enclave wants to send 1 extrinsics
Dec 10 20:52:12 devsgx03 encointer-worker[19235]: Synced 432615 blocks out of 432615 finalized blocks
Dec 10 20:52:12 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:12Z INFO  encointer_worker] [+] Received CallConfirmed event
Dec 10 20:52:13 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:13Z DEBUG substratee_worker_enclave] Restored ECC pubkey:
Dec 10 20:52:13 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:13Z DEBUG substratee_worker_enclave] Syncing chain relay!
Dec 10 20:52:14 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:14Z DEBUG chain_relay] Syncing finalized block without grandpa proof. Amount of unjustified headers: 487
Dec 10 20:52:14 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:14Z DEBUG substratee_worker_enclave] Update STF storage upon block import!
Dec 10 20:52:17 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:17Z DEBUG substratee_worker_enclave] Scanning block 432615 for relevant xt
Dec 10 20:52:17 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:17Z DEBUG substratee_worker_enclave] Restored ECC pubkey:
Dec 10 20:52:17 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:17Z DEBUG substratee_worker_enclave::io::light_validation] backup chain relay state
Dec 10 20:52:17 devsgx03 encointer-worker[19235]: [2020-12-10T19:52:17Z DEBUG substratee_worker_enclave::io::light_validation] Seal Chain Relay State. Current state: LightValidationTest { num_relays: 1, tracked_relays: {1: RelayInfo { last_finalized_block_header_number: 432615, current_validator_set: [(<wasm:stripped>, 1), (<wasm:stripped>, 1)], current_validator_set_id: 0 amount of transaction in tx_inclusion_queue: 1 }} }

I'm pretty sure that attestations from 3 participants have been sent

update no, I've observed multiple calls being processed for a single block