Rasa lagging in production

So… I have RASA Open Source bot deployed to production (Azure Cloud). It’s configured with both Slack and FB. Connectors are fine. It also use MongoDB as Tracker Store (mongo is configure on the server). I got rid of RASA X deployment, because it was lagging, but… As I see now - I think RASA was the main cause of this behavior.

Basically, what’s happening is that bot is stable for random amount of time and then it just lags for few minutes. After few minutes it just response to all the messages that were sent during that lag.

I paste all the debug info from the server (it’s currently running in debug mode, to see what’s going on).

2021-09-06 09:21:36 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 139
2021-09-06 09:21:36 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: ask_service | previous action name: action_listen
2021-09-06 09:21:36 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:21:36 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'prev_action': {'action_name': 'action_greet'}}]
2021-09-06 09:21:36 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:21:36 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_initial_slots_extraction' based on user intent.
2021-09-06 09:21:36 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:21:36 DEBUG    rasa.core.policies.rule_policy  - There is a rule for the next action 'action_listen'.
2021-09-06 09:21:36 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_2_RulePolicy.
2021-09-06 09:21:36 DEBUG    rasa.core.processor  - Predicted next action 'action_listen' with confidence 1.00.
2021-09-06 09:21:36 DEBUG    rasa.core.processor  - Policy prediction ended with events '[]'.
2021-09-06 09:21:36 DEBUG    rasa.core.processor  - Action 'action_listen' ended with events '[]'.
2021-09-06 09:21:37 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 35
/opt/venv/lib/python3.8/site-packages/sanic/app.py:973: ResourceWarning: unclosed <ssl.SSLSocket fd=24, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6, laddr=('10.244.182.32', 50844), raddr=('185.60.216.15', 443)>
  response = await response
