Tracker Store only saving user's first message

So the first message to my bot always initiates a custom action which starts a form. In the tracker store only the first message is logged from the user, after that anything sent by the user in the middle of a form or afterwards is not saved to the tracker store on MongoDB. It is also strange because the latest_message attribute in the tracker is updated with the user’s message during the conversation. Is it required by me to send UserUttered events in the custom actions to update the tracker?

No need to send a UserUttered event. Can you please paste the log output of your bot here? Use the --debug option to enable debug mode. Also how does your custom action look like? Would be great if you could share more information about your bot so that we can help. Thanks.

The log output looks normal from what I can see:

/conversations/<conversation_id>/messages          POST                           add_message
/conversations/<conversation_id>/tracker/events    POST                           append_events
/webhooks/rasa                                     GET                            custom_webhook_RasaChatInput.health
/webhooks/rasa/webhook                             POST                           custom_webhook_RasaChatInput.receive
/webhooks/rest                                     GET                            custom_webhook_RestInput.health
/webhooks/rest/webhook                             POST                           custom_webhook_RestInput.receive
/model/test/intents                                POST                           evaluate_intents
/model/test/stories                                POST                           evaluate_stories
/conversations/<conversation_id>/execute           POST                           execute_action
/domain                                            GET                            get_domain
/                                                  GET                            hello
/model                                             PUT                            load_model
/model/parse                                       POST                           parse
/conversations/<conversation_id>/predict           POST                           predict
/conversations/<conversation_id>/tracker/events    PUT                            replace_events
/conversations/<conversation_id>/story             GET                            retrieve_story
/conversations/<conversation_id>/tracker           GET                            retrieve_tracker
/status                                            GET                            status
/model/predict                                     POST                           tracker_predict
/model/train                                       POST                           train
/model                                             DELETE                         unload_model
/version                                           GET                            version
2020-01-21 09:03:22 INFO     root  - Starting Rasa server on http://localhost:5005
2020-01-21 09:03:22 DEBUG    rasa.core.utils  - Using the default number of Sanic workers (1).
2020-01-21 09:03:22 INFO     root  - Enabling coroutine debugging. Loop id 94165292133768.
2020-01-21 09:03:23 DEBUG    rasa.model  - Extracted model to '/tmp/tmp_ptz3cg0'.
2020-01-21 09:03:27.071896: E tensorflow/stream_executor/cuda/cuda_driver.cc:318] failed call to cuInit: UNKNOWN ERROR (303)
2020-01-21 09:03:27 DEBUG    rasa.core.tracker_store  - Connected to MongoTrackerStore.
2020-01-21 09:03:27 DEBUG    rasa.core.lock_store  - Connected to lock store 'InMemoryLockStore'.
2020-01-21 09:03:28 DEBUG    rasa.model  - Extracted model to '/tmp/tmp03n7xsul'.
2020-01-21 09:03:28 DEBUG    pykwalify.compat  - Using yaml library: /build/lib/python3.6/site-packages/ruamel/yaml/__init__.py
2020-01-21 09:03:29 DEBUG    rasa.core.nlg.generator  - Instantiated NLG to 'TemplatedNaturalLanguageGenerator'.

I’ve attached the initial form that becomes active first and the ExtendedForm file that changes a few definitions of the FormAction class.

Any help is appreciated.

extended_form.py (6.6 KB) form_initial_details.py (5.4 KB)

Apologies, this is the proper log output:

