keripy icon indicating copy to clipboard operation
keripy copied to clipboard
Published 2 months ago • verified publisher icon WebOfTrust

Delegation does not succeed when delegator and delegate use different witnesses

Open lenkan opened this issue 8 months ago • 4 comments

Version

1.2.5-dev0 (main branch)

Environment

No response

Expected behavior

Delegation should resolve even when delegator and delegate use different sets of witnesses.

Actual behavior

Delegation does not resolve.

Steps to reproduce

  1. Create AID X, with witness A
  2. Create AID Y, with witness B, to use as a proxy for delegation
  3. Create delegated AID Z from AID X, using witness B
  4. Approve delegation from AID X.
  5. Notice that delegation never succeeds.

I have created a reproduction script here:

#!/bin/bash

# Creates a random suffix to avoid keystore name collisions
random_suffix=$(head /dev/urandom | tr -dc a-z0-9 | head -c4)
delegator="delegator_${random_suffix}"
delegate="delegate_${random_suffix}"

kli init --name "$delegator" --nopasscode
kli init --name "$delegate" --nopasscode

# Inlined witness resolution to make it easier to modify 
kli oobi resolve --name "$delegator" --oobi http://127.0.0.1:5642/oobi/BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha/controller
kli oobi resolve --name "$delegator" --oobi http://127.0.0.1:5643/oobi/BLskRTInXnMxWaGqcpSyMgo0nYbalW99cGZESrz3zapM/controller
kli oobi resolve --name "$delegator" --oobi http://127.0.0.1:5644/oobi/BIKKuvBwpmDVA4Ds-EpL5bt9OqPzWPja2LigFYZN2YfX/controller
kli oobi resolve --name "$delegate" --oobi http://127.0.0.1:5642/oobi/BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha/controller
kli oobi resolve --name "$delegate" --oobi http://127.0.0.1:5643/oobi/BLskRTInXnMxWaGqcpSyMgo0nYbalW99cGZESrz3zapM/controller
kli oobi resolve --name "$delegate" --oobi http://127.0.0.1:5644/oobi/BIKKuvBwpmDVA4Ds-EpL5bt9OqPzWPja2LigFYZN2YfX/controller

# Add the following line to make the test pass
# --wit BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha \
# It will make sure the delegator and delegate share at least one witness.
kli incept --name "$delegator" --alias delegator \
    --wit BLskRTInXnMxWaGqcpSyMgo0nYbalW99cGZESrz3zapM \
    --wit BIKKuvBwpmDVA4Ds-EpL5bt9OqPzWPja2LigFYZN2YfX \
    --toad 2 \
    --icount 1 \
    --ncount 1 \
    --isith 1 \
    --nsith 1 \
    --transferable

delegator_oobis=$(kli oobi generate --name "$delegator" --alias delegator --role witness)
delegator_aid=$(kli aid --name "$delegator" --alias delegator)
delegator_oobi=$(echo "${delegator_oobis}" | head -n 1)

kli oobi resolve --name "$delegate" --oobi-alias delegator --oobi "${delegator_oobi}"

kli incept --name "$delegate" --alias proxy \
    --wit BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha \
    --toad 1 \
    --icount 1 \
    --ncount 1 \
    --isith 1 \
    --nsith 1 \
    --transferable

kli incept --name "$delegate" --alias delegate \
    --wit BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha \
    --delpre "$delegator_aid" \
    --toad 1 \
    --icount 1 \
    --ncount 1 \
    --isith 1 \
    --nsith 1 \
    --proxy proxy \
    --transferable &
pid=$!

kli delegate confirm --name "$delegator" --alias delegator -Y

wait "$pid"

kli status --name "$delegate" --alias delegate

To run it:

git clone [email protected]:lenkan/keripy.git
cd keripy/
git checkout delegation-problem

Open up two terminals, start witness demo

rm -rf ~/.keri && kli witness demo

Run the script:

./scripts/demo/basic/delegate-wits.sh

lenkan avatar Apr 23 '25 13:04 lenkan

Here is the witness log output