ResourceWarning: Enable tracemalloc to get the object allocation traceback
2021-09-06 09:21:37 DEBUG    rasa.core.lock_store  - Acquired lock for conversation '4067385133374520'.
2021-09-06 09:21:37 DEBUG    urllib3.connectionpool  - Starting new HTTP connection (1): rasa-data.westeurope.cloudapp.azure.com:8000
2021-09-06 09:21:37 DEBUG    urllib3.connectionpool  - http://rasa-data.westeurope.cloudapp.azure.com:8000 "POST /parse HTTP/1.1" 200 None
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Received user message 'hej' with intent '{'id': -6313617482696067480, 'name': 'greet', 'confidence': 0.9984284043312073}' and entities '[]'
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Logged UserUtterance - tracker now has 94 events.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: greet | previous action name: action_listen
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'user': {'intent': 'greet'}, 'prev_action': {'action_name': 'action_listen'}}]
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:21:37 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_initial_slots_extraction' based on user intent.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user text: hej | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:21:37 DEBUG    rasa.core.policies.rule_policy  - There is no applicable rule.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:21:37 DEBUG    rasa.core.policies.rule_policy  - There is a rule for the next action 'privacy_policy_form'.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.ensemble  - Made prediction using user intent.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.ensemble  - Added `DefinePrevUserUtteredFeaturization(False)` event.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_2_RulePolicy.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Predicted next action 'privacy_policy_form' with confidence 1.00.
2021-09-06 09:21:37 DEBUG    rasa.core.actions.forms  - Activated the form 'privacy_policy_form'.
2021-09-06 09:21:37 DEBUG    rasa.core.actions.forms  - Validating pre-filled required slots: {'policy_accepted': True}
2021-09-06 09:21:37 DEBUG    rasa.core.actions.forms  - Validating extracted slots: {'policy_accepted': True}
2021-09-06 09:21:37 DEBUG    rasa.core.actions.forms  - Validating user input 'UserUttered(text: hej, intent: greet, use_text_for_featurization: False)'.
2021-09-06 09:21:37 DEBUG    rasa.core.actions.forms  - Validating extracted slots: {}
2021-09-06 09:21:37 DEBUG    rasa.core.actions.forms  - Deactivating the form 'privacy_policy_form'
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Policy prediction ended with events '[<rasa.shared.core.events.DefinePrevUserUtteredFeaturization object at 0x7f51e06a3370>]'.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Action 'privacy_policy_form' ended with events '[<rasa.shared.core.events.ActiveLoop object at 0x7f51e3339b80>, <rasa.shared.core.events.SlotSet object at 0x7f51de350d00>, <rasa.shared.core.events.SlotSet object at 0x7f51e0fd22b0>, <rasa.shared.core.events.ActiveLoop object at 0x7f51e06e3550>]'.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Current slot values: 
	policy_accepted: True
	patient_name: None
	patient_id: None
	patient_birthdate: None
	phone_number: None
	specialization_name: 
	specialization_id: None
	service_name: None
	service_id: None
	group_service_question_id: 3aa08055-07bb-4133-85e6-30c621b4bb95
	staff_name: 
	staff_id: None
	sickness_name: 
	sickness_id: None
	appointment_type_name: 
	appointment_type_id: None
	organization_unit_name: None
	organization_unit_id: None
	payment_mode_name: None
	payment_mode_id: None
	appointment_mode_name: None
	appointment_mode_id: None
	visit_accepted: None
	visit_from_date: None
	visit_to_date: None
	visit_final: None
	visit_authorization_code: None
	visit_authorization_code_id: None
	specialist_gender: None
	age_category: None
	preliminary_search_organization_units: None
	preliminary_search_payment_modes: None
	preliminary_search_appointment_modes: None
	preliminary_search_patient_age_min: None
	preliminary_search_patient_age_max: None
	patient_identified: None
	make_appointment_path: service
	internal_slot_cache: {'locked_slot_validators': [], 'locked_entities': {'patient_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175873Z'}], 'staff_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175881Z'}], 'service_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175885Z'}], 'specialization_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175889Z'}], 'appointment_type_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175893Z'}], 'sickness_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175897Z'}]}, 'previous_slots': {'group_service_question_id': '3aa08055-07bb-4133-85e6-30c621b4bb95', 'make_appointment_path': 'service', 'specialization_name': '', 'appointment_type_name': '', 'sickness_name': '', 'staff_name': ''}, 'temp': {'QuestionAnswers': [{'Id': 'f43bf1ad-1a53-4a1b-a656-87f75b98b287', 'Name': 'RTG barku Osiowe', 'ServiceId': 272, 'Answer': 'RTG barku; Prześwietlenie barku; zdjęcie barku; Rentgen barku;', 'Question': None, 'Children': None}, {'Id': '6c42e112-9457-4667-9197-7647dac42047', 'Name': 'RTG barku AP', 'ServiceId': 271, 'Answer': 'zdjęcie barku; RTG barku; Prześwietlenie barku; Rentgen barku;', 'Question': None, 'Children': None}]}}
	requested_slot: None
	session_started_metadata: None
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: ask_service | previous action name: action_listen
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'prev_action': {'action_name': 'privacy_policy_form'}}]
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:21:37 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_initial_slots_extraction' based on user intent.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:21:37 DEBUG    rasa.core.policies.rule_policy  - There is a rule for the next action 'action_submit_privacy_policy_form'.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_2_RulePolicy.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Predicted next action 'action_submit_privacy_policy_form' with confidence 1.00.
2021-09-06 09:21:37 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'action_submit_privacy_policy_form'.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Policy prediction ended with events '[]'.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Action 'action_submit_privacy_policy_form' ended with events '[]'.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Current slot values: 
	policy_accepted: True
	patient_name: None
	patient_id: None
	patient_birthdate: None
	phone_number: None
	specialization_name: 
	specialization_id: None
	service_name: None
	service_id: None
	group_service_question_id: 3aa08055-07bb-4133-85e6-30c621b4bb95
	staff_name: 
	staff_id: None
	sickness_name: 
	sickness_id: None
	appointment_type_name: 
	appointment_type_id: None
	organization_unit_name: None
	organization_unit_id: None
	payment_mode_name: None
	payment_mode_id: None
	appointment_mode_name: None
	appointment_mode_id: None
	visit_accepted: None
	visit_from_date: None
	visit_to_date: None
	visit_final: None
	visit_authorization_code: None
	visit_authorization_code_id: None
	specialist_gender: None
	age_category: None
	preliminary_search_organization_units: None
	preliminary_search_payment_modes: None
	preliminary_search_appointment_modes: None
	preliminary_search_patient_age_min: None
	preliminary_search_patient_age_max: None
	patient_identified: None
	make_appointment_path: service
	internal_slot_cache: {'locked_slot_validators': [], 'locked_entities': {'patient_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175873Z'}], 'staff_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175881Z'}], 'service_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175885Z'}], 'specialization_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175889Z'}], 'appointment_type_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175893Z'}], 'sickness_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175897Z'}]}, 'previous_slots': {'group_service_question_id': '3aa08055-07bb-4133-85e6-30c621b4bb95', 'make_appointment_path': 'service', 'specialization_name': '', 'appointment_type_name': '', 'sickness_name': '', 'staff_name': ''}, 'temp': {'QuestionAnswers': [{'Id': 'f43bf1ad-1a53-4a1b-a656-87f75b98b287', 'Name': 'RTG barku Osiowe', 'ServiceId': 272, 'Answer': 'RTG barku; Prześwietlenie barku; zdjęcie barku; Rentgen barku;', 'Question': None, 'Children': None}, {'Id': '6c42e112-9457-4667-9197-7647dac42047', 'Name': 'RTG barku AP', 'ServiceId': 271, 'Answer': 'zdjęcie barku; RTG barku; Prześwietlenie barku; Rentgen barku;', 'Question': None, 'Children': None}]}}
	requested_slot: None
	session_started_metadata: None
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: ask_service | previous action name: action_listen
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'prev_action': {'action_name': 'action_submit_privacy_policy_form'}}]
2021-09-06 09:21:37 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:21:37 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_initial_slots_extraction' based on user intent.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:21:37 DEBUG    rasa.core.policies.rule_policy  - There is a rule for the next action 'action_greet'.
2021-09-06 09:21:37 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_2_RulePolicy.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Predicted next action 'action_greet' with confidence 1.00.
2021-09-06 09:21:37 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'action_greet'.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Policy prediction ended with events '[]'.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Action 'action_greet' ended with events '[BotUttered('Jestem asystentem firmy ALLMEDICA. W czym mogę pomóc?', {"elements": [{"title": "Rezerwacja wizyty", "subtitle": "Wybierz, je\u017celi jeste\u015b naszym pacjentem i chcesz zarejestrowa\u0107 now\u0105 wizyt\u0119.", "buttons": [{"title": "Rezerwacja wizyty", "payload": "/make_appointment"}]}, {"title": "Ceny us\u0142ug", "subtitle": "Wybierz, aby otrzyma\u0107 ceny konkretnej us\u0142ugi wykonywanej w naszych plac\u00f3wkach.", "buttons": [{"title": "Ceny us\u0142ug", "payload": "/ask_price"}]}, {"title": "Specjali\u015bci", "subtitle": "Wybierz, aby zapyta\u0107 o konkretnego specjalist\u0119.", "buttons": [{"title": "Specjali\u015bci", "payload": "/ask_specialist"}]}, {"title": "Dost\u0119pno\u015b\u0107 us\u0142ug", "subtitle": "Wybierz, aby sprawdzi\u0107 dost\u0119pno\u015b\u0107 konkretnej us\u0142ugi.", "buttons": [{"title": "Dost\u0119pno\u015b\u0107 us\u0142ug", "payload": "/ask_service"}]}], "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {}, 1630920097.4011574)]'.
2021-09-06 09:21:37 DEBUG    rasa.core.processor  - Current slot values: 
	policy_accepted: True
	patient_name: None
	patient_id: None
	patient_birthdate: None
	phone_number: None
	specialization_name: 
	specialization_id: None
	service_name: None
	service_id: None
	group_service_question_id: 3aa08055-07bb-4133-85e6-30c621b4bb95
	staff_name: 
	staff_id: None
	sickness_name: 
	sickness_id: None
	appointment_type_name: 
	appointment_type_id: None
	organization_unit_name: None
	organization_unit_id: None
	payment_mode_name: None
	payment_mode_id: None
	appointment_mode_name: None
	appointment_mode_id: None
	visit_accepted: None
	visit_from_date: None
	visit_to_date: None
	visit_final: None
	visit_authorization_code: None
	visit_authorization_code_id: None
	specialist_gender: None
	age_category: None
	preliminary_search_organization_units: None
	preliminary_search_payment_modes: None
	preliminary_search_appointment_modes: None
	preliminary_search_patient_age_min: None
	preliminary_search_patient_age_max: None
	patient_identified: None
	make_appointment_path: service
	internal_slot_cache: {'locked_slot_validators': [], 'locked_entities': {'patient_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175873Z'}], 'staff_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175881Z'}], 'service_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175885Z'}], 'specialization_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175889Z'}], 'appointment_type_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175893Z'}], 'sickness_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175897Z'}]}, 'previous_slots': {'group_service_question_id': '3aa08055-07bb-4133-85e6-30c621b4bb95', 'make_appointment_path': 'service', 'specialization_name': '', 'appointment_type_name': '', 'sickness_name': '', 'staff_name': ''}, 'temp': {'QuestionAnswers': [{'Id': 'f43bf1ad-1a53-4a1b-a656-87f75b98b287', 'Name': 'RTG barku Osiowe', 'ServiceId': 272, 'Answer': 'RTG barku; Prześwietlenie barku; zdjęcie barku; Rentgen barku;', 'Question': None, 'Children': None}, {'Id': '6c42e112-9457-4667-9197-7647dac42047', 'Name': 'RTG barku AP', 'ServiceId': 271, 'Answer': 'zdjęcie barku; RTG barku; Prześwietlenie barku; Rentgen barku;', 'Question': None, 'Children': None}]}}
	requested_slot: None
	session_started_metadata: None