2020-01-21 09:15:18 DEBUG    rasa.cli.utils  - Parameter 'credentials' not set. Using default location 'credentials.yml' instead.
2020-01-21 09:15:18 DEBUG    rasa.core.utils  - Available web server routes: 
/conversations/<conversation_id>/messages          POST                           add_message
/conversations/<conversation_id>/tracker/events    POST                           append_events
/webhooks/rasa                                     GET                            custom_webhook_RasaChatInput.health
/webhooks/rasa/webhook                             POST                           custom_webhook_RasaChatInput.receive
/webhooks/rest                                     GET                            custom_webhook_RestInput.health
/webhooks/rest/webhook                             POST                           custom_webhook_RestInput.receive
/model/test/intents                                POST                           evaluate_intents
/model/test/stories                                POST                           evaluate_stories
/conversations/<conversation_id>/execute           POST                           execute_action
/domain                                            GET                            get_domain
/                                                  GET                            hello
/model                                             PUT                            load_model
/model/parse                                       POST                           parse
/conversations/<conversation_id>/predict           POST                           predict
/conversations/<conversation_id>/tracker/events    PUT                            replace_events
/conversations/<conversation_id>/story             GET                            retrieve_story
/conversations/<conversation_id>/tracker           GET                            retrieve_tracker
/status                                            GET                            status
/model/predict                                     POST                           tracker_predict
/model/train                                       POST                           train
/model                                             DELETE                         unload_model
/version                                           GET                            version
2020-01-21 09:15:18 INFO     root  - Starting Rasa server on http://localhost:5005
2020-01-21 09:15:18 DEBUG    rasa.core.utils  - Using the default number of Sanic workers (1).
2020-01-21 09:15:18 INFO     root  - Enabling coroutine debugging. Loop id 94462935046744.
2020-01-21 09:15:19 DEBUG    rasa.model  - Extracted model to '/tmp/tmpciqxudyt'.
2020-01-21 09:15:22.848399: E tensorflow/stream_executor/cuda/cuda_driver.cc:318] failed call to cuInit: UNKNOWN ERROR (303)
2020-01-21 09:15:23 DEBUG    rasa.core.tracker_store  - Connected to MongoTrackerStore.
2020-01-21 09:15:23 DEBUG    rasa.core.lock_store  - Connected to lock store 'InMemoryLockStore'.
2020-01-21 09:15:23 DEBUG    rasa.model  - Extracted model to '/tmp/tmpfn0o7zpt'.
2020-01-21 09:15:23 DEBUG    pykwalify.compat  - Using yaml library: /build/lib/python3.6/site-packages/ruamel/yaml/__init__.py
2020-01-21 09:15:24 DEBUG    rasa.core.nlg.generator  - Instantiated NLG to 'TemplatedNaturalLanguageGenerator'.
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Starting a new session for conversation ID 'a62350c6-091c-4007-8485-23dd7d8627fd-2020-01-21 09:15:45.536197'.
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Action 'action_session_start' ended with events '[<rasa.core.events.SessionStarted object at 0x7f4a380efcf8>, <rasa.core.events.ActionExecuted object at 0x7f4a380efd30>]'.
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: None
	calendar: None
	company: None
	email: None
	first_name: None
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: False
	last_name: None
	mailchimp: None
	meeting: None
	name: None
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: None
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Received user message '/initiate_conversation{"bot": "maverick_labs"}' with intent '{'name': 'initiate_conversation', 'confidence': 1.0}' and entities '[{'entity': 'bot', 'start': 22, 'end': 46, 'value': 'maverick_labs'}]'
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	email: None
	first_name: None
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: False
	last_name: None
	mailchimp: None
	meeting: None
	name: None
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: None
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Logged UserUtterance - tracker now has 5 events.
2020-01-21 09:15:45 DEBUG    rasa.core.policies.form_policy  - There is no active form
2020-01-21 09:15:45 DEBUG    rasa.core.policies.memoization  - Current tracker state [None, None, None, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'slot_form_two_done_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'intent_initiate_conversation': 1.0, 'prev_action_listen': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}]
2020-01-21 09:15:45 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2020-01-21 09:15:45 DEBUG    rasa.core.policies.fallback  - NLU confidence threshold met, confidence of fallback action set to core threshold (0.2).
2020-01-21 09:15:45 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_4_EmbeddingPolicy
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Predicted next action 'action_introduction' with confidence 0.97.
2020-01-21 09:15:45 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'action_introduction'.
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Action 'action_introduction' ended with events '[BotUttered('Hi there, my name's Adam. I'm a smart, friendly robot here to help you with our Website Technical Maintenance & Support Programme.', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {}, 1579598145.8323843), BotUttered('By chatting to me you agree to our privacy policy: https://www.mavericklabs.ie/privacy-policy/', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {}, 1579598145.8323967), <rasa.core.events.FollowupAction object at 0x7f4a38086be0>]'.
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	email: None
	first_name: None
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: False
	last_name: None
	mailchimp: None
	meeting: None
	name: None
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: None
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Predicted next action 'form_initial_details' with confidence 1.00.
2020-01-21 09:15:45 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'form_initial_details'.
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Action 'form_initial_details' ended with events '[BotUttered('May I have your full name?', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {}, 1579598145.8596785), <rasa.core.events.Form object at 0x7f4a142fb5f8>, <rasa.core.events.SlotSet object at 0x7f4a142fb470>]'.
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	email: None
	first_name: None
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: False
	last_name: None
	mailchimp: None
	meeting: None
	name: None
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: name
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 09:15:45 DEBUG    rasa.core.policies.form_policy  - There is an active form 'form_initial_details'
2020-01-21 09:15:45 DEBUG    rasa.core.policies.memoization  - Current tracker state [None, None, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'slot_form_two_done_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'intent_initiate_conversation': 1.0, 'prev_action_listen': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'prev_action_introduction': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}]
2020-01-21 09:15:45 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2020-01-21 09:15:45 DEBUG    rasa.core.policies.mapping_policy  - There is no mapped action for the predicted intent, 'initiate_conversation'.
2020-01-21 09:15:45 DEBUG    rasa.core.policies.fallback  - NLU confidence threshold met, confidence of fallback action set to core threshold (0.2).
2020-01-21 09:15:45 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_0_FormPolicy
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Predicted next action 'action_listen' with confidence 1.00.
2020-01-21 09:15:45 DEBUG    rasa.core.processor  - Action 'action_listen' ended with events '[]'.
2020-01-21 09:15:45 DEBUG    rasa.core.lock_store  - Deleted lock for conversation 'a62350c6-091c-4007-8485-23dd7d8627fd-2020-01-21 09:15:45.536197'.
2020-01-21 09:15:49 DEBUG    rasa.core.processor  - Received user message 'Shay van Dam' with intent '{'name': 'enter_data', 'confidence': 0.9968634843826294}' and entities '[{'start': 0, 'end': 12, 'value': 'Shay van Dam', 'entity': 'name', 'confidence': 0.5892810155035868, 'extractor': 'CRFEntityExtractor'}]'
/build/lib/python3.6/site-packages/rasa/core/processor.py:375: UserWarning: Interpreter parsed an entity 'name' that is not defined in the domain.
  f"Interpreter parsed an entity '{entity}' "
2020-01-21 09:15:49 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	email: None
	first_name: None
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: False
	last_name: None
	mailchimp: None
	meeting: None
	name: None
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: name
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 09:15:49 DEBUG    rasa.core.processor  - Logged UserUtterance - tracker now has 14 events.
2020-01-21 09:15:49 DEBUG    rasa.core.policies.form_policy  - There is an active form 'form_initial_details'
2020-01-21 09:15:49 DEBUG    rasa.core.policies.memoization  - Current tracker state [None, None, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'slot_form_two_done_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'intent_initiate_conversation': 1.0, 'prev_action_listen': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'prev_action_introduction': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}]
2020-01-21 09:15:49 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2020-01-21 09:15:49 DEBUG    rasa.core.policies.fallback  - NLU confidence threshold met, confidence of fallback action set to core threshold (0.2).
2020-01-21 09:15:49 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_0_FormPolicy
2020-01-21 09:15:49 DEBUG    rasa.core.processor  - Predicted next action 'form_initial_details' with confidence 1.00.
2020-01-21 09:15:49 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'form_initial_details'.
2020-01-21 09:15:49 DEBUG    rasa.core.processor  - Action 'form_initial_details' ended with events '[BotUttered('And what is your email address?', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {}, 1579598149.7873523), <rasa.core.events.SlotSet object at 0x7f4a142c7c88>, <rasa.core.events.SlotSet object at 0x7f4a142c7cf8>, <rasa.core.events.SlotSet object at 0x7f4a142c7f28>, <rasa.core.events.SlotSet object at 0x7f4a142c79e8>]'.
/build/lib/python3.6/site-packages/rasa/core/processor.py:610: UserWarning: Action 'form_initial_details' set a slot type 'first_name' that it never set during the training. This can throw of the prediction. Make sure to include training examples in your stories for the different types of slots this action can return. Remember: you need to set the slots manually in the stories by adding '- slot{"first_name": Shay}' after the action.
  f"Action '{action_name}' set a slot type '{e.key}' that "
/build/lib/python3.6/site-packages/rasa/core/processor.py:610: UserWarning: Action 'form_initial_details' set a slot type 'last_name' that it never set during the training. This can throw of the prediction. Make sure to include training examples in your stories for the different types of slots this action can return. Remember: you need to set the slots manually in the stories by adding '- slot{"last_name": van Dam }' after the action.
  f"Action '{action_name}' set a slot type '{e.key}' that "
2020-01-21 09:15:49 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	email: None
	first_name: Shay
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: False
	last_name: van Dam 
	mailchimp: None
	meeting: None
	name: Shay van Dam
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: email
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 09:15:49 DEBUG    rasa.core.policies.form_policy  - There is an active form 'form_initial_details'
2020-01-21 09:15:49 DEBUG    rasa.core.policies.memoization  - Current tracker state [None, None, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'slot_form_two_done_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'intent_initiate_conversation': 1.0, 'prev_action_listen': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'prev_action_introduction': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}]
2020-01-21 09:15:49 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2020-01-21 09:15:49 DEBUG    rasa.core.policies.mapping_policy  - There is no mapped action for the predicted intent, 'enter_data'.
2020-01-21 09:15:49 DEBUG    rasa.core.policies.fallback  - NLU confidence threshold met, confidence of fallback action set to core threshold (0.2).
2020-01-21 09:15:49 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_0_FormPolicy
2020-01-21 09:15:49 DEBUG    rasa.core.processor  - Predicted next action 'action_listen' with confidence 1.00.
2020-01-21 09:15:49 DEBUG    rasa.core.processor  - Action 'action_listen' ended with events '[]'.
2020-01-21 09:15:49 DEBUG    rasa.core.lock_store  - Deleted lock for conversation 'a62350c6-091c-4007-8485-23dd7d8627fd-2020-01-21 09:15:45.536197'.
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Received user message 'shay@example.com' with intent '{'name': 'enter_data', 'confidence': 0.9847762584686279}' and entities '[{'start': 0, 'end': 16, 'value': 'shay@example.com', 'entity': 'url', 'confidence': 0.34448567352502807, 'extractor': 'CRFEntityExtractor'}]'
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	email: None
	first_name: Shay
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: False
	last_name: van Dam 
	mailchimp: None
	meeting: None
	name: Shay van Dam
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: email
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Logged UserUtterance - tracker now has 21 events.
2020-01-21 09:15:54 DEBUG    rasa.core.policies.form_policy  - There is an active form 'form_initial_details'
2020-01-21 09:15:54 DEBUG    rasa.core.policies.memoization  - Current tracker state [None, None, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'slot_form_two_done_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'intent_initiate_conversation': 1.0, 'prev_action_listen': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}, {'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'prev_action_introduction': 1.0, 'slot_benefits_0': 1.0, 'slot_introduction_complete_0': 1.0}]
2020-01-21 09:15:54 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2020-01-21 09:15:54 DEBUG    rasa.core.policies.fallback  - NLU confidence threshold met, confidence of fallback action set to core threshold (0.2).
2020-01-21 09:15:54 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_0_FormPolicy
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Predicted next action 'form_initial_details' with confidence 1.00.
2020-01-21 09:15:54 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'form_initial_details'.
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Action 'form_initial_details' ended with events '[BotUttered('Thank you!', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {}, 1579598154.8187876), BotUttered('It's a pleasure to meet you Shay!', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {}, 1579598154.8193157), <rasa.core.events.SlotSet object at 0x7f4a142fb240>, <rasa.core.events.SlotSet object at 0x7f4a142274a8>, <rasa.core.events.FollowupAction object at 0x7f4a142c7e48>, <rasa.core.events.SlotSet object at 0x7f4a1424feb8>, <rasa.core.events.Form object at 0x7f4a1424fd68>, <rasa.core.events.SlotSet object at 0x7f4a1424fba8>]'.
/build/lib/python3.6/site-packages/rasa/core/processor.py:610: UserWarning: Action 'form_initial_details' set a slot type 'mailchimp' that it never set during the training. This can throw of the prediction. Make sure to include training examples in your stories for the different types of slots this action can return. Remember: you need to set the slots manually in the stories by adding '- slot{"mailchimp": False}' after the action.
  f"Action '{action_name}' set a slot type '{e.key}' that "
/build/lib/python3.6/site-packages/rasa/core/processor.py:610: UserWarning: Action 'form_initial_details' set a slot type 'introduction_complete' that it never set during the training. This can throw of the prediction. Make sure to include training examples in your stories for the different types of slots this action can return. Remember: you need to set the slots manually in the stories by adding '- slot{"introduction_complete": True}' after the action.
  f"Action '{action_name}' set a slot type '{e.key}' that "
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	email: shay@example.com
	first_name: Shay
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: True
	last_name: van Dam 
	mailchimp: False
	meeting: None
	name: Shay van Dam
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: None
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Predicted next action 'action_ask_next' with confidence 1.00.
2020-01-21 09:15:54 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'action_ask_next'.
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Action 'action_ask_next' ended with events '[BotUttered('So you'd like to keep your website protected, is that right?', {"elements": null, "quick_replies": null, "buttons": [{"payload": "/affirm", "title": "That's right!"}, {"payload": "/deny", "title": "Heck no"}], "attachment": null, "image": null, "custom": null}, {}, 1579598154.8473916), <rasa.core.events.FollowupAction object at 0x7f4a142fbcf8>]'.
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	email: shay@example.com
	first_name: Shay
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: True
	last_name: van Dam 
	mailchimp: False
	meeting: None
	name: Shay van Dam
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: None
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Predicted next action 'action_listen' with confidence 1.00.
2020-01-21 09:15:54 DEBUG    rasa.core.processor  - Action 'action_listen' ended with events '[]'.
2020-01-21 09:15:54 DEBUG    rasa.core.lock_store  - Deleted lock for conversation 'a62350c6-091c-4007-8485-23dd7d8627fd-2020-01-21 09:15:45.536197'.