2025-04-24 09:38:39 [keri] eventing.logEvent-3189 INFO     AID BPFo...Vy7f: Added to KEL icp at sn=0 valid event SAID=EMEa7V3p1RftbjOc165klR11sk8m4ZhIfJ67rzOR9UTF
2025-04-24 09:38:39 [keri] eventing.logEvent-3189 INFO     AID BPfK...ETZf: Added to KEL icp at sn=0 valid event SAID=EIJ7F-Z1k76GVymvoKWjkyJABnItK99DK4wSr54poF6O
2025-04-24 09:38:39 [keri] eventing.logEvent-3189 INFO     AID BLSl...579v: Added to KEL icp at sn=0 valid event SAID=EJMHzHpdDYGW496hJ-Yem5QJHtL42J_av2dg8vRY6RtR
2025-04-24 09:38:40 [keri] eventing.logEvent-3189 INFO     AID BC2Y...__eU: Added to KEL icp at sn=0 valid event SAID=EBFSfUzxXc6lp7Ys0lSCpz8ZEBZoLmmVx22dtUYDD0Bp
2025-04-24 09:38:40 [keri] eventing.logEvent-3189 INFO     AID BFAN...EGiF: Added to KEL icp at sn=0 valid event SAID=EMo85tLTYvOl4Vne8MjtNb5ECHM2zXqxP7Sfm_mp_ADD
2025-04-24 09:38:40 [keri] eventing.logEvent-3189 INFO     AID BEC-..._miM: Added to KEL icp at sn=0 valid event SAID=EJQAonl6z1Qpt5sGT2p6w636RRvYJesMFIiqceP12Hc5
2025-04-24 09:38:40 [keri] eventing.logEvent-3189 INFO     AID BBil...f2Ha: Added to KEL icp at sn=0 valid event SAID=EIkO4CUmYXukX4auGU9yaFoQaIicfVZkazQ0A3IO5biT
2025-04-24 09:38:40 [keri] eventing.logEvent-3189 INFO     AID BLsk...zapM: Added to KEL icp at sn=0 valid event SAID=EGuHrbhKDUC6umKP5buvRr6DLUorsQwnCZGZU0hEqsSP
2025-04-24 09:38:41 [keri] eventing.logEvent-3189 INFO     AID BIKK...2YfX: Added to KEL icp at sn=0 valid event SAID=EC5Yh_MaLtrkgFpg7PyVNkM7Fv6htQdZb1kGxThK6Ahi
2025-04-24 09:38:41 [keri] eventing.logEvent-3189 INFO     AID BM35...CorE: Added to KEL icp at sn=0 valid event SAID=EIGspjOvYwgoZkuu2ynls8d8x6RuJEYqIiqfc2l_DoHs
2025-04-24 09:38:41 [keri] eventing.logEvent-3189 INFO     AID BIj1...pYHP: Added to KEL icp at sn=0 valid event SAID=EGm5cP_LsBmVsUuLlCXhfIUgPnRZwFk4bddDfSHWYkXu
2025-04-24 09:38:41 [keri] eventing.logEvent-3189 INFO     AID BF2r...PfsM: Added to KEL icp at sn=0 valid event SAID=EBou0Po7cpqA9gEyvjHG8LsJ7zrbZVHOmX3KXYi4-_tW
2025-04-24 09:38:43 [keri] serving.serviceReqs-801 INFO     Parsed Request: GET /oobi/BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha/controller (1, 1)
2025-04-24 09:38:44 [keri] serving.serviceReqs-801 INFO     Parsed Request: GET /oobi/BLskRTInXnMxWaGqcpSyMgo0nYbalW99cGZESrz3zapM/controller (1, 1)
2025-04-24 09:38:45 [keri] serving.serviceReqs-801 INFO     Parsed Request: GET /oobi/BIKKuvBwpmDVA4Ds-EpL5bt9OqPzWPja2LigFYZN2YfX/controller (1, 1)
2025-04-24 09:38:45 [keri] serving.serviceReqs-801 INFO     Parsed Request: GET /oobi/BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha/controller (1, 1)
2025-04-24 09:38:46 [keri] serving.serviceReqs-801 INFO     Parsed Request: GET /oobi/BLskRTInXnMxWaGqcpSyMgo0nYbalW99cGZESrz3zapM/controller (1, 1)
2025-04-24 09:38:46 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:46 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=ENgCWa-AAeQ83PS-nNJ12lL5ysiTjndWUxFs04I13h-r
2025-04-24 09:38:46 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:46 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EFkBL-BDCrQXkUHwn0yQ7wu25A8_h7YpgoixJer27fp1
2025-04-24 09:38:47 [keri] serving.serviceReqs-801 INFO     Parsed Request: GET /oobi/BIKKuvBwpmDVA4Ds-EpL5bt9OqPzWPja2LigFYZN2YfX/controller (1, 1)
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL icp at sn=0 valid event SAID=ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL icp at sn=0 valid event SAID=ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL icp at sn=0 valid event SAID=ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL icp at sn=0 valid event SAID=ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:48 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:50 [keri] serving.serviceReqs-801 INFO     Parsed Request: GET /oobi/ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR/witness (1, 1)
2025-04-24 09:38:51 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:51 [keri] eventing.logEvent-3189 INFO     AID EOdn...HcAU: Added to KEL icp at sn=0 valid event SAID=EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU
2025-04-24 09:38:51 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:51 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:51 [keri] eventing.logEvent-3189 INFO     AID EOdn...HcAU: Added to KEL icp at sn=0 valid event SAID=EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EJV2hMZgKXFkTxZxI4v8HV_3mr_CVb4XrrQESoll8WA0
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=ECNTAM2LsYEqJ7wnERac-YOD2XvOkDhqmgiopgPnpUxy
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST /receipts (1, 1)
2025-04-24 09:38:52 [keri] eventing.logEvent-3189 INFO     AID EKu-...-ttj: Added to KEL dip at sn=0 valid event SAID=EKu-NlyOjDRBAkrps5xe6WjpjCcaEJ2n3pKWvrzq-ttj
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] parsing.msgParsator-1040 INFO     Missing attached signatures on receipt msg sn=0 SAID=EKu-NlyOjDRBAkrps5xe6WjpjCcaEJ2n3pKWvrzq-ttj
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] eventing.logEvent-3189 INFO     AID EOdn...HcAU: Added to KEL icp at sn=0 valid event SAID=EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU
2025-04-24 09:38:52 [keri] eventing.logEvent-3189 INFO     AID EOdn...HcAU: Added to KEL icp at sn=0 valid event SAID=EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] eventing.logEvent-3189 INFO     AID EOdn...HcAU: Added to KEL icp at sn=0 valid event SAID=EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU
2025-04-24 09:38:52 [keri] eventing.logEvent-3189 INFO     AID EOdn...HcAU: Added to KEL icp at sn=0 valid event SAID=EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:52 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:53 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=logs SAID=EDjT2cGhFlxHkzrHg9d8W4WobAl7ea64G54tyvvadmFa
2025-04-24 09:38:53 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:53 [keri] eventing.logEvent-3189 INFO     AID EOdn...HcAU: Added to KEL icp at sn=0 valid event SAID=EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU
2025-04-24 09:38:53 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:53 [keri] eventing.logEvent-3189 INFO     AID EOdn...HcAU: Added to KEL icp at sn=0 valid event SAID=EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU
2025-04-24 09:38:53 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:53 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:53 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL rot at sn=1 valid event SAID=EOegjN5RftZZKo7ii9V1H2MXwypHeHEgeE7cMe8VhfLK
2025-04-24 09:38:53 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:53 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL rot at sn=1 valid event SAID=EOegjN5RftZZKo7ii9V1H2MXwypHeHEgeE7cMe8VhfLK
2025-04-24 09:38:54 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:54 [keri] eventing.processQueryNotFound-6522 INFO     Kevery: QNF unescrow succeeded in valid event: key =  	digest = EDjT2cGhFlxHkzrHg9d8W4WobAl7ea64G54tyvvadmFa
2025-04-24 09:38:54 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:54 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:54 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EIHtE_U_PsEwkMCyfxN1l4AjGH4H69dSCD1fmbvkdICt
2025-04-24 09:38:54 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:54 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EMJo2d1EJGxChVxW-VhgI1xElNE2h_3FYzc0RK84x7id
2025-04-24 09:38:54 [keri] forwarding.deliverDo-91 ERROR    Error sending to EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU with ends={'witness': {'BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha': {}}}.  Err=unable to find a valid endpoint for witness BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha
2025-04-24 09:38:54 [keri] forwarding.deliverDo-91 ERROR    Error sending to EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU with ends={'witness': {'BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha': {}}}.  Err=unable to find a valid endpoint for witness BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha
2025-04-24 09:38:55 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:55 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL icp at sn=0 valid event SAID=ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR
2025-04-24 09:38:55 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:55 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL rot at sn=1 valid event SAID=EOegjN5RftZZKo7ii9V1H2MXwypHeHEgeE7cMe8VhfLK
2025-04-24 09:38:55 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:55 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL icp at sn=0 valid event SAID=ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR
2025-04-24 09:38:55 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:55 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=ECh6gUCjq0EPAUv80LUeefSDCLqRHcQCyhCQHyYcE-W8
2025-04-24 09:38:55 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:55 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:55 [keri] eventing.logEvent-3189 INFO     AID ENVa...aaaR: Added to KEL rot at sn=1 valid event SAID=EOegjN5RftZZKo7ii9V1H2MXwypHeHEgeE7cMe8VhfLK
2025-04-24 09:38:55 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=ELNx1bIxspQnjWToBC2PTtYOPjth6PWzaYuzM8jDitkt
2025-04-24 09:38:55 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:56 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:56 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EMbxgIKlCSX5NEtarlKpePQloV_889jQ6k5K5malFFtf
2025-04-24 09:38:56 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:38:56 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EEEycOaRqHwvrUDXjYTuSc3yotLqgwe-ihOcrbWi7JUO
2025-04-24 09:39:17 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:17 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EPrPZFe1R72pmjwqjiXRIFMnT0ZEUDJtRLi0U3ksFmTA
2025-04-24 09:39:17 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:17 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EKV_7J66NtJrIfE0cNOM_Bwkf2eAk59aSgXItzPWX5br
2025-04-24 09:39:23 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:23 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EJyS_MnKHig5IJYusQh5NWmZhy4IsfS4O5xxXp7uKaOZ
2025-04-24 09:39:23 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:23 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EJkHbmc7U1fQW6u2CPFkpl7ZhYcyWW7VpomqYs1H6Hi-
2025-04-24 09:39:23 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:23 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:23 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:23 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:25 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:25 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EE605JFTmOrvMEi7V-HI2cJNIW4TvsoEuHbCUw9dRA7_
2025-04-24 09:39:25 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:25 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EPpbMXuBdl4kiJBaNvJbNKvCM7mduDJ11DtZ4vkvYzXc
2025-04-24 09:39:26 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:26 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=ELbrXA6svVdjlZ-3xGQJV3MplwLbnmEGRidI2FmzH66b
2025-04-24 09:39:26 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:26 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EFQfXHsbL_5jVVQPt29hV17uzliqmPlJrXx11jasJ7W8
2025-04-24 09:39:27 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:27 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EIgM_tJOD7OPJSN5OQJfFetIFGaYjaSvAWhqSD9OKgXv
2025-04-24 09:39:27 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:27 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EHrfC_wWpumptd5Fo8vh97VayveGSTKiPwBFwBoem9sB
2025-04-24 09:39:49 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:49 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EIJufJQJkHsk2N9DB34dI-WjfZIRVJ4hI_-gta_oa3xD
2025-04-24 09:39:49 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:49 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EMPK-tBZLOrSH3D0OcFRoRt6utqBv-w33ymjGD_78Juy
2025-04-24 09:39:54 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:54 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EKLQIY_Sh45xK7I0JqHP-Qc1P1TsTvf8JeHWohIX4c8U
2025-04-24 09:39:54 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:54 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EGLGQRCdTeYBLR2jC72oOwwryKaJxRqJo6yOTU-OnnrU
2025-04-24 09:39:54 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:54 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:56 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:56 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:56 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EFBJzo-lVt_BaOs51mAbwF40dR3FTZe8DW99pMuvsJz3
2025-04-24 09:39:56 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EGfx0bCckztKR6KJ0hbYnSCBgAD6YAMHWR1pNovo09db
2025-04-24 09:39:57 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:57 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EOg3usICLrukBeZc_rQMQ0F4B2ehlx48eeDhriTYXbbx
2025-04-24 09:39:57 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:57 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EFgjgPdNB5HUcmNIweS0hyf-00MmP97-JBENeHOlHhR-
2025-04-24 09:39:58 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:58 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EH1S0vhStjqJS0cmnx1cEKvwUJtH83mu3GQY2PR5P98J
2025-04-24 09:39:58 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:39:58 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EI4Uq7I9hyGvABLFqBH2Fm1OGXIGtCnWBvoB0Tl7NIBd
2025-04-24 09:40:20 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:20 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:20 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EPrjEpe1XMZUV-w5sAxdS75sQ3uqzF_2D7sxUFhYf1Ua
2025-04-24 09:40:20 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EGNjSG1Xgx89sRDIJDDeIOSBtqEOW5qt3mo_GZvmE-Jz
2025-04-24 09:40:26 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:26 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EM6oHMJQkS28A4SVzxFp3Hhab4CUvP8qB5Ms7rRaM_FE
2025-04-24 09:40:26 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:26 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EIJ_wUbYulTiOjJpN6TBKcIFtW1mJnafDQfLhNsPqM6P
2025-04-24 09:40:26 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:26 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:27 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:27 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EEKFhAYVk0eOoLq39DC0eBtTs-tkLWAwCofSE50-ITHL
2025-04-24 09:40:27 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:27 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EDJLZlqH6ngEeqQzSY4om1EwUzk7CNlSWhvk7pso0MtD
2025-04-24 09:40:29 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:29 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:29 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EG5wF_Hc_w1pu1k_56mUgl7u6iVF8-RweCu53ZsSVaXY
2025-04-24 09:40:29 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=EFLs3hSUS_QN4pgtlNHrwC9jQUg_14ip-I-ddoujS-l3
2025-04-24 09:40:29 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:29 [keri] serving.serviceReqs-801 INFO     Parsed Request: POST / (1, 1)
2025-04-24 09:40:29 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=ENtth7eW5bg8L5kID1qdd5me5V_yUzkLP8vfxsGgvGNp
2025-04-24 09:40:29 [keri] parsing.msgParsator-1109 ERROR    Error processing query = Query not found error on event route=mbx SAID=ECGGCiSzuEzvSeI_ojaeKaIbX6_TK37_vZk27awaTv1l