2021-09-06 09:21:37 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 139
2021-09-06 09:21:38 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: ask_service | previous action name: action_listen
2021-09-06 09:21:38 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:21:38 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'prev_action': {'action_name': 'action_greet'}}]
2021-09-06 09:21:38 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:21:38 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_initial_slots_extraction' based on user intent.
2021-09-06 09:21:38 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:21:38 DEBUG    rasa.core.policies.rule_policy  - There is a rule for the next action 'action_listen'.
2021-09-06 09:21:38 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_2_RulePolicy.
2021-09-06 09:21:38 DEBUG    rasa.core.processor  - Predicted next action 'action_listen' with confidence 1.00.
2021-09-06 09:21:38 DEBUG    rasa.core.processor  - Policy prediction ended with events '[]'.
2021-09-06 09:21:38 DEBUG    rasa.core.processor  - Action 'action_listen' ended with events '[]'.
2021-09-06 09:21:38 DEBUG    rasa.core.lock_store  - Deleted lock for conversation '4067385133374520'.
2021-09-06 09:21:39 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 35
/opt/venv/lib/python3.8/site-packages/sanic/app.py:973: ResourceWarning: unclosed <ssl.SSLSocket fd=25, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6, laddr=('10.244.182.32', 50846), raddr=('185.60.216.15', 443)>
  response = await response