Would I be correct in saying that the user’s messages aren’t saved to the Tracker Store when the FormPolicy is active? If so is there a way to change this?

According to the logs, its seems like that the user’s message is saved, e.g. Logged UserUtterance - tracker now has 14 events.. Why do you think the message is not stored? It seems like the bot behaves as expected.

Note: You got a couple of warnings in the logs. You might want to solve them.

Because this is what’s stored in the MongoDB Tracker store after the first form:

 "events": [
        {
            "event": "action",
            "timestamp": 1579603727.8676612,
            "name": "action_session_start",
            "policy": null,
            "confidence": null
        },
        {
            "event": "session_started",
            "timestamp": 1579603727.8676703
        },
        {
            "event": "action",
            "timestamp": 1579603727.8676941,
            "name": "action_listen",
            "policy": null,
            "confidence": null
        },
        {
            "event": "user",
            "timestamp": 1579603727.8696961,
            "text": "/initiate_conversation{\"bot\": \"maverick_labs\"}",
            "parse_data": {
                "text": "/initiate_conversation{\"bot\": \"maverick_labs\"}",
                "intent": {
                    "name": "initiate_conversation",
                    "confidence": 1
                },
                "intent_ranking": [
                    {
                        "name": "initiate_conversation",
                        "confidence": 1
                    }
                ],
                "entities": [
                    {
                        "entity": "bot",
                        "start": 22,
                        "end": 46,
                        "value": "maverick_labs"
                    }
                ]
            },
            "input_channel": "rest",
            "message_id": "1c5f44957fbe4820875505d55551a4d5",
            "metadata": {}
        },
        {
            "event": "slot",
            "timestamp": 1579603727.8697143,
            "name": "bot",
            "value": "maverick_labs"
        },
        {
            "event": "action",
            "timestamp": 1579603728.140582,
            "name": "action_introduction",
            "policy": "policy_4_EmbeddingPolicy",
            "confidence": 0.8784152865409851
        },
        {
            "event": "bot",
            "timestamp": 1579603728.1405916,
            "text": "Hi there, my name's Adam. I'm a smart, friendly robot here to help you with our Website Technical Maintenance & Support Programme.",
            "data": {
                "elements": null,
                "quick_replies": null,
                "buttons": null,
                "attachment": null,
                "image": null,
                "custom": null
            },
            "metadata": {}
        },
        {
            "event": "bot",
            "timestamp": 1579603728.1405954,
            "text": "By chatting to me you agree to our privacy policy: https://www.mavericklabs.ie/privacy-policy/",
            "data": {
                "elements": null,
                "quick_replies": null,
                "buttons": null,
                "attachment": null,
                "image": null,
                "custom": null
            },
            "metadata": {}
        },
        {
            "event": "followup",
            "timestamp": 1579603728.1405973,
            "name": "form_initial_details"
        },
        {
            "event": "action",
            "timestamp": 1579603728.1657672,
            "name": "form_initial_details",
            "policy": null,
            "confidence": 1
        },
        {
            "event": "bot",
            "timestamp": 1579603728.1657739,
            "text": "May I have your full name?",
            "data": {
                "elements": null,
                "quick_replies": null,
                "buttons": null,
                "attachment": null,
                "image": null,
                "custom": null
            },
            "metadata": {}
        },
        {
            "event": "form",
            "timestamp": 1579603728.165777,
            "name": "form_initial_details"
        },
        {
            "event": "slot",
            "timestamp": 1579603728.1657817,
            "name": "requested_slot",
            "value": "name"
        },
        {
            "event": "action",
            "timestamp": 1579603728.1715336,
            "name": "action_listen",
            "policy": "policy_0_FormPolicy",
            "confidence": 1
        },
        {
            "event": "action",
            "timestamp": 1579603732.8638175,
            "name": "form_initial_details",
            "policy": "policy_0_FormPolicy",
            "confidence": 1
        },
        {
            "event": "bot",
            "timestamp": 1579603732.8638244,
            "text": "Could I get your email address then?",
            "data": {
                "elements": null,
                "quick_replies": null,
                "buttons": null,
                "attachment": null,
                "image": null,
                "custom": null
            },
            "metadata": {}
        },
        {
            "event": "slot",
            "timestamp": 1579603732.8638275,
            "name": "name",
            "value": "Shay van Dam"
        },
        {
            "event": "slot",
            "timestamp": 1579603732.863831,
            "name": "first_name",
            "value": "Shay"
        },
        {
            "event": "slot",
            "timestamp": 1579603732.863834,
            "name": "last_name",
            "value": "van Dam "
        },
        {
            "event": "slot",
            "timestamp": 1579603732.863836,
            "name": "requested_slot",
            "value": "email"
        },
        {
            "event": "action",
            "timestamp": 1579603732.8712785,
            "name": "action_listen",
            "policy": "policy_0_FormPolicy",
            "confidence": 1
        },
        {
            "event": "action",
            "timestamp": 1579603738.9176588,
            "name": "form_initial_details",
            "policy": "policy_0_FormPolicy",
            "confidence": 1
        },
        {
            "event": "bot",
            "timestamp": 1579603738.9176707,
            "text": "Thank you!",
            "data": {
                "elements": null,
                "quick_replies": null,
                "buttons": null,
                "attachment": null,
                "image": null,
                "custom": null
            },
            "metadata": {}
        },
        {
            "event": "bot",
            "timestamp": 1579603738.917675,
            "text": "It's a pleasure to meet you {}!",
            "data": {
                "elements": null,
                "quick_replies": null,
                "buttons": null,
                "attachment": null,
                "image": null,
                "custom": null
            },
            "metadata": {}
        },
        {
            "event": "slot",
            "timestamp": 1579603738.9176774,
            "name": "email",
            "value": "shay@example.com"
        },
        {
            "event": "slot",
            "timestamp": 1579603738.9176826,
            "name": "mailchimp",
            "value": false
        },
        {
            "event": "followup",
            "timestamp": 1579603738.9176877,
            "name": "action_ask_next"
        },
        {
            "event": "slot",
            "timestamp": 1579603738.9176936,
            "name": "introduction_complete",
            "value": true
        },
        {
            "event": "form",
            "timestamp": 1579603738.9176986,
            "name": null
        },
        {
            "event": "slot",
            "timestamp": 1579603738.917704,
            "name": "requested_slot",
            "value": null
        },
        {
            "event": "action",
            "timestamp": 1579603738.9464698,
            "name": "action_ask_next",
            "policy": null,
            "confidence": 1
        },
        {
            "event": "bot",
            "timestamp": 1579603738.9464798,
            "text": "So you'd like to keep your website protected, is that right?",
            "data": {
                "elements": null,
                "quick_replies": null,
                "buttons": [
                    {
                        "payload": "/affirm",
                        "title": "That's right!"
                    },
                    {
                        "payload": "/deny",
                        "title": "Heck no"
                    }
                ],
                "attachment": null,
                "image": null,
                "custom": null
            },
            "metadata": {}
        },
        {
            "event": "followup",
            "timestamp": 1579603738.9464843,
            "name": "action_listen"
        },
        {
            "event": "action",
            "timestamp": 1579603738.9475675,
            "name": "action_listen",
            "policy": null,
            "confidence": 1
        }
    ],
    "followup_action": null,
    "latest_action_name": "action_listen",
    "latest_event_time": 1579603738.9475675,
    "latest_input_channel": "rest",
    "latest_message": {
        "intent": {
            "name": "enter_data",
            "confidence": 0.9833219051361084
        },
        "entities": [
            {
                "start": 0,
                "end": 16,
                "value": "shay@example.com",
                "entity": "url",
                "confidence": 0.34448567357666426,
                "extractor": "CRFEntityExtractor"
            }
        ],
        "intent_ranking": [
            {
                "name": "enter_data",
                "confidence": 0.9833219051361084
            },
            {
                "name": "handle_insult",
                "confidence": 0.0033315178006887436
            },
            {
                "name": "request_callback",
                "confidence": 0.0016405265778303146
            },
            {
                "name": "ask_who_am_i",
                "confidence": 0.0013293125666677952
            },
            {
                "name": "thank",
                "confidence": 0.0009723895927891135
            },
            {
                "name": "tell_joke",
                "confidence": 0.0009547466179355979
            },
            {
                "name": "ask_price_penetration_testing",
                "confidence": 0.0008467169245705009
            },
            {
                "name": "request_meeting",
                "confidence": 0.000742993492167443
            },
            {
                "name": "ask_languages_bot",
                "confidence": 0.0004680998681578785
            },
            {
                "name": "ask_what_is_premium",
                "confidence": 0.0004476514586713165
            }
        ],
        "text": "shay@example.com"
    },

