Get GolemDb entity metadata - not found for existing entity #3480
Description
Sometimes call to golembase_getEntityMetaData fails with entity not found, despite the entity existing on chain. The problem doesn't happen in deterministic way. I was able to catch it once, but in the second run everything worked fine.
[2025-09-22T15:20:25.658+0200 DEBUG ya_market::protocol::discovery] Removing all existing offers from previous runs..
[2025-09-22T15:20:25.658+0200 DEBUG golem_base_sdk::rpc] RPC Call - Method: golembase_getEntitiesOfOwner, Params: [0x8f8c7eb38013f57acf44fd36fcb2847810166b2a]
[2025-09-22T15:20:25.725+0200 TRACE golem_base_sdk::rpc] RPC Response: Some([])
[2025-09-22T15:20:25.725+0200 DEBUG golem_base_sdk::rpc] RPC Call - Method: golembase_getEntitiesOfOwner, Params: [0x95369fc6fd02afeca110b9c32a21fb8ad899ee0a]
[2025-09-22T15:20:25.760+0200 TRACE golem_base_sdk::rpc] RPC Response: Some([0xf34b45f42cd251fb8af4be8f39cfe4bea1d12e428bc5ee14f9fc2d7efb5e51e1, 0x285beeceb0deae2d3a13d4e73ce2616af01b7903304ad7897cf864388d2429db])
[2025-09-22T15:20:25.760+0200 DEBUG golem_base_sdk::rpc] RPC Call - Method: golembase_getEntityMetaData, Params: [0xf34b45f42cd251fb8af4be8f39cfe4bea1d12e428bc5ee14f9fc2d7efb5e51e1]
[2025-09-22T15:20:25.820+0200 WARN ya_market::protocol::discovery] Failed to remove Offers for identity 0x95369fc6fd02afeca110b9c32a21fb8ad899ee0a: Failed to send the RPC request: Error response from RPC service: error code -32000: entity 0xf34b45f42cd251fb8af4be8f39cfe4bea1d12e428bc5ee14f9fc2d7efb5e51e1 not found
[2025-09-22T15:20:25.971+0200 TRACE golem_base_sdk::rpc] RPC Response: Some([SearchResult { key: 0xf34b45f42cd251fb8af4be8f39cfe4bea1d12e428bc5ee14f9fc2d7efb5e51e1, value: b"{\"properties\":{\"golem\":{\"activity\":{\"caps\":{\"deploy\":{\"report-progress\":true},\"transfer\":{\"protocol\":[\"http\",\"gftp\",\"https\"],\"report-progress\":true}}},\"com\":{\"payment\":{\"debit-notes\":{\"accept-timeout?\":240},\"platform\":{\"erc20-holesky-tglm\":{\"address\":\"0xa5ad3f81e283983b8e9705b2e31d0c138bb2b1b7\"}},\"protocol\":{\"version\":3}},\"pricing\":{\"model\":{\"@tag\":\"linear\",\"linear\":{\"coeffs\":[0.001388888888888889,0.0002777777777777778,0.0]}}},\"scheme\":{\"@tag\":\"payu\",\"payu\":{\"debit-note\":{\"interval-sec?\":120},\"payment-timeout-sec?\":120}},\"usage\":{\"vector\":[\"golem.usage.cpu_sec\",\"golem.usage.duration_sec\"]}},\"inf\":{\"cpu\":{\"architecture\":\"x86_64\",\"cores\":6,\"threads\":11},\"mem\":{\"gib\":28.0},\"storage\":{\"gib\":7.700756072998047}},\"node\":{\"debug\":{\"subnet\":\"public\"},\"id\":{\"name\":\"nieznanysprawiciel-leopard-Provider-1\"},\"net\":{\"is-public\":false}},\"runtime\":{\"name\":\"wasmtime\",\"version\":\"0.2.1\"},\"srv\":{\"caps\":{\"multi-activity\":true,\"payload-manifest\":false}}}},\"constraints\":\"(&\\n (golem.srv.comp.expiration>1758547111156)\\n (golem.node.debug.subnet=public)\\n)\",\"providerId\":\"0x95369fc6fd02afeca110b9c32a21fb8ad899ee0a\",\"expiration\":\"2025-09-22T14:18:31.158368160Z\",\"timestamp\":\"2025-09-22T13:18:31.158368160Z\"}" }, SearchResult { key: 0x285beeceb0deae2d3a13d4e73ce2616af01b7903304ad7897cf864388d2429db, value: b"{\"properties\":{\"golem\":{\"activity\":{\"caps\":{\"deploy\":{\"report-progress\":true},\"transfer\":{\"protocol\":[\"gftp\",\"http\",\"https\"],\"report-progress\":true}}},\"com\":{\"payment\":{\"debit-notes\":{\"accept-timeout?\":240},\"platform\":{\"erc20-holesky-tglm\":{\"address\":\"0xa5ad3f81e283983b8e9705b2e31d0c138bb2b1b7\"}},\"protocol\":{\"version\":3}},\"pricing\":{\"model\":{\"@tag\":\"linear\",\"linear\":{\"coeffs\":[0.001388888888888889,0.0002777777777777778,0.0]}}},\"scheme\":{\"@tag\":\"payu\",\"payu\":{\"debit-note\":{\"interval-sec?\":120},\"payment-timeout-sec?\":120}},\"usage\":{\"vector\":[\"golem.usage.cpu_sec\",\"golem.usage.duration_sec\"]}},\"inf\":{\"cpu\":{\"architecture\":\"x86_64\",\"brand\":\"Intel(R) Core(TM) i7-9750H CPU @ 2.60GHz\",\"capabilities\":[\"sse3\",\"pclmulqdq\",\"dtes64\",\"monitor\",\"dscpl\",\"vmx\",\"eist\",\"tm2\",\"ssse3\",\"fma\",\"cmpxchg16b\",\"pdcm\",\"pcid\",\"sse41\",\"sse42\",\"x2apic\",\"movbe\",\"popcnt\",\"tsc_deadline\",\"aesni\",\"xsave\",\"osxsave\",\"avx\",\"f16c\",\"rdrand\",\"fpu\",\"vme\",\"de\",\"pse\",\"tsc\",\"msr\",\"pae\",\"mce\",\"cx8\",\"apic\",\"sep\",\"mtrr\",\"pge\",\"mca\",\"cmov\",\"pat\",\"pse36\",\"clfsh\",\"ds\",\"acpi\",\"mmx\",\"fxsr\",\"sse\",\"sse2\",\"ss\",\"htt\",\"tm\",\"pbe\",\"fsgsbase\",\"adjust_msr\",\"bmi1\",\"avx2\",\"smep\",\"bmi2\",\"rep_movsb_stosb\",\"invpcid\",\"deprecate_fpu_cs_ds\",\"mpx\",\"rdseed\",\"adx\",\"smap\",\"clflushopt\",\"processor_trace\",\"sgx\",\"sgx_lc\"],\"cores\":6,\"model\":\"Stepping 10 Family 6 Model 302\",\"threads\":11,\"vendor\":\"GenuineIntel\"},\"mem\":{\"gib\":28.0},\"storage\":{\"gib\":7.700756072998047}},\"node\":{\"debug\":{\"subnet\":\"public\"},\"id\":{\"name\":\"nieznanysprawiciel-leopard-Provider-1\"},\"net\":{\"is-public\":false}},\"runtime\":{\"capabilities\":[\"inet\",\"vpn\",\"manifest-support\",\"start-entrypoint\"],\"name\":\"vm\",\"version\":\"0.3.0\"},\"srv\":{\"caps\":{\"multi-activity\":true,\"payload-manifest\":true}}}},\"constraints\":\"(&\\n (golem.srv.comp.expiration>1758547114256)\\n (golem.node.debug.subnet=public)\\n)\",\"providerId\":\"0x95369fc6fd02afeca110b9c32a21fb8ad899ee0a\",\"expiration\":\"2025-09-22T14:18:34.258348806Z\",\"timestamp\":\"2025-09-22T13:18:34.258348806Z\"}" }])
- First we list entities and
0xf34b45f42cd251fb8af4be8f39cfe4bea1d12e428bc5ee14f9fc2d7efb5e51e1is returned. - Next we run
golembase_getEntityMetaDataand entity can't be found - Afterwards running
golembase_queryEntitiesreturns the entity in question as one of many
Entity created at:
[2025-09-22T15:18:31.609+0200 DEBUG golem_base_sdk::account] Transaction attempt 1 sent with hash: 0x26ecc34e69370239348a0bcd61228fb18a523e018864a3058d78712e30335298
[2025-09-22T15:18:34.011+0200 DEBUG golem_base_sdk::rpc] RPC Call - Method: golembase_getEntityMetaData, Params: [0xf34b45f42cd251fb8af4be8f39cfe4bea1d12e428bc5ee14f9fc2d7efb5e51e1]
[2025-09-22T15:18:34.051+0200 TRACE golem_base_sdk::rpc] RPC Response: EntityMetaData { expires_at_block: Some(1382615), payload: None, string_annotations: [Annotation { key: "golem_marketplace_type", value: "Offer" }], numeric_annotations: [], owner: 0x95369fc6fd02afeca110b9c32a21fb8ad899ee0a }
[2025-09-22T15:18:34.252+0200 INFO golem_base_sdk::account] Transaction 0x26ecc34e69370239348a0bcd61228fb18a523e018864a3058d78712e30335298 was included in a block Some(1380815) (Some("67056e22373221812d1af20c9c424197a77723e2cff501d98de49dfd7c0135c6")). Waiting for 1 confirmations.
[2025-09-22T15:18:34.252+0200 INFO golem_base_sdk::account] Transaction succeeded on attempt 1 with hash: 0x26ecc34e69370239348a0bcd61228fb18a523e018864a3058d78712e30335298
[2025-09-22T15:18:34.253+0200 TRACE golem_base_sdk::client] Log: Log { inner: Log { address: 0x0000000000000000000000000000000060138453, data: LogData { topics: [0xce4b4ad6891d716d0b1fba2b4aeb05ec20edadb01df512263d0dde423736bbb9, 0xf34b45f42cd251fb8af4be8f39cfe4bea1d12e428bc5ee14f9fc2d7efb5e51e1], data: 0x00000000000000000000000000000000000000000000000000000000001518d7 } }, block_hash: Some(0x67056e22373221812d1af20c9c424197a77723e2cff501d98de49dfd7c0135c6), block_number: Some(1380815), block_timestamp: None, transaction_hash: Some(0x26ecc34e69370239348a0bcd61228fb18a523e018864a3058d78712e30335298), transaction_index: Some(1), log_index: Some(0), removed: false }
[2025-09-22T15:18:34.253+0200 DEBUG golem_base_sdk::client] Created entity with ID: 0xf34b45f42cd251fb8af4be8f39cfe4bea1d12e428bc5ee14f9fc2d7efb5e51e1
[2025-09-22T15:18:34.253+0200 INFO ya_market::protocol::discovery] Created Offer entry in GolemBase with ID: 0xf34b45f42cd251fb8af4be8f39cfe4bea1d12e428bc5ee14f9fc2d7efb5e51e1