ResourceWarning: Enable tracemalloc to get the object allocation traceback
2021-09-06 09:22:54 DEBUG    urllib3.connectionpool  - Starting new HTTPS connection (1): graph.facebook.com:443
2021-09-06 09:22:55 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 35
2021-09-06 09:22:55 DEBUG    rasa.core.lock_store  - Issuing ticket for conversation '4067385133374520'.
2021-09-06 09:22:55 DEBUG    rasa.core.lock_store  - Acquiring lock for conversation '4067385133374520'.
2021-09-06 09:22:55 DEBUG    rasa.core.lock_store  - Acquired lock for conversation '4067385133374520'.
2021-09-06 09:22:55 DEBUG    urllib3.connectionpool  - Starting new HTTP connection (1): rasa-data.westeurope.cloudapp.azure.com:8000
2021-09-06 09:22:55 DEBUG    urllib3.connectionpool  - http://rasa-data.westeurope.cloudapp.azure.com:8000 "POST /parse HTTP/1.1" 200 None
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Received user message 'hej' with intent '{'id': -6313617482696067480, 'name': 'greet', 'confidence': 0.9984284043312073}' and entities '[]'
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Logged UserUtterance - tracker now has 105 events.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: greet | previous action name: action_listen
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'user': {'intent': 'greet'}, 'prev_action': {'action_name': 'action_listen'}}]
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:22:55 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_initial_slots_extraction' based on user intent.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 20] user text: hej | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:22:55 DEBUG    rasa.core.policies.rule_policy  - There is no applicable rule.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 20] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:22:55 DEBUG    rasa.core.policies.rule_policy  - There is a rule for the next action 'privacy_policy_form'.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.ensemble  - Made prediction using user intent.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.ensemble  - Added `DefinePrevUserUtteredFeaturization(False)` event.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_2_RulePolicy.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Predicted next action 'privacy_policy_form' with confidence 1.00.
2021-09-06 09:22:55 DEBUG    rasa.core.actions.forms  - Activated the form 'privacy_policy_form'.
2021-09-06 09:22:55 DEBUG    rasa.core.actions.forms  - Validating pre-filled required slots: {'policy_accepted': True}
2021-09-06 09:22:55 DEBUG    rasa.core.actions.forms  - Validating extracted slots: {'policy_accepted': True}
2021-09-06 09:22:55 DEBUG    rasa.core.actions.forms  - Validating user input 'UserUttered(text: hej, intent: greet, use_text_for_featurization: False)'.
2021-09-06 09:22:55 DEBUG    rasa.core.actions.forms  - Validating extracted slots: {}
2021-09-06 09:22:55 DEBUG    rasa.core.actions.forms  - Deactivating the form 'privacy_policy_form'
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Policy prediction ended with events '[<rasa.shared.core.events.DefinePrevUserUtteredFeaturization object at 0x7f51e41d6be0>]'.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Action 'privacy_policy_form' ended with events '[<rasa.shared.core.events.ActiveLoop object at 0x7f51e06832b0>, <rasa.shared.core.events.SlotSet object at 0x7f51e06835b0>, <rasa.shared.core.events.SlotSet object at 0x7f51e394b700>, <rasa.shared.core.events.ActiveLoop object at 0x7f51e06362e0>]'.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Current slot values: 
	policy_accepted: True
	patient_name: None
	patient_id: None
	patient_birthdate: None
	phone_number: None
	specialization_name: 
	specialization_id: None
	service_name: None
	service_id: None
	group_service_question_id: 3aa08055-07bb-4133-85e6-30c621b4bb95
	staff_name: 
	staff_id: None
	sickness_name: 
	sickness_id: None
	appointment_type_name: 
	appointment_type_id: None
	organization_unit_name: None
	organization_unit_id: None
	payment_mode_name: None
	payment_mode_id: None
	appointment_mode_name: None
	appointment_mode_id: None
	visit_accepted: None
	visit_from_date: None
	visit_to_date: None
	visit_final: None
	visit_authorization_code: None
	visit_authorization_code_id: None
	specialist_gender: None
	age_category: None
	preliminary_search_organization_units: None
	preliminary_search_payment_modes: None
	preliminary_search_appointment_modes: None
	preliminary_search_patient_age_min: None
	preliminary_search_patient_age_max: None
	patient_identified: None
	make_appointment_path: service
	internal_slot_cache: {'locked_slot_validators': [], 'locked_entities': {'patient_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175873Z'}], 'staff_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175881Z'}], 'service_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175885Z'}], 'specialization_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175889Z'}], 'appointment_type_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175893Z'}], 'sickness_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175897Z'}]}, 'previous_slots': {'group_service_question_id': '3aa08055-07bb-4133-85e6-30c621b4bb95', 'make_appointment_path': 'service', 'specialization_name': '', 'appointment_type_name': '', 'sickness_name': '', 'staff_name': ''}, 'temp': {'QuestionAnswers': [{'Id': 'f43bf1ad-1a53-4a1b-a656-87f75b98b287', 'Name': 'RTG barku Osiowe', 'ServiceId': 272, 'Answer': 'RTG barku; Prześwietlenie barku; zdjęcie barku; Rentgen barku;', 'Question': None, 'Children': None}, {'Id': '6c42e112-9457-4667-9197-7647dac42047', 'Name': 'RTG barku AP', 'ServiceId': 271, 'Answer': 'zdjęcie barku; RTG barku; Prześwietlenie barku; Rentgen barku;', 'Question': None, 'Children': None}]}}
	requested_slot: None
	session_started_metadata: None
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: ask_service | previous action name: action_listen
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'prev_action': {'action_name': 'privacy_policy_form'}}]
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:22:55 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_initial_slots_extraction' based on user intent.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 20] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 21] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:22:55 DEBUG    rasa.core.policies.rule_policy  - There is a rule for the next action 'action_submit_privacy_policy_form'.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_2_RulePolicy.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Predicted next action 'action_submit_privacy_policy_form' with confidence 1.00.
2021-09-06 09:22:55 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'action_submit_privacy_policy_form'.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Policy prediction ended with events '[]'.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Action 'action_submit_privacy_policy_form' ended with events '[]'.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Current slot values: 
	policy_accepted: True
	patient_name: None
	patient_id: None
	patient_birthdate: None
	phone_number: None
	specialization_name: 
	specialization_id: None
	service_name: None
	service_id: None
	group_service_question_id: 3aa08055-07bb-4133-85e6-30c621b4bb95
	staff_name: 
	staff_id: None
	sickness_name: 
	sickness_id: None
	appointment_type_name: 
	appointment_type_id: None
	organization_unit_name: None
	organization_unit_id: None
	payment_mode_name: None
	payment_mode_id: None
	appointment_mode_name: None
	appointment_mode_id: None
	visit_accepted: None
	visit_from_date: None
	visit_to_date: None
	visit_final: None
	visit_authorization_code: None
	visit_authorization_code_id: None
	specialist_gender: None
	age_category: None
	preliminary_search_organization_units: None
	preliminary_search_payment_modes: None
	preliminary_search_appointment_modes: None
	preliminary_search_patient_age_min: None
	preliminary_search_patient_age_max: None
	patient_identified: None
	make_appointment_path: service
	internal_slot_cache: {'locked_slot_validators': [], 'locked_entities': {'patient_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175873Z'}], 'staff_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175881Z'}], 'service_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175885Z'}], 'specialization_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175889Z'}], 'appointment_type_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175893Z'}], 'sickness_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175897Z'}]}, 'previous_slots': {'group_service_question_id': '3aa08055-07bb-4133-85e6-30c621b4bb95', 'make_appointment_path': 'service', 'specialization_name': '', 'appointment_type_name': '', 'sickness_name': '', 'staff_name': ''}, 'temp': {'QuestionAnswers': [{'Id': 'f43bf1ad-1a53-4a1b-a656-87f75b98b287', 'Name': 'RTG barku Osiowe', 'ServiceId': 272, 'Answer': 'RTG barku; Prześwietlenie barku; zdjęcie barku; Rentgen barku;', 'Question': None, 'Children': None}, {'Id': '6c42e112-9457-4667-9197-7647dac42047', 'Name': 'RTG barku AP', 'ServiceId': 271, 'Answer': 'zdjęcie barku; RTG barku; Prześwietlenie barku; Rentgen barku;', 'Question': None, 'Children': None}]}}
	requested_slot: None
	session_started_metadata: None
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: ask_service | previous action name: action_listen
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'prev_action': {'action_name': 'action_submit_privacy_policy_form'}}]
2021-09-06 09:22:55 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:22:55 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_initial_slots_extraction' based on user intent.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 20] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 21] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 22] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:22:55 DEBUG    rasa.core.policies.rule_policy  - There is a rule for the next action 'action_greet'.
2021-09-06 09:22:55 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_2_RulePolicy.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Predicted next action 'action_greet' with confidence 1.00.
2021-09-06 09:22:55 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'action_greet'.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Policy prediction ended with events '[]'.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Action 'action_greet' ended with events '[BotUttered('Jestem asystentem firmy ALLMEDICA. W czym mogę pomóc?', {"elements": [{"title": "Rezerwacja wizyty", "subtitle": "Wybierz, je\u017celi jeste\u015b naszym pacjentem i chcesz zarejestrowa\u0107 now\u0105 wizyt\u0119.", "buttons": [{"title": "Rezerwacja wizyty", "payload": "/make_appointment"}]}, {"title": "Ceny us\u0142ug", "subtitle": "Wybierz, aby otrzyma\u0107 ceny konkretnej us\u0142ugi wykonywanej w naszych plac\u00f3wkach.", "buttons": [{"title": "Ceny us\u0142ug", "payload": "/ask_price"}]}, {"title": "Specjali\u015bci", "subtitle": "Wybierz, aby zapyta\u0107 o konkretnego specjalist\u0119.", "buttons": [{"title": "Specjali\u015bci", "payload": "/ask_specialist"}]}, {"title": "Dost\u0119pno\u015b\u0107 us\u0142ug", "subtitle": "Wybierz, aby sprawdzi\u0107 dost\u0119pno\u015b\u0107 konkretnej us\u0142ugi.", "buttons": [{"title": "Dost\u0119pno\u015b\u0107 us\u0142ug", "payload": "/ask_service"}]}], "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {}, 1630920175.9407353)]'.
2021-09-06 09:22:55 DEBUG    rasa.core.processor  - Current slot values: 
	policy_accepted: True
	patient_name: None
	patient_id: None
	patient_birthdate: None
	phone_number: None
	specialization_name: 
	specialization_id: None
	service_name: None
	service_id: None
	group_service_question_id: 3aa08055-07bb-4133-85e6-30c621b4bb95
	staff_name: 
	staff_id: None
	sickness_name: 
	sickness_id: None
	appointment_type_name: 
	appointment_type_id: None
	organization_unit_name: None
	organization_unit_id: None
	payment_mode_name: None
	payment_mode_id: None
	appointment_mode_name: None
	appointment_mode_id: None
	visit_accepted: None
	visit_from_date: None
	visit_to_date: None
	visit_final: None
	visit_authorization_code: None
	visit_authorization_code_id: None
	specialist_gender: None
	age_category: None
	preliminary_search_organization_units: None
	preliminary_search_payment_modes: None
	preliminary_search_appointment_modes: None
	preliminary_search_patient_age_min: None
	preliminary_search_patient_age_max: None
	patient_identified: None
	make_appointment_path: service
	internal_slot_cache: {'locked_slot_validators': [], 'locked_entities': {'patient_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175873Z'}], 'staff_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175881Z'}], 'service_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175885Z'}], 'specialization_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175889Z'}], 'appointment_type_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175893Z'}], 'sickness_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175897Z'}]}, 'previous_slots': {'group_service_question_id': '3aa08055-07bb-4133-85e6-30c621b4bb95', 'make_appointment_path': 'service', 'specialization_name': '', 'appointment_type_name': '', 'sickness_name': '', 'staff_name': ''}, 'temp': {'QuestionAnswers': [{'Id': 'f43bf1ad-1a53-4a1b-a656-87f75b98b287', 'Name': 'RTG barku Osiowe', 'ServiceId': 272, 'Answer': 'RTG barku; Prześwietlenie barku; zdjęcie barku; Rentgen barku;', 'Question': None, 'Children': None}, {'Id': '6c42e112-9457-4667-9197-7647dac42047', 'Name': 'RTG barku AP', 'ServiceId': 271, 'Answer': 'zdjęcie barku; RTG barku; Prześwietlenie barku; Rentgen barku;', 'Question': None, 'Children': None}]}}
	requested_slot: None
	session_started_metadata: None