As you can see the user input isn’t logged in the events list but it is getting picked up by the latest_message tag.

I have since implemented an event broker and the messages do also appear there, but are still not saved to the MongoDB Tracker Store, see below:

2020-01-21 16:58:02 DEBUG    rasa.core.policies.form_policy  - There is an active form 'form_initial_details'
2020-01-21 16:58:02 DEBUG    rasa.core.policies.memoization  - Current tracker state [{'intent_initiate_conversation': 1.0, 'prev_action_reintroduction': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'prev_action_introduction': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'prev_form_initial_details': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'prev_action_reintroduction': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'prev_action_introduction': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}]
2020-01-21 16:58:02 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2020-01-21 16:58:02 DEBUG    rasa.core.policies.mapping_policy  - There is no mapped action for the predicted intent, 'initiate_return_conversation'.
2020-01-21 16:58:02 DEBUG    rasa.core.policies.fallback  - NLU confidence threshold met, confidence of fallback action set to core threshold (0.2).
2020-01-21 16:58:02 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_0_FormPolicy
2020-01-21 16:58:02 DEBUG    rasa.core.processor  - Predicted next action 'action_listen' with confidence 1.00.
2020-01-21 16:58:02 DEBUG    rasa.core.processor  - Action 'action_listen' ended with events '[]'.
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "user", "timestamp": 1579625881.9652805, "text": "/initiate_return_conversation{\"bot\": \"maverick_labs\"}", "parse_data": {"text": "/initiate_return_conversation{\"bot\": \"maverick_labs\"}", "intent": {"name": "initiate_return_conversation", "confidence": 1.0}, "intent_ranking": [{"name": "initiate_return_conversation", "confidence": 1.0}], "entities": [{"entity": "bot", "start": 29, "end": 53, "value": "maverick_labs"}]}, "input_channel": "rest", "message_id": "3b6a26b9973049e8a4f6cda608d009cb", "metadata": {}}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "slot", "timestamp": 1579625881.9652936, "name": "bot", "value": "maverick_labs"}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "action", "timestamp": 1579625882.218143, "name": "form_initial_details", "policy": "policy_0_FormPolicy", "confidence": 1.0}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "form", "timestamp": 1579625882.2181542, "name": null}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "slot", "timestamp": 1579625882.2181592, "name": "requested_slot", "value": null}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "followup", "timestamp": 1579625882.218163, "name": "action_reintroduction"}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "action", "timestamp": 1579625882.2271543, "name": "action_reintroduction", "policy": null, "confidence": 1.0}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "followup", "timestamp": 1579625882.227161, "name": "action_introduction"}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "action", "timestamp": 1579625882.2693105, "name": "action_introduction", "policy": null, "confidence": 1.0}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "bot", "timestamp": 1579625882.269321, "text": "Hi there, my name's Adam. I'm a smart, friendly robot here to help you with our Website Technical Maintenance & Support Programme.", "data": {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, "metadata": {}}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "bot", "timestamp": 1579625882.2693264, "text": "By chatting to me you agree to our privacy policy: https://www.mavericklabs.ie/privacy-policy/", "data": {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, "metadata": {}}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "followup", "timestamp": 1579625882.269329, "name": "form_initial_details"}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "action", "timestamp": 1579625882.2973664, "name": "form_initial_details", "policy": null, "confidence": 1.0}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "bot", "timestamp": 1579625882.2973764, "text": "What is your full name?", "data": {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, "metadata": {}}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "form", "timestamp": 1579625882.2973819, "name": "form_initial_details"}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "slot", "timestamp": 1579625882.2973878, "name": "requested_slot", "value": "name"}
2020-01-21 16:58:02 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "action", "timestamp": 1579625882.3095908, "name": "action_listen", "policy": "policy_0_FormPolicy", "confidence": 1.0}
2020-01-21 16:58:02 DEBUG    rasa.core.lock_store  - Deleted lock for conversation 'b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719'.
2020-01-21 16:58:27 DEBUG    rasa.core.processor  - Received user message 'Shay van Dam' with intent '{'name': 'enter_data', 'confidence': 0.9985899329185486}' and entities '[{'start': 0, 'end': 12, 'value': 'Shay van Dam', 'entity': 'name', 'confidence': 0.5892810155001024, 'extractor': 'CRFEntityExtractor'}]'
2020-01-21 16:58:27 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	continue: None
	continue_questions: None
	email: None
	first_name: None
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: False
	last_name: None
	mailchimp: None
	meeting: None
	name: None
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: name
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 16:58:27 DEBUG    rasa.core.processor  - Logged UserUtterance - tracker now has 46 events.
2020-01-21 16:58:27 DEBUG    rasa.core.policies.form_policy  - There is an active form 'form_initial_details'
2020-01-21 16:58:27 DEBUG    rasa.core.policies.memoization  - Current tracker state [{'intent_initiate_conversation': 1.0, 'prev_action_reintroduction': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'prev_action_introduction': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'prev_form_initial_details': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'prev_action_reintroduction': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'prev_action_introduction': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}]
2020-01-21 16:58:27 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2020-01-21 16:58:27 DEBUG    rasa.core.policies.fallback  - NLU confidence threshold met, confidence of fallback action set to core threshold (0.2).
2020-01-21 16:58:27 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_0_FormPolicy
2020-01-21 16:58:27 DEBUG    rasa.core.processor  - Predicted next action 'form_initial_details' with confidence 1.00.
2020-01-21 16:58:27 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'form_initial_details'.
2020-01-21 16:58:27 DEBUG    rasa.core.processor  - Action 'form_initial_details' ended with events '[BotUttered('And what is your email address?', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {}, 1579625907.8761618), <rasa.core.events.SlotSet object at 0x7f7ea477fc88>, <rasa.core.events.SlotSet object at 0x7f7ea477f780>, <rasa.core.events.SlotSet object at 0x7f7ea4786470>, <rasa.core.events.SlotSet object at 0x7f7ebc5cd898>]'.
2020-01-21 16:58:27 DEBUG    rasa.core.processor  - Current slot values: 
	add_on_247_support: None
	add_on_penetration_testing: None
	amount-of-money: None
	appointment: None
	ask_sure_index: 0
	benefits: 0
	bot: maverick_labs
	calendar: None
	company: None
	continue: None
	continue_questions: None
	email: None
	first_name: Shay
	form_one_done: False
	form_two_done: False
	id: None
	industry: None
	interested_plan: None
	introduction_complete: False
	last_name: van Dam 
	mailchimp: None
	meeting: None
	name: Shay van Dam
	phone-number: None
	phone_number: None
	plan: None
	quote: None
	requested_slot: email
	role: None
	size: None
	technologies: None
	time: None
	trello_id: None
	url: None
	what_i_get: 0
