ussd-1                  | [D] cache:cache.go:165 Cache free	frame=5, key=authorize_account, size=0
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=4, key=validate_amount, size=4
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=4, key=get_recipient, size=10
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=4, key=get_sender, size=13
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=4, key=get_amount, size=11
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=4, key=reset_transaction_amount, size=0
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=4, key=max_amount, size=10
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=3, key=transaction_reset, size=0
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=3, key=validate_recipient, size=0
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=2, key=clear_temporary_value, size=0
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=2, key=manage_vouchers, size=0
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=2, key=check_balance, size=23
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=1, key=check_blocked_status, size=0
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=1, key=check_account_created, size=0
ussd-1                  | [D] cache:cache.go:165 Cache free	frame=1, key=check_account_status, size=0
ussd-1                  | [I] persist:persist.go:100 saving state and cache	self=persister @0xc00007e300 state:0xc000152880 cache:0xc0001421c0, key=+254729281232, state=state @0xc000152880 moves: 9794 idx: 0 flags: 0x065320809f000000000000000000000000 path:  lang: eng (English)
ussd-1                  | [D] atserver:parse.go:48 Received request:	{"body":"phoneNumber=%2B254710946401\u0026serviceCode=%2A384%2A96%23\u0026text=\u0026sessionId=ATUid_021cd8364cc7c84608861cb4e65423b0\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 '+254710946401', rootpath 'root', flagcount 128, language '' <nil> [] <nil> 0xc0000e40e0 false}
ussd-1                  | [D] engine:db.go:214 ca	ca=&{0 0 [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_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 @0xc00007e300 state:0xc000152a00 cache:0xc0001421c0, key=+254710946401, state=state @0xc000152a00 moves: 3887 idx: 0 flags: 0x065320809f000000000000000000000000 path:  lang: eng (English)
ussd-1                  | [I] vm:runner.go:63 vm created with state	state=state @0xc000152a00 moves: 3887 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 @0xc000152a00 moves: 3888 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 @0xc000152a00 moves: 3888 idx: 0 flags: 0x105320809f000000000000000000000000 path: _first lang: eng (English)
ussd-1                  | [D] ussdmenuhandler:menuhandler.go:152 handler has been initialized	state=state @0xc000152a00 moves: 3888 idx: 0 flags: 0x105320809f000000000000000000000000 path: _first lang: eng (English), cache=&{0 4294966298 [map[check_account_created: check_blocked_status:] 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_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:1 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=+254710946401
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 @0xc000152a00 moves: 3888 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 @0xc000152a00 moves: 3889 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 @0xc000152a00 moves: 3890 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 @0xc000152a00 moves: 3890 idx: 0 flags: 0x105320809f000000000000000000000000 path: root lang: eng (English)
ussd-1                  | [E] engine:db.go:533 fail VM run with state	code=, state=state @0xc000152a00 moves: 3890 idx: 0 flags: 0x105320809f000000000000000000000000 path: root lang: eng (English), vm=vm (0xc0001122a0) ok
ussd-1                  | [D] atserver:parse.go:48 Received request:	{"body":"phoneNumber=%2B254705136690\u0026serviceCode=%2A384%2A96%23\u0026text=\u0026sessionId=ATUid_2c96886321b573c6996e82bffb5b1133\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 '+254705136690', rootpath 'root', flagcount 128, language '' <nil> [] <nil> 0xc00044a000 false}
ussd-1                  | [D] engine:db.go:214 ca	ca=&{0 4294966298 [map[check_account_created: check_blocked_status:] 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_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:1 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 @0xc00007e300 state:0xc000152a00 cache:0xc0001421c0, key=+254705136690, state=state @0xc000152a00 moves: 2227 idx: 0 flags: 0x065312881f010000000000000000000000 path:  lang: eng (English)
ussd-1                  | [I] vm:runner.go:63 vm created with state	state=state @0xc000152a00 moves: 2227 idx: 0 flags: 0x065312881f010000000000000000000000 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 @0xc000152a00 moves: 2228 idx: 0 flags: 0x065312881f010000000000000000000000 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 @0xc000152a00 moves: 2228 idx: 0 flags: 0x105312881f010000000000000000000000 path: _first lang: eng (English)
ussd-1                  | [D] ussdmenuhandler:menuhandler.go:152 handler has been initialized	state=state @0xc000152a00 moves: 2228 idx: 0 flags: 0x105312881f010000000000000000000000 path: _first lang: eng (English), cache=&{0 4294966728 [map[check_account_created: check_blocked_status:] 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:1 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=+254705136690
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 @0xc000152a00 moves: 2228 idx: 0 flags: 0x105312881f010000000000000000000000 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 @0xc000152a00 moves: 2229 idx: 0 flags: 0x105312881f010000000000000000000000 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 @0xc000152a00 moves: 2230 idx: 0 flags: 0x105312881f010000000000000000000000 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 @0xc000152a00 moves: 2230 idx: 0 flags: 0x105312881f010000000000000000000000 path: root lang: eng (English)
ussd-1                  | [E] engine:db.go:533 fail VM run with state	code=, state=state @0xc000152a00 moves: 2230 idx: 0 flags: 0x105312881f010000000000000000000000 path: root lang: eng (English), vm=vm (0xc000112720) ok
ussd-1                  | [D] atserver:parse.go:48 Received request:	{"body":"channel=\u0026phoneNumber=%2B254705136690\u0026serviceCode=%2A384%2A96%23\u0026sessionId=DialogussSession__9478453382003990660\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 '+254705136690', rootpath 'root', flagcount 128, language '' <nil> [] <nil> 0xc00044a000 false}
ussd-1                  | [D] engine:db.go:214 ca	ca=&{0 4294966728 [map[check_account_created: check_blocked_status:] 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:1 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 @0xc00007e300 state:0xc000152a00 cache:0xc0001421c0, key=+254705136690, state=state @0xc000152a00 moves: 2227 idx: 0 flags: 0x065312881f010000000000000000000000 path:  lang: eng (English)
ussd-1                  | [I] vm:runner.go:63 vm created with state	state=state @0xc000152a00 moves: 2227 idx: 0 flags: 0x065312881f010000000000000000000000 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 @0xc000152a00 moves: 2228 idx: 0 flags: 0x065312881f010000000000000000000000 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 @0xc000152a00 moves: 2228 idx: 0 flags: 0x105312881f010000000000000000000000 path: _first lang: eng (English)
ussd-1                  | [D] ussdmenuhandler:menuhandler.go:152 handler has been initialized	state=state @0xc000152a00 moves: 2228 idx: 0 flags: 0x105312881f010000000000000000000000 path: _first lang: eng (English), cache=&{0 4294966728 [map[check_account_created: check_blocked_status:] 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:1 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=+254705136690
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 @0xc000152a00 moves: 2228 idx: 0 flags: 0x105312881f010000000000000000000000 path: _first lang: eng (English)