VALID USSD REQUEST ================== ussd-1 | [D] atserver:parse.go:48 Received request: {"body":"channel=\u0026phoneNumber=%2B254724942097\u0026serviceCode=%2A483%2A061%23\u0026sessionId=DialogussSession__5609292183699199632\u0026text="}=?? 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 '' [] 0xc00048c0e0 false} ussd-1 | [D] engine:db.go:214 ca ca=&{0 0 [map[]] map[authorize_account:6 check_account_created:2 check_blocked_status:1 check_identifier:0 check_pin_mismatch:6 check_transactions:0 check_vouchers:10 confirm_new_alias:0 confirm_pin_change: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 max_amount:40 request_custom_alias:0 reset_account_authorized:16 reset_allow_update:0 reset_incorrect:6 reset_incorrect_date_format:8 reset_invalid_pin:6 reset_others_pin:6 reset_transaction_amount: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_yob:32 set_back:6 set_default_voucher:8 set_voucher:12 transaction_reset:0 update_all_profile_items:0 validate_amount:64 validate_blocked_number:6 validate_recipient:50 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 @0xc00054c240 state:0xc00045ee00 cache:0xc000099dc0, key=+254724942097, state=state @0xc00045ee00 moves: 2671 idx: 0 flags: 0x065300889f010000000000000000000000 path: lang: eng (English) ussd-1 | [I] vm:runner.go:63 vm created with state state=state @0xc00045ee00 moves: 2671 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 @0xc00045ee00 moves: 2672 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 @0xc00045ee00 moves: 2672 idx: 0 flags: 0x105300889f010000000000000000000000 path: _first lang: eng (English) ussd-1 | [D] ussdmenuhandler:menuhandler.go:153 handler has been initialized state=state @0xc00045ee00 moves: 2672 idx: 0 flags: 0x105300889f010000000000000000000000 path: _first lang: eng (English), cache=&{0 4294966839 [map[] map[]] map[authorize_account:6 check_account_created:2 check_blocked_status:1 check_identifier:0 check_pin_mismatch:6 check_transactions:0 check_vouchers:10 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 max_amount:40 request_custom_alias:0 reset_account_authorized:0 reset_allow_update:0 reset_api_call_failure:6 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 @0xc00045ee00 moves: 2672 idx: 0 flags: 0x105300889f010000000000000000000000 path: _first lang: eng (English) ussd-1 | [D] vm:runner.go:438 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 @0xc00045ee00 moves: 2673 idx: 0 flags: 0x105300889f010000000000000000000000 path: lang: eng (English) ussd-1 | [D] vm:runner.go:363 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | 2025/05/26 12:49:38 URL: http://eth-custodial:5003/api/v2/account/status/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/26 12:49:38 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 @0xc00045ee00 moves: 2674 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | 2025/05/26 12:49:38 URL: http://eth-custodial:5003/api/v2/account/status/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/26 12:49:38 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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 @0xc00045ee00 moves: 2674 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=15636c6561725f74656d706f726172795f76616c75650102000415636c6561725f74656d706f726172795f76616c756500030f6d616e6167655f766f75636865727301a000040f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 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=15636c6561725f74656d706f726172795f76616c756500030f6d616e6167655f766f75636865727301a000040f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=0f6d616e6167655f766f75636865727301a000040f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | 2025/05/26 12:49:38 URL: http://ussd-data-service:5006/api/v1/holdings/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/26 12:49:38 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=0f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | 2025/05/26 12:49:38 URL: http://ussd-data-service:5006/api/v1/holdings/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/26 12:49:38 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=0b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=0d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] cache:cache.go:72 Cache add key=check_balance, size=33, limit=128 ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=0d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=5, op=MAP, code=0d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=10, op=MOUT, code=0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=10, op=MOUT, code=08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=10, op=MOUT, code=076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=10, op=MOUT, code=0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=10, op=MOUT, code=04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=7, op=HALT, code=00080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:438 found HALT, stopping ussd-1 | [D] engine:db.go:537 end VM run code=00080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a, state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x145300889f010000000000000000000000 path: root/main lang: eng (English), vm=vm (0xc000113b60) ok ussd-1 | [D] state:state.go:346 code changed (set) code=00080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [I] render:page.go:234 found sink sym=main, sink=check_account_status ussd-1 | [D] render:size.go:87 Added cursor offset=0 ussd-1 | [D] render:page.go:175 render for index=0 ussd-1 | [D] render:page.go:391 rendered template bytes=33 ussd-1 | [D] render:page.go:400 rendered menu bytes=52 ussd-1 | [D] render:menu.go:261 applypage m=pagecount: 2 menusink: false next: false prev: false, idx=0 ussd-1 | [D] render:menu.go:261 applypage m=pagecount: 2 menusink: false next: false prev: false, idx=1 ussd-1 | [D] render:page.go:361 calculated pre-navigation allocation bytes=74, menusizes=[0 0 0 0] ussd-1 | [D] render:page.go:175 render for index=0 ussd-1 | [D] render:page.go:391 rendered template bytes=33 ussd-1 | [D] render:page.go:400 rendered menu bytes=52 ussd-1 | [I] persist:persist.go:100 saving state and cache self=persister @0xc00054c240 state:0xc00045ee00 cache:0xc000099dc0, key=+254724942097, state=state @0xc00045ee00 moves: 2675 idx: 0 flags: 0x045300889f010000000000000000000000 path: root/main lang: eng (English) INVALID USSD REQUEST ==================== ussd-1 | [D] atserver:parse.go:48 Received request: {"body":"channel=\u0026phoneNumber=%2B254724942097\u0026serviceCode=%2A483%2A061%23\u0026sessionId=DialogussSession__2881809649885222734\u0026text="}=?? 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 '' [] 0xc0004e0000 false} ussd-1 | [D] engine:db.go:214 ca ca=&{0 4294963143 [map[check_balance:Salio: 5866.56 MEDENE ussd-1 | manage_vouchers:] map[check_account_created: check_account_status: check_blocked_status:] map[clear_temporary_value:]] map[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 invite_valid_recipient:0 request_custom_alias:0 reset_api_call_failure:6 reset_incorrect_date_format:8 reset_others_pin:6 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_yob:32 set_default_voucher:8 set_voucher:12 update_all_profile_items:0 validate_amount:64 verify_create_pin:8 verify_new_pin:2 verify_yob:6 view_statement:0] false} ussd-1 | [I] persist:persist.go:126 loaded state and cache self=persister @0xc0000b0040 state:0xc000696480 cache:0xc0000b0080, key=+254724942097, state=state @0xc000696480 moves: 2651 idx: 0 flags: 0x065300889f010000000000000000000000 path: lang: eng (English) ussd-1 | [I] vm:runner.go:63 vm created with state state=state @0xc000696480 moves: 2651 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 @0xc000696480 moves: 2652 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 @0xc000696480 moves: 2652 idx: 0 flags: 0x105300889f010000000000000000000000 path: _first lang: eng (English) ussd-1 | [D] ussdmenuhandler:menuhandler.go:153 handler has been initialized state=state @0xc000696480 moves: 2652 idx: 0 flags: 0x105300889f010000000000000000000000 path: _first lang: eng (English), cache=&{0 4294966839 [map[check_balance:Salio: 5866.56 MEDENE ussd-1 | manage_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 max_amount:40 request_custom_alias:0 reset_account_authorized:0 reset_allow_update:0 reset_api_call_failure:6 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 @0xc000696480 moves: 2652 idx: 0 flags: 0x105300889f010000000000000000000000 path: _first lang: eng (English) ussd-1 | [D] vm:runner.go:438 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 @0xc000696480 moves: 2653 idx: 0 flags: 0x105300889f010000000000000000000000 path: lang: eng (English) ussd-1 | [D] vm:runner.go:363 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | 2025/05/26 10:35:44 URL: http://eth-custodial:5003/api/v2/account/status/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/26 10:35: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 @0xc000696480 moves: 2654 idx: 0 flags: 0x105300889f010000000000000000000000 path: root lang: eng (English) ussd-1 | 2025/05/26 10:35:44 URL: http://eth-custodial:5003/api/v2/account/status/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/26 10:35: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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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 @0xc000696480 moves: 2654 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=15636c6561725f74656d706f726172795f76616c75650102000415636c6561725f74656d706f726172795f76616c756500030f6d616e6167655f766f75636865727301a000040f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000696480 moves: 2655 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=15636c6561725f74656d706f726172795f76616c756500030f6d616e6167655f766f75636865727301a000040f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000696480 moves: 2655 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:152 execute code opcode=3, op=LOAD, code=0f6d616e6167655f766f75636865727301a000040f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000696480 moves: 2655 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | [D] vm:runner.go:311 skip already loaded symbol symbol=manage_vouchers ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=0f6d616e6167655f766f75636865727300010b6170695f6661696c75726501190100030d636865636b5f62616c616e6365018000040d636865636b5f62616c616e636500050d636865636b5f62616c616e6365000a0473656e640131000a08766f7563686572730132000a076163636f756e740133000a0468656c700134000a04717569740139000700080473656e64013100080b6d795f766f756368657273013200080a6d795f6163636f756e74013300080468656c7001340008047175697401390008012e012a ussd-1 | [D] vm:runner.go:153 state=state @0xc000696480 moves: 2655 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English) ussd-1 | 2025/05/26 10:35:44 URL: http://ussd-data-service:5006/api/v1/holdings/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93 | Content-Type: application/json | Method: GET| Request Body: - ussd-1 | 2025/05/26 10:35:44 Received response for http://ussd-data-service:5006/api/v1/holdings/0xB474BD1b764b5ad21F0f6c2E96468e5E0d27Cd93: Status Code: 200 | Content-Type: application/json ussd-1 | [E] engine:db.go:533 fail VM run with state code=, state=state @0xc000696480 moves: 2655 idx: 0 flags: 0x105300889f010000000000000000000000 path: root/main lang: eng (English), vm=vm (0xc00014aae0) ok