2022-03-04 15:34:13 DEBUG rasa.core.lock_store - Issuing ticket for conversation '7b40391b4f9946fd985429ece7002b83'. 2022-03-04 15:34:13 DEBUG rasa.core.lock_store - Acquiring lock for conversation '7b40391b4f9946fd985429ece7002b83'. 2022-03-04 15:34:13 DEBUG rasa.core.lock_store - Acquired lock for conversation '7b40391b4f9946fd985429ece7002b83'. 2022-03-04 15:34:13 DEBUG rasa.core.tracker_store - Could not find tracker for conversation ID '7b40391b4f9946fd985429ece7002b83'. 2022-03-04 15:34:13 DEBUG rasa.core.processor - Starting a new session for conversation ID '7b40391b4f9946fd985429ece7002b83'. 2022-03-04 15:34:13 DEBUG rasa.core.processor - Policy prediction ended with events '[]'. 2022-03-04 15:34:13 DEBUG rasa.core.processor - Action 'action_session_start' ended with events '[, ActionExecuted(action: action_listen, policy: None, confidence: None)]'. 2022-03-04 15:34:13 DEBUG rasa.core.processor - Current slot values: day_slot: None requested_slot: None session_started_metadata: None 2022-03-04 15:34:13 DEBUG rasa.engine.runner.dask - Running graph with inputs: {'__message__': []}, targets: ['run_RegexMessageHandler'] and ExecutionContext(model_id='16630c5aa8b74594be51fc28205d674f', should_add_diagnostic_data=False, is_finetuning=False, node_name=None). 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'nlu_message_converter' running 'NLUMessageConverter.convert_user_message'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_WhitespaceTokenizer0' running 'WhitespaceTokenizer.process'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_RegexFeaturizer1' running 'RegexFeaturizer.process'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_LexicalSyntacticFeaturizer2' running 'LexicalSyntacticFeaturizer.process'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_CountVectorsFeaturizer3' running 'CountVectorsFeaturizer.process'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_CountVectorsFeaturizer4' running 'CountVectorsFeaturizer.process'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_DIETClassifier5' running 'DIETClassifier.process'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_EntitySynonymMapper6' running 'EntitySynonymMapper.process'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_ResponseSelector7' running 'ResponseSelector.process'. 2022-03-04 15:34:13 DEBUG rasa.nlu.classifiers.diet_classifier - There is no trained model for 'ResponseSelector': The component is either not trained or didn't receive enough training data. 2022-03-04 15:34:13 DEBUG rasa.nlu.selectors.response_selector - Adding following selector key to message property: default 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_FallbackClassifier8' running 'FallbackClassifier.process'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'domain_provider' running 'DomainProvider.provide_inference'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_RegexMessageHandler' running 'RegexMessageHandler.process'. 2022-03-04 15:34:13 DEBUG rasa.core.processor - Received user message 'I want to visit you' with intent '{'name': 'visit', 'confidence': 0.9959660768508911}' and entities '[]' 2022-03-04 15:34:13 DEBUG rasa.core.processor - Logged UserUtterance - tracker now has 4 events. 2022-03-04 15:34:13 DEBUG rasa.core.actions.action - Validating extracted slots: 2022-03-04 15:34:13 DEBUG rasa.core.processor - Default action 'action_extract_slots' was executed, resulting in 0 events: 2022-03-04 15:34:13 DEBUG rasa.engine.runner.dask - Running graph with inputs: {'__tracker__': }, targets: ['select_prediction'] and ExecutionContext(model_id='16630c5aa8b74594be51fc28205d674f', should_add_diagnostic_data=False, is_finetuning=False, node_name=None). 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'rule_only_data_provider' running 'RuleOnlyDataProvider.provide'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'domain_provider' running 'DomainProvider.provide_inference'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_MemoizationPolicy0' running 'MemoizationPolicy.predict_action_probabilities'. 2022-03-04 15:34:13 DEBUG rasa.core.policies.memoization - Current tracker state: [state 0] slots: {'day_slot': (1.0,)} [state 1] user intent: visit | previous action name: action_listen | slots: {'day_slot': (1.0,)} 2022-03-04 15:34:13 DEBUG rasa.core.policies.memoization - There is no memorised next action 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_RulePolicy1' running 'RulePolicy.predict_action_probabilities'. 2022-03-04 15:34:13 DEBUG rasa.core.policies.rule_policy - Current tracker state: [state 0] slots: {'day_slot': (1.0,)} [state 1] user text: I want to visit you | previous action name: action_listen | slots: {'day_slot': (1.0,)} 2022-03-04 15:34:13 DEBUG rasa.core.policies.rule_policy - There is no applicable rule. 2022-03-04 15:34:13 DEBUG rasa.core.policies.rule_policy - Current tracker state: [state 0] slots: {'day_slot': (1.0,)} [state 1] user intent: visit | previous action name: action_listen | slots: {'day_slot': (1.0,)} 2022-03-04 15:34:13 DEBUG rasa.core.policies.rule_policy - There is a rule for the next action 'form_request_day'. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_TEDPolicy3' running 'TEDPolicy.predict_action_probabilities'. 2022-03-04 15:34:13 DEBUG rasa.core.policies.ted_policy - TED predicted 'utter_greet' based on user intent. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'run_UnexpecTEDIntentPolicy2' running 'UnexpecTEDIntentPolicy.predict_action_probabilities'. 2022-03-04 15:34:13 DEBUG rasa.core.policies.unexpected_intent_policy - Querying for intent `visit`. 2022-03-04 15:34:13 DEBUG rasa.core.policies.unexpected_intent_policy - Query intent index 12 not found in label thresholds - {0: -0.9634993, 3: -1.3364615, 5: -1.1911387, 6: -0.50831115, 7: -0.29682404}. Check for `action_unlikely_intent` prediction will be skipped. 2022-03-04 15:34:13 DEBUG rasa.engine.graph - Node 'select_prediction' running 'DefaultPolicyPredictionEnsemble.combine_predictions_from_kwargs'. 2022-03-04 15:34:13 DEBUG rasa.core.policies.ensemble - Made prediction using user intent. 2022-03-04 15:34:14 DEBUG rasa.core.policies.ensemble - Added `DefinePrevUserUtteredFeaturization(False)` event. 2022-03-04 15:34:14 DEBUG rasa.core.policies.ensemble - Predicted next action using RulePolicy. 2022-03-04 15:34:14 DEBUG rasa.core.processor - Predicted next action 'form_request_day' with confidence 1.00. 2022-03-04 15:34:14 DEBUG rasa.core.actions.forms - Activated the form 'form_request_day'. 2022-03-04 15:34:14 DEBUG rasa.core.actions.forms - Validating pre-filled required slots: {'day_slot': 'None'} 2022-03-04 15:34:14 DEBUG rasa.core.actions.forms - Validating extracted slots: {'day_slot': 'None'} 2022-03-04 15:34:14 DEBUG rasa.core.actions.action - Calling action endpoint to run action 'validate_form_request_day'. 2022-03-04 15:34:14 DEBUG rasa.core.actions.forms - Request next slot 'day_slot' 2022-03-04 15:34:14 DEBUG rasa.core.actions.action - Calling action endpoint to run action 'action_ask_day_slot'. 2022-03-04 15:34:14 DEBUG rasa.core.processor - Policy prediction ended with events '[]'. 2022-03-04 15:34:14 DEBUG rasa.core.processor - Action 'form_request_day' ended with events '[, , , BotUttered('on which day?', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {"utter_action": "utter_myask_day", "template": "utter_myask_day"}, 1646404454.2847936)]'. 2022-03-04 15:34:14 DEBUG rasa.core.processor - Current slot values: day_slot: None requested_slot: day_slot session_started_metadata: None 2022-03-04 15:34:14 DEBUG rasa.engine.runner.dask - Running graph with inputs: {'__tracker__': }, targets: ['select_prediction'] and ExecutionContext(model_id='16630c5aa8b74594be51fc28205d674f', should_add_diagnostic_data=False, is_finetuning=False, node_name=None). 2022-03-04 15:34:14 DEBUG rasa.engine.graph - Node 'rule_only_data_provider' running 'RuleOnlyDataProvider.provide'. 2022-03-04 15:34:14 DEBUG rasa.engine.graph - Node 'domain_provider' running 'DomainProvider.provide_inference'. 2022-03-04 15:34:14 DEBUG rasa.engine.graph - Node 'run_MemoizationPolicy0' running 'MemoizationPolicy.predict_action_probabilities'. 2022-03-04 15:34:14 DEBUG rasa.core.policies.memoization - Current tracker state: [state 0] slots: {'day_slot': (1.0,)} 2022-03-04 15:34:14 DEBUG rasa.core.policies.memoization - There is no memorised next action 2022-03-04 15:34:14 DEBUG rasa.engine.graph - Node 'run_RulePolicy1' running 'RulePolicy.predict_action_probabilities'. 2022-03-04 15:34:14 DEBUG rasa.core.policies.rule_policy - Predicted 'action_listen' after loop 'form_request_day'. 2022-03-04 15:34:14 DEBUG rasa.engine.graph - Node 'run_TEDPolicy3' running 'TEDPolicy.predict_action_probabilities'. 2022-03-04 15:34:14 DEBUG rasa.core.policies.ted_policy - TED predicted 'action_listen' based on user intent. 2022-03-04 15:34:14 DEBUG rasa.engine.graph - Node 'run_UnexpecTEDIntentPolicy2' running 'UnexpecTEDIntentPolicy.predict_action_probabilities'. 2022-03-04 15:34:14 DEBUG rasa.core.policies.unexpected_intent_policy - Skipping predictions for UnexpecTEDIntentPolicy as either there is no event of type `UserUttered`, event's intent is new and not in domain or there is an event of type `ActionExecuted` after the last `UserUttered`. 2022-03-04 15:34:14 DEBUG rasa.engine.graph - Node 'select_prediction' running 'DefaultPolicyPredictionEnsemble.combine_predictions_from_kwargs'. 2022-03-04 15:34:14 DEBUG rasa.core.policies.ensemble - Predicted next action using RulePolicy. 2022-03-04 15:34:14 DEBUG rasa.core.processor - Predicted next action 'action_listen' with confidence 1.00. 2022-03-04 15:34:14 DEBUG rasa.core.processor - Policy prediction ended with events '[]'. 2022-03-04 15:34:14 DEBUG rasa.core.processor - Action 'action_listen' ended with events '[]'. 2022-03-04 15:34:14 DEBUG rasa.core.lock_store - Deleted lock for conversation '7b40391b4f9946fd985429ece7002b83'. on which day? Your input -> are you a bot 2022-03-04 15:34:18 DEBUG rasa.core.lock_store - Issuing ticket for conversation '7b40391b4f9946fd985429ece7002b83'. 2022-03-04 15:34:18 DEBUG rasa.core.lock_store - Acquiring lock for conversation '7b40391b4f9946fd985429ece7002b83'. 2022-03-04 15:34:18 DEBUG rasa.core.lock_store - Acquired lock for conversation '7b40391b4f9946fd985429ece7002b83'. 2022-03-04 15:34:18 DEBUG rasa.core.tracker_store - Recreating tracker for id '7b40391b4f9946fd985429ece7002b83' 2022-03-04 15:34:18 DEBUG rasa.engine.runner.dask - Running graph with inputs: {'__message__': []}, targets: ['run_RegexMessageHandler'] and ExecutionContext(model_id='16630c5aa8b74594be51fc28205d674f', should_add_diagnostic_data=False, is_finetuning=False, node_name=None). 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'nlu_message_converter' running 'NLUMessageConverter.convert_user_message'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_WhitespaceTokenizer0' running 'WhitespaceTokenizer.process'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_RegexFeaturizer1' running 'RegexFeaturizer.process'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_LexicalSyntacticFeaturizer2' running 'LexicalSyntacticFeaturizer.process'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_CountVectorsFeaturizer3' running 'CountVectorsFeaturizer.process'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_CountVectorsFeaturizer4' running 'CountVectorsFeaturizer.process'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_DIETClassifier5' running 'DIETClassifier.process'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_EntitySynonymMapper6' running 'EntitySynonymMapper.process'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_ResponseSelector7' running 'ResponseSelector.process'. 2022-03-04 15:34:18 DEBUG rasa.nlu.classifiers.diet_classifier - There is no trained model for 'ResponseSelector': The component is either not trained or didn't receive enough training data. 2022-03-04 15:34:18 DEBUG rasa.nlu.selectors.response_selector - Adding following selector key to message property: default 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_FallbackClassifier8' running 'FallbackClassifier.process'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'domain_provider' running 'DomainProvider.provide_inference'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_RegexMessageHandler' running 'RegexMessageHandler.process'. 2022-03-04 15:34:18 DEBUG rasa.core.processor - Received user message 'are you a bot' with intent '{'name': 'bot_challenge', 'confidence': 0.9996732473373413}' and entities '[]' 2022-03-04 15:34:18 DEBUG rasa.core.processor - Logged UserUtterance - tracker now has 12 events. 2022-03-04 15:34:18 DEBUG rasa.core.actions.action - Validating extracted slots: 2022-03-04 15:34:18 DEBUG rasa.core.processor - Default action 'action_extract_slots' was executed, resulting in 0 events: 2022-03-04 15:34:18 DEBUG rasa.engine.runner.dask - Running graph with inputs: {'__tracker__': }, targets: ['select_prediction'] and ExecutionContext(model_id='16630c5aa8b74594be51fc28205d674f', should_add_diagnostic_data=False, is_finetuning=False, node_name=None). 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'rule_only_data_provider' running 'RuleOnlyDataProvider.provide'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'domain_provider' running 'DomainProvider.provide_inference'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_MemoizationPolicy0' running 'MemoizationPolicy.predict_action_probabilities'. 2022-03-04 15:34:18 DEBUG rasa.core.policies.memoization - Current tracker state: [state 0] slots: {'day_slot': (1.0,)} [state 1] user intent: bot_challenge | previous action name: action_listen 2022-03-04 15:34:18 DEBUG rasa.core.policies.memoization - There is no memorised next action 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_RulePolicy1' running 'RulePolicy.predict_action_probabilities'. 2022-03-04 15:34:18 DEBUG rasa.core.policies.rule_policy - Current tracker state: [state 0] slots: {'day_slot': (1.0,)} [state 1] user intent: visit | previous action name: action_listen | slots: {'day_slot': (1.0,)} [state 2] user intent: visit | previous action name: form_request_day | active loop: {'name': 'form_request_day'} [state 3] user text: are you a bot | previous action name: action_listen | active loop: {'name': 'form_request_day'} 2022-03-04 15:34:18 DEBUG rasa.core.policies.rule_policy - There is no applicable rule. 2022-03-04 15:34:18 DEBUG rasa.core.policies.rule_policy - Predicted loop 'form_request_day'. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_TEDPolicy3' running 'TEDPolicy.predict_action_probabilities'. 2022-03-04 15:34:18 DEBUG rasa.core.policies.ted_policy - TED predicted 'utter_happy' based on user intent. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'run_UnexpecTEDIntentPolicy2' running 'UnexpecTEDIntentPolicy.predict_action_probabilities'. 2022-03-04 15:34:18 DEBUG rasa.core.policies.unexpected_intent_policy - Querying for intent `bot_challenge`. 2022-03-04 15:34:18 DEBUG rasa.core.policies.unexpected_intent_policy - Query intent index 2 not found in label thresholds - {0: -0.9634993, 3: -1.3364615, 5: -1.1911387, 6: -0.50831115, 7: -0.29682404}. Check for `action_unlikely_intent` prediction will be skipped. 2022-03-04 15:34:18 DEBUG rasa.engine.graph - Node 'select_prediction' running 'DefaultPolicyPredictionEnsemble.combine_predictions_from_kwargs'. 2022-03-04 15:34:18 DEBUG rasa.core.policies.ensemble - Made prediction using user intent. 2022-03-04 15:34:18 DEBUG rasa.core.policies.ensemble - Added `DefinePrevUserUtteredFeaturization(False)` event. 2022-03-04 15:34:18 DEBUG rasa.core.policies.ensemble - Predicted next action using RulePolicy. 2022-03-04 15:34:18 DEBUG rasa.core.processor - Predicted next action 'form_request_day' with confidence 1.00. 2022-03-04 15:34:18 DEBUG rasa.core.actions.forms - Validating user input 'UserUttered(text: are you a bot, intent: bot_challenge, use_text_for_featurization: False)'. 2022-03-04 15:34:18 DEBUG rasa.core.actions.forms - Validating extracted slots: {} 2022-03-04 15:34:18 DEBUG rasa.core.actions.action - Calling action endpoint to run action 'validate_form_request_day'. 2022-03-04 15:34:19 DEBUG rasa.core.actions.forms - Request next slot 'day_slot' 2022-03-04 15:34:19 DEBUG rasa.core.actions.action - Calling action endpoint to run action 'action_ask_day_slot'. 2022-03-04 15:34:19 DEBUG rasa.core.processor - Policy prediction ended with events '[]'. 2022-03-04 15:34:19 DEBUG rasa.core.processor - Action 'form_request_day' ended with events '[, , BotUttered('on which day?', {"elements": null, "quick_replies": null, "buttons": null, "attachment": null, "image": null, "custom": null}, {"utter_action": "utter_myask_day", "template": "utter_myask_day"}, 1646404459.1439912)]'. 2022-03-04 15:34:19 DEBUG rasa.core.processor - Current slot values: day_slot: None requested_slot: day_slot session_started_metadata: None 2022-03-04 15:34:19 DEBUG rasa.engine.runner.dask - Running graph with inputs: {'__tracker__': }, targets: ['select_prediction'] and ExecutionContext(model_id='16630c5aa8b74594be51fc28205d674f', should_add_diagnostic_data=False, is_finetuning=False, node_name=None). 2022-03-04 15:34:19 DEBUG rasa.engine.graph - Node 'rule_only_data_provider' running 'RuleOnlyDataProvider.provide'. 2022-03-04 15:34:19 DEBUG rasa.engine.graph - Node 'domain_provider' running 'DomainProvider.provide_inference'. 2022-03-04 15:34:19 DEBUG rasa.engine.graph - Node 'run_MemoizationPolicy0' running 'MemoizationPolicy.predict_action_probabilities'. 2022-03-04 15:34:19 DEBUG rasa.core.policies.memoization - Current tracker state: [state 0] slots: {'day_slot': (1.0,)} 2022-03-04 15:34:19 DEBUG rasa.core.policies.memoization - There is no memorised next action 2022-03-04 15:34:19 DEBUG rasa.engine.graph - Node 'run_RulePolicy1' running 'RulePolicy.predict_action_probabilities'. 2022-03-04 15:34:19 DEBUG rasa.core.policies.rule_policy - Predicted 'action_listen' after loop 'form_request_day'. 2022-03-04 15:34:19 DEBUG rasa.engine.graph - Node 'run_TEDPolicy3' running 'TEDPolicy.predict_action_probabilities'. 2022-03-04 15:34:19 DEBUG rasa.core.policies.ted_policy - TED predicted 'action_listen' based on user intent. 2022-03-04 15:34:19 DEBUG rasa.engine.graph - Node 'run_UnexpecTEDIntentPolicy2' running 'UnexpecTEDIntentPolicy.predict_action_probabilities'. 2022-03-04 15:34:19 DEBUG rasa.core.policies.unexpected_intent_policy - Skipping predictions for UnexpecTEDIntentPolicy as either there is no event of type `UserUttered`, event's intent is new and not in domain or there is an event of type `ActionExecuted` after the last `UserUttered`. 2022-03-04 15:34:19 DEBUG rasa.engine.graph - Node 'select_prediction' running 'DefaultPolicyPredictionEnsemble.combine_predictions_from_kwargs'. 2022-03-04 15:34:19 DEBUG rasa.core.policies.ensemble - Predicted next action using RulePolicy. 2022-03-04 15:34:19 DEBUG rasa.core.processor - Predicted next action 'action_listen' with confidence 1.00. 2022-03-04 15:34:19 DEBUG rasa.core.processor - Policy prediction ended with events '[]'. 2022-03-04 15:34:19 DEBUG rasa.core.processor - Action 'action_listen' ended with events '[]'. 2022-03-04 15:34:19 DEBUG rasa.core.lock_store - Deleted lock for conversation '7b40391b4f9946fd985429ece7002b83'. on which day? Your input ->