2021-09-06 09:22:56 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 139
2021-09-06 09:22:56 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: ask_service | previous action name: action_listen
2021-09-06 09:22:56 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:22:56 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'prev_action': {'action_name': 'action_greet'}}]
2021-09-06 09:22:56 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:22:56 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_initial_slots_extraction' based on user intent.
2021-09-06 09:22:56 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 20] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 21] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 22] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 23] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:22:56 DEBUG    rasa.core.policies.rule_policy  - There is a rule for the next action 'action_listen'.
2021-09-06 09:22:56 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_2_RulePolicy.
2021-09-06 09:22:56 DEBUG    rasa.core.processor  - Predicted next action 'action_listen' with confidence 1.00.
2021-09-06 09:22:56 DEBUG    rasa.core.processor  - Policy prediction ended with events '[]'.
2021-09-06 09:22:56 DEBUG    rasa.core.processor  - Action 'action_listen' ended with events '[]'.
2021-09-06 09:22:57 DEBUG    rasa.core.lock_store  - Deleted lock for conversation '4067385133374520'.
2021-09-06 09:22:57 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 35
/opt/venv/lib/python3.8/site-packages/sanic/app.py:973: ResourceWarning: unclosed <ssl.SSLSocket fd=20, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6, laddr=('10.244.182.32', 50936), raddr=('185.60.216.15', 443)>
  response = await response