2020-01-21 16:58:27 DEBUG    rasa.core.policies.form_policy  - There is an active form 'form_initial_details'
2020-01-21 16:58:27 DEBUG    rasa.core.policies.memoization  - Current tracker state [{'intent_initiate_conversation': 1.0, 'prev_action_reintroduction': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'prev_action_introduction': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'prev_form_initial_details': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'prev_action_reintroduction': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}, {'intent_initiate_conversation': 1.0, 'slot_form_two_done_0': 1.0, 'slot_bot_0': 1.0, 'slot_introduction_complete_0': 1.0, 'slot_benefits_0': 1.0, 'slot_what_i_get_0': 1.0, 'entity_bot': 1.0, 'prev_action_introduction': 1.0, 'slot_form_one_done_0': 1.0, 'slot_ask_sure_index_0': 1.0}]
2020-01-21 16:58:27 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2020-01-21 16:58:27 DEBUG    rasa.core.policies.mapping_policy  - There is no mapped action for the predicted intent, 'enter_data'.
2020-01-21 16:58:27 DEBUG    rasa.core.policies.fallback  - NLU confidence threshold met, confidence of fallback action set to core threshold (0.2).
2020-01-21 16:58:27 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_0_FormPolicy
2020-01-21 16:58:27 DEBUG    rasa.core.processor  - Predicted next action 'action_listen' with confidence 1.00.
2020-01-21 16:58:27 DEBUG    rasa.core.processor  - Action 'action_listen' ended with events '[]'.
2020-01-21 16:58:27 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "user", "timestamp": 1579625907.8316908, "text": "Shay van Dam", "parse_data": {"intent": {"name": "enter_data", "confidence": 0.9985899329185486}, "entities": [{"start": 0, "end": 12, "value": "Shay van Dam", "entity": "name", "confidence": 0.5892810155001024, "extractor": "CRFEntityExtractor"}], "intent_ranking": [{"name": "enter_data", "confidence": 0.9985899329185486}, {"name": "ask_restaurant", "confidence": 0.00017915967328008264}, {"name": "unsure", "confidence": 0.00014692576951347291}, {"name": "request_sales", "confidence": 0.00012557426816783845}, {"name": "ask_weather", "confidence": 0.0001245308667421341}, {"name": "ask_what_is_penetration_testing", "confidence": 8.663049811730161e-05}, {"name": "give_calendar", "confidence": 7.898952753748745e-05}, {"name": "deny", "confidence": 7.172536425059661e-05}, {"name": "ask_how_built", "confidence": 6.036161721567623e-05}, {"name": "ask_what_is_premium", "confidence": 5.079909533378668e-05}], "text": "Shay van Dam"}, "input_channel": "rest", "message_id": "ea66be650f3845acb8c1dcf4051eb1c0", "metadata": {}}
2020-01-21 16:58:27 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "action", "timestamp": 1579625907.8766315, "name": "form_initial_details", "policy": "policy_0_FormPolicy", "confidence": 1.0}
2020-01-21 16:58:27 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "bot", "timestamp": 1579625907.8766434, "text": "And what is your email address?", "data": {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, "metadata": {}}
2020-01-21 16:58:27 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "slot", "timestamp": 1579625907.876648, "name": "name", "value": "Shay van Dam"}
2020-01-21 16:58:27 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "slot", "timestamp": 1579625907.8766532, "name": "first_name", "value": "Shay"}
2020-01-21 16:58:27 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "slot", "timestamp": 1579625907.8766575, "name": "last_name", "value": "van Dam "}
2020-01-21 16:58:27 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "slot", "timestamp": 1579625907.876661, "name": "requested_slot", "value": "email"}
2020-01-21 16:58:27 DEBUG    rasa.core.brokers.pika  - Published Pika events to queue 'rasa_production_events' on host 'rabbit':
{"sender_id": "b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719", "event": "action", "timestamp": 1579625907.8908923, "name": "action_listen", "policy": "policy_0_FormPolicy", "confidence": 1.0}
2020-01-21 16:58:27 DEBUG    rasa.core.lock_store  - Deleted lock for conversation 'b0acf491-7f13-418d-84f3-4d00905c956d-2020-01-21 16:38:35.670719'.

To add to this, I have run interactive learning and that actually does save the user events to the Tracker Store

Any updates on why this might be happening?

@ShayVD Not sure yet. Trying to get some help from a colleague, we will get back to you as soon as possible.

1 Like

@ShayVD We had bug in rasa 1.6.0 which could lead to the described problem. Can you please try upgrading to the latest patch release (1.6.1) in case you haven’t done so far?

1 Like

Yeah that’s done the trick alright, thank you!

Great :tada: