ussd-1 | [D] atserver:parse.go:48 Received request: {"body":"phoneNumber=%2B254724942097\u0026serviceCode=%2A384%2A96%23\u0026text=\u0026sessionId=ATUid_41edc83c7a8ac7be89cccb7172b254f9\u0026networkCode=63902"}=?? ussd-1 | [I] visedriver.request:base.go:68 new request data={context.Background.WithValue(net/http context value http-server, *http.Server).WithValue(net/http context value local-addr, 172.18.0.10:7123).WithCancel.WithCancel sessionid '+254724942097', rootpath 'root', flagcount 128, language '' [] 0xc0004ae000 false} ussd-1 | [D] engine:db.go:214 ca ca=&{0 4294966750 [map[check_balance:Salio: 1132.91 MUUUO ussd-1 | check_vouchers:] map[check_account_created: check_account_status: check_blocked_status:] map[clear_temporary_value: manage_vouchers:]] map[authorize_account:6 check_account_created:2 check_account_status:0 check_blocked_status:1 check_identifier:0 check_pin_mismatch:6 check_transactions:0 check_vouchers:10 clear_temporary_value:2 confirm_new_alias:0 confirm_pin_change:0 create_account:0 fetch_community_balance:0 get_amount:32 get_current_profile_info:0 get_profile_info:0 get_recipient:0 get_sender:64 get_suggested_alias:0 get_transactions:0 get_voucher_details:0 get_vouchers:0 invite_valid_recipient:0 manage_vouchers:160 request_custom_alias:0 reset_account_authorized:0 reset_allow_update:0 reset_incorrect:6 reset_incorrect_date_format:8 reset_invalid_pin:6 reset_others_pin:6 reset_unregistered_number:0 retrieve_blocked_number:0 save_familyname:64 save_firstname:128 save_gender:16 save_location:16 save_offerings:8 save_others_temporary_pin:6 save_temporary_pin:6 save_yob:32 set_back:6 set_default_voucher:8 set_language:6 set_voucher:12 update_all_profile_items:0 validate_amount:64 validate_blocked_number:6 verify_create_pin:8 verify_new_pin:2 verify_yob:6 view_statement:0 view_voucher:80] false} ussd-1 | [I] persist:persist.go:126 loaded state and cache self=persister @0xc000099400 state:0xc000518d00 cache:0xc000462300, key=+254724942097, state=state @0xc000518d00 moves: 2271 idx: 0 flags: 0x065300889f010000000000000000000000 path: lang: eng (English) ussd-1 | [I] vm:runner.go:63 vm created with state state=state @0xc000518d00 moves: 2271 idx: 0 flags: 0x065300889f010000000000000000000000 path: lang: eng (English), renderer= ussd-1 | [D] engine:db.go:329 start pre-VM check ussd-1 | [I] vm:runner.go:63 vm created with state state=state @0xc000518d00 moves: 2272 idx: 0 flags: 0x065300889f010000000000000000000000 path: _first lang: eng (English), renderer= ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=065f666972737401000007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2272 idx: 0 flags: 0x105300889f010000000000000000000000 path: _first lang: eng (English) ussd-1 | [D] ussdmenuhandler:menuhandler.go:153 handler has been initialized state=state @0xc000518d00 moves: 2272 idx: 0 flags: 0x105300889f010000000000000000000000 path: _first lang: eng (English), cache=&{0 4294966839 [map[check_balance:Salio: 1132.91 MUUUO ussd-1 | check_vouchers:] map[]] map[authorize_account:6 check_account_created:2 check_account_status:0 check_blocked_status:1 check_identifier:0 check_pin_mismatch:6 check_transactions:0 check_vouchers:10 clear_temporary_value:2 confirm_new_alias:0 confirm_pin_change:0 create_account:0 fetch_community_balance:0 get_amount:32 get_current_profile_info:0 get_profile_info:0 get_recipient:0 get_sender:64 get_suggested_alias:0 get_transactions:0 get_voucher_details:0 get_vouchers:0 invite_valid_recipient:0 manage_vouchers:160 max_amount:40 request_custom_alias:0 reset_account_authorized:0 reset_allow_update:0 reset_incorrect:6 reset_incorrect_date_format:8 reset_invalid_pin:6 reset_others_pin:6 reset_transaction_amount:0 reset_unregistered_number:0 retrieve_blocked_number:0 save_familyname:64 save_firstname:128 save_gender:32 save_location:16 save_offerings:8 save_others_temporary_pin:6 save_temporary_pin:6 save_yob:32 set_back:6 set_default_voucher:8 set_language:6 set_voucher:12 transaction_reset:0 update_all_profile_items:0 validate_amount:64 validate_blocked_number:6 validate_recipient:50 verify_create_pin:8 verify_new_pin:2 verify_yob:6 view_statement:0 view_voucher:80] false}, x-SessionId=+254724942097 ussd-1 | [D] cache:cache.go:72 Cache add key=_first, size=0, limit=0 ussd-1 | [D] vm:runner.go:152 execute code opcode=7, op=HALT, code= ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2272 idx: 0 flags: 0x105300889f010000000000000000000000 path: _first lang: eng (English) ussd-1 | [D] vm:runner.go:440 found HALT, stopping ussd-1 | [D] engine:db.go:361 end pre-VM check ussd-1 | [D] cache:cache.go:165 Cache free frame=1, key=_first, size=0 ussd-1 | [D] state:state.go:346 code changed (set) code=000604726f6f74 ussd-1 | [I] engine:db.go:521 new VM execution with input input= ussd-1 | [D] engine:db.go:530 start VM run code=000604726f6f74 ussd-1 | [D] vm:runner.go:152 execute code opcode=6, op=MOVE, code=04726f6f74 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2273 idx: 0 flags: 0x105300889f010000000000000000000000 path: lang: eng (English) ussd-1 | [D] vm:runner.go:365 loaded code sym=root, code=000314636865636b5f626c6f636b65645f7374617475730101000414636865636b5f626c6f636b65645f737461747573000315636865636b5f6163636f756e745f637265617465640102000415636865636b5f6163636f756e745f6372656174656400010e73656c665f72657365745f70696e01290100010f626c6f636b65645f6163636f756e7401260100010f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=14636865636b5f626c6f636b65645f7374617475730101000414636865636b5f626c6f636b65645f737461747573000315636865636b5f6163636f756e745f637265617465640102000415636865636b5f6163636f756e745f6372656174656400010e73656c665f72657365745f70696e01290100010f626c6f636b65645f6163636f756e7401260100010f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] cache:cache.go:72 Cache add key=check_blocked_status, size=0, limit=1 ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=14636865636b5f626c6f636b65645f737461747573000315636865636b5f6163636f756e745f637265617465640102000415636865636b5f6163636f756e745f6372656174656400010e73656c665f72657365745f70696e01290100010f626c6f636b65645f6163636f756e7401260100010f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=15636865636b5f6163636f756e745f637265617465640102000415636865636b5f6163636f756e745f6372656174656400010e73656c665f72657365745f70696e01290100010f626c6f636b65645f6163636f756e7401260100010f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] cache:cache.go:72 Cache add key=check_account_created, size=0, limit=2 ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=15636865636b5f6163636f756e745f6372656174656400010e73656c665f72657365745f70696e01290100010f626c6f636b65645f6163636f756e7401260100010f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=1, op=CATCH, code=0e73656c665f72657365745f70696e01290100010f626c6f636b65645f6163636f756e7401260100010f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=1, op=CATCH, code=0f626c6f636b65645f6163636f756e7401260100010f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=1, op=CATCH, code=0f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=1, op=CATCH, code=057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=1, op=CATCH, code=0a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=14636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | 2025/05/14 23:10:44 URL: http://eth-custodial:5003/api/v2/account/status/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/14 23:10:44 Received response for http://eth-custodial:5003/api/v2/account/status/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93: Status Code: 200 | Content-Type: application/json ussd-1 | [D] cache:cache.go:72 Cache add key=check_account_status, size=0, limit=0 ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=14636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | 2025/05/14 23:10:44 URL: http://eth-custodial:5003/api/v2/account/status/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/14 23:10:44 Received response for http://eth-custodial:5003/api/v2/account/status/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93: Status Code: 200 | Content-Type: application/json ussd-1 | [D] vm:runner.go:152 execute code opcode=1, op=CATCH, code=0b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=1, op=CATCH, code=0f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=1, op=CATCH, code=046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2274 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | [I] vm:runner.go:276 catch! flag=12, sym=main, target=main, mode=true ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=15636c6561725f74656d706f726172795f76616c75650102000415636c6561725f74656d706f726172795f76616c756500030f6d616e6167655f766f75636865727301a000040f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500010b6170695f6661696c75726501190100050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2275 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] cache:cache.go:72 Cache add key=clear_temporary_value, size=0, limit=2 ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=15636c6561725f74656d706f726172795f76616c756500030f6d616e6167655f766f75636865727301a000040f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500010b6170695f6661696c75726501190100050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2275 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=0f6d616e6167655f766f75636865727301a000040f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500010b6170695f6661696c75726501190100050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2275 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | 2025/05/14 23:10:44 URL: http://ussd-data-service:5006/api/v1/holdings/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/14 23:10:44 Received response for http://ussd-data-service:5006/api/v1/holdings/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93: Status Code: 200 | Content-Type: application/json ussd-1 | [D] cache:cache.go:72 Cache add key=manage_vouchers, size=0, limit=160 ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=0f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500010b6170695f6661696c75726501190100050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2275 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | 2025/05/14 23:10:44 URL: http://ussd-data-service:5006/api/v1/holdings/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/14 23:10:44 Received response for http://ussd-data-service:5006/api/v1/holdings/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93: Status Code: 200 | Content-Type: application/json ussd-1 | [D] vm:runner.go:152 execute code opcode=1, op=CATCH, code=0b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500010b6170695f6661696c75726501190100050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2275 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=0d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500010b6170695f6661696c75726501190100050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2275 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:311 skip already loaded symbol symbol=check_balance ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=0d636865636b5f62616c616e636500010b6170695f6661696c75726501190100050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000518d00 moves: 2275 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [E] engine:db.go:533 fail VM run with state code=, state=state @0xc000518d00 moves: 2275 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English), vm=vm (0xc000792720) ok