ResourceWarning: Enable tracemalloc to get the object allocation traceback
2021-09-06 09:22:58 DEBUG    urllib3.connectionpool  - Starting new HTTPS connection (1): graph.facebook.com:443
2021-09-06 09:22:58 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 35
2021-09-06 09:22:58 DEBUG    rasa.core.lock_store  - Issuing ticket for conversation '4067385133374520'.
2021-09-06 09:22:58 DEBUG    rasa.core.lock_store  - Acquiring lock for conversation '4067385133374520'.
2021-09-06 09:22:58 DEBUG    rasa.core.lock_store  - Acquired lock for conversation '4067385133374520'.
2021-09-06 09:22:58 DEBUG    urllib3.connectionpool  - Starting new HTTP connection (1): rasa-data.westeurope.cloudapp.azure.com:8000
2021-09-06 09:22:58 DEBUG    urllib3.connectionpool  - http://rasa-data.westeurope.cloudapp.azure.com:8000 "POST /parse HTTP/1.1" 200 None
2021-09-06 09:22:59 DEBUG    rasa.nlu.classifiers.fallback_classifier  - NLU confidence 0.3405591547489166 for intent 'restart' is lower than NLU threshold 0.70.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Received user message 'żyjesz?' with intent '{'name': 'nlu_fallback', 'confidence': 0.7}' and entities '[{'entity': 'service_name', 'start': 0, 'end': 6, 'confidence_entity': 0.7170197367668152, 'value': 'żyjesz', 'extractor': 'DIETClassifier'}]'
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Current slot values: 
	policy_accepted: True
	patient_name: None
	patient_id: None
	patient_birthdate: None
	phone_number: None
	specialization_name: 
	specialization_id: None
	service_name: None
	service_id: None
	group_service_question_id: 3aa08055-07bb-4133-85e6-30c621b4bb95
	staff_name: 
	staff_id: None
	sickness_name: 
	sickness_id: None
	appointment_type_name: 
	appointment_type_id: None
	organization_unit_name: None
	organization_unit_id: None
	payment_mode_name: None
	payment_mode_id: None
	appointment_mode_name: None
	appointment_mode_id: None
	visit_accepted: None
	visit_from_date: None
	visit_to_date: None
	visit_final: None
	visit_authorization_code: None
	visit_authorization_code_id: None
	specialist_gender: None
	age_category: None
	preliminary_search_organization_units: None
	preliminary_search_payment_modes: None
	preliminary_search_appointment_modes: None
	preliminary_search_patient_age_min: None
	preliminary_search_patient_age_max: None
	patient_identified: None
	make_appointment_path: service
	internal_slot_cache: {'locked_slot_validators': [], 'locked_entities': {'patient_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175873Z'}], 'staff_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175881Z'}], 'service_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175885Z'}], 'specialization_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175889Z'}], 'appointment_type_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175893Z'}], 'sickness_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175897Z'}]}, 'previous_slots': {'group_service_question_id': '3aa08055-07bb-4133-85e6-30c621b4bb95', 'make_appointment_path': 'service', 'specialization_name': '', 'appointment_type_name': '', 'sickness_name': '', 'staff_name': ''}, 'temp': {'QuestionAnswers': [{'Id': 'f43bf1ad-1a53-4a1b-a656-87f75b98b287', 'Name': 'RTG barku Osiowe', 'ServiceId': 272, 'Answer': 'RTG barku; Prześwietlenie barku; zdjęcie barku; Rentgen barku;', 'Question': None, 'Children': None}, {'Id': '6c42e112-9457-4667-9197-7647dac42047', 'Name': 'RTG barku AP', 'ServiceId': 271, 'Answer': 'zdjęcie barku; RTG barku; Prześwietlenie barku; Rentgen barku;', 'Question': None, 'Children': None}]}}
	requested_slot: None
	session_started_metadata: None
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Logged UserUtterance - tracker now has 116 events.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_listen
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'user': {'intent': 'nlu_fallback', 'entities': ('service_name',)}, 'prev_action': {'action_name': 'action_listen'}}]
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:22:59 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_submit_ask_service_form' based on user intent.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 20] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 21] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 22] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 23] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 24] user text: żyjesz? | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:22:59 DEBUG    rasa.core.policies.rule_policy  - There is no applicable rule.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 20] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 21] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 22] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 23] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 24] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:22:59 DEBUG    rasa.core.policies.rule_policy  - There is no applicable rule.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.ensemble  - Made prediction using user intent.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.ensemble  - Added `DefinePrevUserUtteredFeaturization(False)` event.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_1_TEDPolicy.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Predicted next action 'action_submit_ask_service_form' with confidence 0.77.
2021-09-06 09:22:59 DEBUG    rasa.core.actions.action  - Calling action endpoint to run action 'action_submit_ask_service_form'.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Policy prediction ended with events '[<rasa.shared.core.events.DefinePrevUserUtteredFeaturization object at 0x7f51e3339c10>]'.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Action 'action_submit_ask_service_form' ended with events '[]'.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Current slot values: 
	policy_accepted: True
	patient_name: None
	patient_id: None
	patient_birthdate: None
	phone_number: None
	specialization_name: 
	specialization_id: None
	service_name: None
	service_id: None
	group_service_question_id: 3aa08055-07bb-4133-85e6-30c621b4bb95
	staff_name: 
	staff_id: None
	sickness_name: 
	sickness_id: None
	appointment_type_name: 
	appointment_type_id: None
	organization_unit_name: None
	organization_unit_id: None
	payment_mode_name: None
	payment_mode_id: None
	appointment_mode_name: None
	appointment_mode_id: None
	visit_accepted: None
	visit_from_date: None
	visit_to_date: None
	visit_final: None
	visit_authorization_code: None
	visit_authorization_code_id: None
	specialist_gender: None
	age_category: None
	preliminary_search_organization_units: None
	preliminary_search_payment_modes: None
	preliminary_search_appointment_modes: None
	preliminary_search_patient_age_min: None
	preliminary_search_patient_age_max: None
	patient_identified: None
	make_appointment_path: service
	internal_slot_cache: {'locked_slot_validators': [], 'locked_entities': {'patient_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175873Z'}], 'staff_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175881Z'}], 'service_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175885Z'}], 'specialization_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175889Z'}], 'appointment_type_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175893Z'}], 'sickness_name': [{'value': 'barku', 'expiration': '2021-09-06T08:59:27.3175897Z'}]}, 'previous_slots': {'group_service_question_id': '3aa08055-07bb-4133-85e6-30c621b4bb95', 'make_appointment_path': 'service', 'specialization_name': '', 'appointment_type_name': '', 'sickness_name': '', 'staff_name': ''}, 'temp': {'QuestionAnswers': [{'Id': 'f43bf1ad-1a53-4a1b-a656-87f75b98b287', 'Name': 'RTG barku Osiowe', 'ServiceId': 272, 'Answer': 'RTG barku; Prześwietlenie barku; zdjęcie barku; Rentgen barku;', 'Question': None, 'Children': None}, {'Id': '6c42e112-9457-4667-9197-7647dac42047', 'Name': 'RTG barku AP', 'ServiceId': 271, 'Answer': 'zdjęcie barku; RTG barku; Prześwietlenie barku; Rentgen barku;', 'Question': None, 'Children': None}]}}
	requested_slot: None
	session_started_metadata: None
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_listen
[state 5] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_submit_ask_service_form
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'prev_action': {'action_name': 'action_submit_ask_service_form'}}]
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:22:59 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'utter_ask_if_make_appointment' based on user intent.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 20] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 21] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 22] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 23] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 24] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 25] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_submit_ask_service_form | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:22:59 DEBUG    rasa.core.policies.rule_policy  - There is no applicable rule.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_1_TEDPolicy.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Predicted next action 'utter_ask_if_make_appointment' with confidence 1.00.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Policy prediction ended with events '[]'.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Action 'utter_ask_if_make_appointment' ended with events '[BotUttered('Czy chcesz zarezerwować termin wizyty?', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {"utter_action": "utter_ask_if_make_appointment"}, 1630920179.202911)]'.
2021-09-06 09:22:59 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 139
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - Current tracker state:
[state 0] user intent: greet
[state 1] user intent: ask_service | previous action name: action_listen
[state 2] user intent: ask_service | previous action name: action_initial_slots_extraction
[state 3] user intent: ask_service | previous action name: ask_service_form
[state 4] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_listen
[state 5] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_submit_ask_service_form
[state 6] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: utter_ask_if_make_appointment
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - Launch DeLorean...
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - Current tracker state [{}, {'prev_action': {'action_name': 'utter_ask_if_make_appointment'}}]
2021-09-06 09:22:59 DEBUG    rasa.core.policies.memoization  - There is no memorised next action
2021-09-06 09:22:59 DEBUG    rasa.core.policies.ted_policy  - TED predicted 'action_listen' based on user intent.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.rule_policy  - Current tracker state:
[state 0] user intent: greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 1] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 2] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 3] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 4] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 5] user intent: ask_service | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 6] user intent: ask_service | previous action name: action_initial_slots_extraction | slots: {'policy_accepted': (1.0, 1.0)}
[state 7] user intent: ask_service | previous action name: ask_service_form | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 8] user intent: greet | previous action name: action_listen | active loop: {'name': 'ask_service_form'} | slots: {'policy_accepted': (1.0, 1.0)}
[state 9] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 10] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 11] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 12] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 13] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 14] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 15] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 16] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 17] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 18] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 19] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 20] user intent: greet | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 21] user intent: greet | previous action name: privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 22] user intent: greet | previous action name: action_submit_privacy_policy_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 23] user intent: greet | previous action name: action_greet | slots: {'policy_accepted': (1.0, 1.0)}
[state 24] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_listen | slots: {'policy_accepted': (1.0, 1.0)}
[state 25] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: action_submit_ask_service_form | slots: {'policy_accepted': (1.0, 1.0)}
[state 26] user intent: nlu_fallback | user entities: ('service_name',) | previous action name: utter_ask_if_make_appointment | slots: {'policy_accepted': (1.0, 1.0)}
2021-09-06 09:22:59 DEBUG    rasa.core.policies.rule_policy  - There is no applicable rule.
2021-09-06 09:22:59 DEBUG    rasa.core.policies.ensemble  - Predicted next action using policy_1_TEDPolicy.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Predicted next action 'action_listen' with confidence 1.00.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Policy prediction ended with events '[]'.
2021-09-06 09:22:59 DEBUG    rasa.core.processor  - Action 'action_listen' ended with events '[]'.
2021-09-06 09:22:59 DEBUG    rasa.core.lock_store  - Deleted lock for conversation '4067385133374520'.
2021-09-06 09:23:00 DEBUG    urllib3.connectionpool  - https://graph.facebook.com:443 "POST /v2.12/me/messages?access_token=xxxxxxxxxxx HTTP/1.1" 200 35

I really don’t have any particular idea on what’s going on. No errors were reported. Model and data are prepared and trained accordingly to RASA docs. Any advise would be appreciated.

EDIT: I use InMemoryLockStore, but… I don’t think it could cause an issue. Production is in beta stage (we want to deploy, but… How to do that with this lag?:/), so not more then 3-4 testers writes with bot in the same time (usually just 1-2).

I’m dealing with the same problem! Did you get a clue? Thanks, Flor

I’ve get rid of RASA X (as mentioned) and switch tracker store from Mongo to Redis. Bot is currently deployed in production and it looks like it did the trick. I’m not using RASA X, because it lagged and from what I see - there were another reasons for that (probably associated with sanic?).

My current setup is:

  • Proxy configured on Azure Cloud (for https),
  • Redis as TrackerStore on separate server,
  • InMemory Event brocker,
  • Configured FB/Slack connector,
  • Clean RASA trained and configured for polish language.

All of that is deployed to ACI (Azure Container Instances) from docker images I build.