The script output

KERI Keystore created at: /home/lenkan/.keri/ks/delegator_aecz
KERI Database created at: /home/lenkan/.keri/db/delegator_aecz
KERI Credential Store created at: /home/lenkan/.keri/reg/delegator_aecz
KERI Keystore created at: /home/lenkan/.keri/ks/delegate_aecz
KERI Database created at: /home/lenkan/.keri/db/delegate_aecz
KERI Credential Store created at: /home/lenkan/.keri/reg/delegate_aecz
http://127.0.0.1:5642/oobi/BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha/controller resolved
http://127.0.0.1:5643/oobi/BLskRTInXnMxWaGqcpSyMgo0nYbalW99cGZESrz3zapM/controller resolved
http://127.0.0.1:5644/oobi/BIKKuvBwpmDVA4Ds-EpL5bt9OqPzWPja2LigFYZN2YfX/controller resolved
http://127.0.0.1:5642/oobi/BBilc4-L3tFUnfM_wJr4S4OJanAv_VmF_dJNN6vkf2Ha/controller resolved
http://127.0.0.1:5643/oobi/BLskRTInXnMxWaGqcpSyMgo0nYbalW99cGZESrz3zapM/controller resolved
http://127.0.0.1:5644/oobi/BIKKuvBwpmDVA4Ds-EpL5bt9OqPzWPja2LigFYZN2YfX/controller resolved
Waiting for witness receipts...
Prefix  ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR
	Public key 1:  DErZZHCMcXRlnJWOpvftdcFromVDgeSe0tTq8LH-o7qD

