+254724942097 logs ================== ussd-1 | [D] atserver:parse.go:48 Received request: {"body":"channel=\u0026phoneNumber=%2B254724942097\u0026serviceCode=%2A483%2A061%23\u0026sessionId=DialogussSession__252531369062818785\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 '' [] 0xc0000e40e0 false} ussd-1 | [D] engine:db.go:214 ca ca=&{0 4294966861 [map[check_account_created: check_balance:Balance: 101.97 5STAR ussd-1 | check_blocked_status: manage_vouchers:] map[]] map[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_current_profile_info:0 get_profile_info:0 get_suggested_alias:0 get_transactions:0 get_voucher_details:0 get_vouchers:0 invite_valid_recipient:0 request_custom_alias:0 reset_account_authorized:0 reset_allow_update:0 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: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 update_all_profile_items:0 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 @0xc000490100 state:0xc00063ef80 cache:0xc00007efc0, key=+254724942097, state=state @0xc00063ef80 moves: 1557 idx: 0 flags: 0x065322881f010000000000000000000000 path: lang: eng (English) ussd-1 | [I] vm:runner.go:63 vm created with state state=state @0xc00063ef80 moves: 1557 idx: 0 flags: 0x065322881f010000000000000000000000 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 @0xc00063ef80 moves: 1558 idx: 0 flags: 0x065322881f010000000000000000000000 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 @0xc00063ef80 moves: 1558 idx: 0 flags: 0x105322881f010000000000000000000000 path: _first lang: eng (English) ussd-1 | [D] ussdmenuhandler:menuhandler.go:152 handler has been initialized state=state @0xc00063ef80 moves: 1558 idx: 0 flags: 0x105322881f010000000000000000000000 path: _first lang: eng (English), cache=&{0 4294966839 [map[check_account_created: check_balance:Balance: 101.97 5STAR ussd-1 | check_blocked_status: manage_vouchers:] map[]] map[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_current_profile_info:0 get_profile_info:0 get_suggested_alias:0 get_transactions:0 get_voucher_details:0 get_vouchers:0 invite_valid_recipient:0 request_custom_alias:0 reset_account_authorized:16 reset_allow_update:0 reset_incorrect:0 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: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 update_all_profile_items:0 validate_blocked_number:6 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 @0xc00063ef80 moves: 1558 idx: 0 flags: 0x105322881f010000000000000000000000 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 @0xc00063ef80 moves: 1559 idx: 0 flags: 0x105322881f010000000000000000000000 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 @0xc00063ef80 moves: 1560 idx: 0 flags: 0x105322881f010000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:311 skip already loaded symbol symbol=check_blocked_status ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=14636865636b5f626c6f636b65645f737461747573000315636865636b5f6163636f756e745f637265617465640102000415636865636b5f6163636f756e745f6372656174656400010e73656c665f72657365745f70696e01290100010f626c6f636b65645f6163636f756e7401260100010f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc00063ef80 moves: 1560 idx: 0 flags: 0x105322881f010000000000000000000000 path: root lang: eng (English) ussd-1 | [E] engine:db.go:533 fail VM run with state code=, state=state @0xc00063ef80 moves: 1560 idx: 0 flags: 0x105322881f010000000000000000000000 path: root lang: eng (English), vm=vm (0xc0004e82a0) ok +254710726134 logs ================== ussd-1 | [D] atserver:parse.go:48 Received request: {"body":"phoneNumber=%2B254710726134\u0026serviceCode=%2A384%2A96%23\u0026text=\u0026sessionId=ATUid_f368c2d3906d9950fbc4595c3760b614\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 '+254710726134', rootpath 'root', flagcount 128, language '' [] 0xc00049e000 false} ussd-1 | [D] engine:db.go:214 ca ca=&{0 4294966839 [map[check_account_created: check_balance:Balance: 101.97 5STAR ussd-1 | check_blocked_status: manage_vouchers:] map[]] map[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_current_profile_info:0 get_profile_info:0 get_suggested_alias:0 get_transactions:0 get_voucher_details:0 get_vouchers:0 invite_valid_recipient:0 request_custom_alias:0 reset_account_authorized:16 reset_allow_update:0 reset_incorrect:0 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: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 update_all_profile_items:0 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 @0xc000490100 state:0xc00063ef80 cache:0xc00007efc0, key=+254710726134, state=state @0xc00063ef80 moves: 961 idx: 0 flags: 0x065320809f000000000000000000000000 path: lang: eng (English) ussd-1 | [I] vm:runner.go:63 vm created with state state=state @0xc00063ef80 moves: 961 idx: 0 flags: 0x065320809f000000000000000000000000 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 @0xc00063ef80 moves: 962 idx: 0 flags: 0x065320809f000000000000000000000000 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 @0xc00063ef80 moves: 962 idx: 0 flags: 0x105320809f000000000000000000000000 path: _first lang: eng (English) ussd-1 | [D] ussdmenuhandler:menuhandler.go:152 handler has been initialized state=state @0xc00063ef80 moves: 962 idx: 0 flags: 0x105320809f000000000000000000000000 path: _first lang: eng (English), cache=&{0 4294966861 [map[check_account_created: check_balance:Balance: 101.97 5STAR ussd-1 | check_blocked_status: manage_vouchers:] map[]] map[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_current_profile_info:0 get_profile_info:0 get_suggested_alias:0 get_transactions:0 get_voucher_details:0 get_vouchers:0 invite_valid_recipient:0 request_custom_alias:0 reset_account_authorized:0 reset_allow_update:0 reset_incorrect:0 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: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 update_all_profile_items:0 validate_blocked_number:6 verify_create_pin:8 verify_new_pin:2 verify_yob:6 view_statement:0 view_voucher:80] false}, x-SessionId=+254710726134 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 @0xc00063ef80 moves: 962 idx: 0 flags: 0x105320809f000000000000000000000000 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 @0xc00063ef80 moves: 963 idx: 0 flags: 0x105320809f000000000000000000000000 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 @0xc00063ef80 moves: 964 idx: 0 flags: 0x105320809f000000000000000000000000 path: root lang: eng (English) ussd-1 | [D] vm:runner.go:311 skip already loaded symbol symbol=check_blocked_status ussd-1 | [D] vm:runner.go:152 execute code opcode=4, op=RELOAD, code=14636865636b5f626c6f636b65645f737461747573000315636865636b5f6163636f756e745f637265617465640102000415636865636b5f6163636f756e745f6372656174656400010e73656c665f72657365745f70696e01290100010f626c6f636b65645f6163636f756e7401260100010f73656c6563745f6c616e67756167650108000001057465726d7301090000010a6372656174655f70696e010e00000314636865636b5f6163636f756e745f7374617475730100000414636865636b5f6163636f756e745f73746174757300010b6170695f6661696c75726501190100010f6163636f756e745f70656e64696e67010b010001046d61696e010c010007 ussd-1 | [D] vm:runner.go:153 state=state @0xc00063ef80 moves: 964 idx: 0 flags: 0x105320809f000000000000000000000000 path: root lang: eng (English) ussd-1 | [E] engine:db.go:533 fail VM run with state code=, state=state @0xc00063ef80 moves: 964 idx: 0 flags: 0x105320809f000000000000000000000000 path: root lang: eng (English), vm=vm (0xc00060e180) ok