http://127.0.0.1:5643/oobi/ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR/witness resolved
Waiting for witness receipts...
Prefix  EOdnqEAGar5dPrva6y49Kr5EIK2t_x1XYwea4htyHcAU
	Public key 1:  DBbx0c6BedkcXk-w0ILp9lN2_eYmvOaLjID-0KHPwqWc

Delegator Prefix  ENVafdEO5iL0Kgyc-O8WKHFiE-dxwsy27HIWZzS5aaaR
	Delegate EKu-NlyOjDRBAkrps5xe6WjpjCcaEJ2n3pKWvrzq-ttj inception Anchored at Seq. No.  1

lenkan avatar Apr 24 '25 08:04 lenkan

It seems like the solution is for the Delegator to resolve the Proxy AID OOBI before approving the delegation. I wonder if this should be automated? Otherwise it should be documented. I'd be happy to submit a PR with my test script to either complement or replace the existing delegation test script.

lenkan avatar Apr 28 '25 11:04 lenkan

From call today.

Expected sequence is:

  1. Create delegator AID
  2. Create proxy AID in delegatee keystore
  3. Resolve delegator oobi
  4. Resolve proxy oobi from delegator
  5. Create delegated AID

However, I would then expect a failure when step 4 is skipped and delegator and proxy use a shared witness.

lenkan avatar Apr 29 '25 14:04 lenkan

So, I think I have concluded steps needed for it to pass:

  • Proxy adds end role mailbox for their witness
  • Delegator resolve proxy oobi

lenkan avatar May 05 '25 10:05 lenkan