------------------------------------------------------------------------ ACK sip:drachtio@192.168.1.53:5080;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.1.53;rport;branch=z9hG4bKe3KUNSyFvNHvg Max-Forwards: 70 From: ;tag=e3gj17ryjmUyc To: ;tag=cmyDp5gpcZ5cj Call-ID: 115ff229-8a37-1238-3b90-000c296e5b76 CSeq: 12793202 ACK Content-Length: 0 2019-11-25 09:59:49.175931 [DEBUG] sofia.c:7290 Channel sofia/drachtio_mrf/nobody@192.168.1.53:5060 entering state [ready][200] 2019-11-25 09:59:49.275986 [ALERT] switch_rtp.c:1595 sofia/drachtio_mrf/nobody@192.168.1.53:5060 audio stat 100.00 0/0 flaws: 0 mos: 4.50 v: 0.00 0.00/0.00 2019-11-25 09:59:49.275986 [ALERT] switch_rtp.c:1595 sofia/drachtio_mrf/nobody@192.168.1.53:5060 audio stat 100.00 0/0 flaws: 0 mos: 4.50 v: 0.00 0.00/0.00 2019-11-25 09:59:49.275986 [DEBUG] switch_ivr.c:632 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Command Execute [depth=1] answer(undefined) 2019-11-25 09:59:49.275986 [ALERT] switch_core_session.c:2662 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Send KeyFrame EXECUTE [depth=1] sofia/drachtio_mrf/nobody@192.168.1.53:5060 answer(undefined) 2019-11-25 09:59:49.275986 [ALERT] switch_core_session.c:2889 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC] 2019-11-25 09:59:49.275986 [ALERT] switch_core_session.c:2905 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC_COMPLETE] 2019-11-25 09:59:49.296028 [ALERT] switch_ivr.c:679 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [AUDIO_SYNC] 2019-11-25 09:59:49.335676 [DEBUG] mod_dialogflow.c:146 command 3a01d1ae-0f94-11ea-b754-2335b9dc4942 fourth-cedar-251319 en-US 30 2019-11-25 09:59:49.335676 [INFO] mod_dialogflow.c:96 starting dialogflow with project fourth-cedar-251319, language en-US, event 30, text (null). 2019-11-25 09:59:49.335676 [DEBUG] google_glue.cpp:89 GStreamer::startStream set event 30, text (null) 0x7f3468006a40 2019-11-25 09:59:49.515910 [INFO] mod_dialogflow.c:52 Got SWITCH_ABC_TYPE_INIT. 2019-11-25 09:59:49.515910 [DEBUG] switch_core_media_bug.c:970 Attaching BUG to sofia/drachtio_mrf/nobody@192.168.1.53:5060 2019-11-25 09:59:49.515910 [DEBUG] google_glue.cpp:179 grpc_read_thread: starting cb 0x7f3444719900 2019-11-25 09:59:49.515910 [ALERT] switch_core_media.c:12382 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [HARD_MUTE] 2019-11-25 09:59:49.535906 [ALERT] switch_core_io.c:416 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [TRANSCODING_NECESSARY] 2019-11-25 09:59:50.015792 [INFO] parser.cpp:20 GStrGRPCParser - parsing StreamingDetectIntentResponse 2019-11-25 09:59:50.015792 [INFO] parser.cpp:33 GStrGRPCParser - adding query result 2019-11-25 09:59:50.015792 [INFO] parser.cpp:45 GStrGRPCParser - adding audio config 2019-11-25 09:59:50.015792 [INFO] mod_dialogflow.c:26 json payload for type dialogflow::intent: {"response_id":"e9affd91-454c-4c87-9eff-77dd3505897b-9cc28bb4","query_result":{"query_text":"","language_code":"en","speech_recognition_confidence":0,"action":"input.unknown","parameters":{},"all_required_params_present":true,"fulfillment_text":"Sorry, could you say that again?","fulfillment_messages":[{"platform":"PLATFORM_UNSPECIFIED","text":{"text":["Sorry, could you say that again?"]}}],"webhook_source":"","output_contexts":[],"intent":{"name":"projects/fourth-cedar-251319/agent/intents/0db76238-e18b-437b-ad93-ff06a79fc11f","display_name":"Default Fallback Intent","webhook_state":"WEBHOOK_STATE_UNSPECIFIED","priority":0,"is_fallback":true,"ml_disabled":false,"end_interaction":false,"input_context_names":[],"events":[],"training_phrases":[],"action":"","output_contexts":[],"reset_contexts":false,"parameters":[],"messages":[],"default_response_platforms":[],"root_followup_intent_name":"","followup_intent_info":[]},"intent_detection_confidence":1,"sentiment_analysis_result":{"query_text_sentiment":{"score":0,"magnitude":0}},"knowledge_answers":{"answers":[]}},"alternative_query_results":[],"webhook_status":{"code":0,"message":""},"output_audio_config":{"audio_encoding":"OUTPUT_AUDIO_ENCODING_LINEAR_16","sample_rate_hertz":0,"synthesize_speech_config":{"speaking_rate":1.200000,"pitch":-2,"volume_gain_db":8,"effects_profile_id":["telephony-class-application"],"voice":{"name":"en-US-Wavenet-D","ssml_gender":"SSML_VOICE_GENDER_UNSPECIFIED"}}}}. 2019-11-25 09:59:50.275672 [DEBUG] google_glue.cpp:218 grpc_read_thread: received audio to play 2019-11-25 09:59:50.275672 [DEBUG] google_glue.cpp:222 grpc_read_thread: encoding is 1 2019-11-25 09:59:50.275672 [DEBUG] google_glue.cpp:236 grpc_read_thread: wrote audio to /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_1.wav 2019-11-25 09:59:50.275672 [INFO] mod_dialogflow.c:26 json payload for type dialogflow::audio_provided: {"path":"/tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_1.wav"}. 2019-11-25 09:59:50.275672 [DEBUG] google_glue.cpp:256 dialogflow read loop is done 2019-11-25 09:59:50.275672 [DEBUG] google_glue.cpp:132 GStreamer::finish 0x7f3468006a40 2019-11-25 09:59:50.275672 [DEBUG] google_glue.cpp:276 dialogflow read thread exiting 2019-11-25 09:59:50.355718 [DEBUG] switch_ivr.c:632 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Command Execute [depth=1] playback(/tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_1.wav) 2019-11-25 09:59:50.355718 [ALERT] switch_core_session.c:2662 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Send KeyFrame EXECUTE [depth=1] sofia/drachtio_mrf/nobody@192.168.1.53:5060 playback(/tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_1.wav) 2019-11-25 09:59:50.355718 [ALERT] switch_core_session.c:2889 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC] 2019-11-25 09:59:50.355718 [DEBUG] switch_core_file.c:391 File /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_1.wav sample rate 24000 doesn't match requested rate 8000 2019-11-25 09:59:50.355718 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@8000hz 1 channels 20ms 2019-11-25 09:59:50.355718 [ALERT] switch_ivr_play_say.c:1423 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [AUDIO_SYNC] 2019-11-25 09:59:51.916026 [DEBUG] switch_ivr_play_say.c:1933 done playing file /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_1.wav 2019-11-25 09:59:51.916026 [ALERT] switch_core_session.c:2905 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC_COMPLETE] 2019-11-25 09:59:51.935621 [ALERT] switch_core_io.c:416 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [TRANSCODING_NECESSARY] 2019-11-25 09:59:51.935621 [ALERT] switch_ivr.c:679 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [AUDIO_SYNC] 2019-11-25 09:59:52.095685 [DEBUG] mod_dialogflow.c:146 command 3a01d1ae-0f94-11ea-b754-2335b9dc4942 fourth-cedar-251319 en-US 30 2019-11-25 09:59:52.095685 [INFO] mod_dialogflow.c:86 a dialogflow is already running on this channel, we will stop it. 2019-11-25 09:59:52.095685 [INFO] mod_dialogflow.c:130 Received user command command to stop dialogflow. 2019-11-25 09:59:52.095685 [DEBUG] google_glue.cpp:370 google_dialogflow_session_cleanup: acquiring lock 2019-11-25 09:59:52.095685 [DEBUG] google_glue.cpp:372 google_dialogflow_session_cleanup: acquired lock 2019-11-25 09:59:52.095685 [DEBUG] google_glue.cpp:375 google_dialogflow_session_cleanup: sending writesDone.. 2019-11-25 09:59:52.095685 [DEBUG] google_glue.cpp:132 GStreamer::finish 0x7f3468006a40 2019-11-25 09:59:52.095685 [INFO] google_glue.cpp:381 google_dialogflow_session_cleanup: waiting for read thread to complete 2019-11-25 09:59:52.095685 [INFO] google_glue.cpp:384 google_dialogflow_session_cleanup: read thread completed 2019-11-25 09:59:52.095685 [DEBUG] google_glue.cpp:79 GStreamer::~GStreamer wrote 38 packets 0x7f3468006a40 2019-11-25 09:59:52.095685 [INFO] mod_dialogflow.c:57 Got SWITCH_ABC_TYPE_CLOSE. 2019-11-25 09:59:52.095685 [INFO] google_glue.cpp:397 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Bug is not attached. 2019-11-25 09:59:52.095685 [DEBUG] mod_dialogflow.c:60 Finished SWITCH_ABC_TYPE_CLOSE. 2019-11-25 09:59:52.095685 [DEBUG] switch_core_media_bug.c:1289 Removing BUG from sofia/drachtio_mrf/nobody@192.168.1.53:5060 2019-11-25 09:59:52.095685 [INFO] google_glue.cpp:392 google_dialogflow_session_cleanup: Closed google session 2019-11-25 09:59:52.095685 [INFO] mod_dialogflow.c:132 stopped dialogflow. 2019-11-25 09:59:52.095685 [INFO] mod_dialogflow.c:96 starting dialogflow with project fourth-cedar-251319, language en-US, event 30, text (null). 2019-11-25 09:59:52.095685 [DEBUG] google_glue.cpp:89 GStreamer::startStream set event 30, text (null) 0x7f3468148130 2019-11-25 09:59:52.136096 [INFO] mod_dialogflow.c:52 Got SWITCH_ABC_TYPE_INIT. 2019-11-25 09:59:52.136096 [DEBUG] switch_core_media_bug.c:970 Attaching BUG to sofia/drachtio_mrf/nobody@192.168.1.53:5060 2019-11-25 09:59:52.136096 [DEBUG] google_glue.cpp:179 grpc_read_thread: starting cb 0x7f34681569d0 2019-11-25 09:59:52.136096 [ALERT] switch_core_media.c:12382 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [HARD_MUTE] 2019-11-25 09:59:52.636088 [INFO] parser.cpp:20 GStrGRPCParser - parsing StreamingDetectIntentResponse 2019-11-25 09:59:52.636088 [INFO] parser.cpp:33 GStrGRPCParser - adding query result 2019-11-25 09:59:52.636088 [INFO] parser.cpp:45 GStrGRPCParser - adding audio config 2019-11-25 09:59:52.636088 [INFO] mod_dialogflow.c:26 json payload for type dialogflow::intent: {"response_id":"98aaaa0d-f11b-4b40-a1dd-377d88d3301f-9cc28bb4","query_result":{"query_text":"","language_code":"en","speech_recognition_confidence":0,"action":"input.unknown","parameters":{},"all_required_params_present":true,"fulfillment_text":"I missed what you said. What was that?","fulfillment_messages":[{"platform":"PLATFORM_UNSPECIFIED","text":{"text":["I missed what you said. What was that?"]}}],"webhook_source":"","output_contexts":[],"intent":{"name":"projects/fourth-cedar-251319/agent/intents/0db76238-e18b-437b-ad93-ff06a79fc11f","display_name":"Default Fallback Intent","webhook_state":"WEBHOOK_STATE_UNSPECIFIED","priority":0,"is_fallback":true,"ml_disabled":false,"end_interaction":false,"input_context_names":[],"events":[],"training_phrases":[],"action":"","output_contexts":[],"reset_contexts":false,"parameters":[],"messages":[],"default_response_platforms":[],"root_followup_intent_name":"","followup_intent_info":[]},"intent_detection_confidence":1,"sentiment_analysis_result":{"query_text_sentiment":{"score":0,"magnitude":0}},"knowledge_answers":{"answers":[]}},"alternative_query_results":[],"webhook_status":{"code":0,"message":""},"output_audio_config":{"audio_encoding":"OUTPUT_AUDIO_ENCODING_LINEAR_16","sample_rate_hertz":0,"synthesize_speech_config":{"speaking_rate":1.200000,"pitch":-2,"volume_gain_db":8,"effects_profile_id":["telephony-class-application"],"voice":{"name":"en-US-Wavenet-D","ssml_gender":"SSML_VOICE_GENDER_UNSPECIFIED"}}}}. 2019-11-25 09:59:52.915801 [DEBUG] google_glue.cpp:218 grpc_read_thread: received audio to play 2019-11-25 09:59:52.915801 [DEBUG] google_glue.cpp:222 grpc_read_thread: encoding is 1 2019-11-25 09:59:52.915801 [DEBUG] google_glue.cpp:236 grpc_read_thread: wrote audio to /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_2.wav 2019-11-25 09:59:52.915801 [INFO] mod_dialogflow.c:26 json payload for type dialogflow::audio_provided: {"path":"/tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_2.wav"}. 2019-11-25 09:59:52.915801 [DEBUG] google_glue.cpp:256 dialogflow read loop is done 2019-11-25 09:59:52.915801 [DEBUG] google_glue.cpp:132 GStreamer::finish 0x7f3468148130 2019-11-25 09:59:52.915801 [DEBUG] google_glue.cpp:276 dialogflow read thread exiting 2019-11-25 09:59:52.935649 [DEBUG] switch_ivr.c:632 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Command Execute [depth=1] playback(/tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_2.wav) 2019-11-25 09:59:52.935649 [ALERT] switch_core_session.c:2662 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Send KeyFrame EXECUTE [depth=1] sofia/drachtio_mrf/nobody@192.168.1.53:5060 playback(/tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_2.wav) 2019-11-25 09:59:52.935649 [ALERT] switch_core_session.c:2889 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC] 2019-11-25 09:59:52.935649 [DEBUG] switch_core_file.c:391 File /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_2.wav sample rate 24000 doesn't match requested rate 8000 2019-11-25 09:59:52.935649 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@8000hz 1 channels 20ms 2019-11-25 09:59:52.935649 [ALERT] switch_ivr_play_say.c:1423 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [AUDIO_SYNC] 2019-11-25 09:59:54.835779 [DEBUG] switch_ivr_play_say.c:1933 done playing file /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_2.wav 2019-11-25 09:59:54.835779 [ALERT] switch_core_session.c:2905 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC_COMPLETE] 2019-11-25 09:59:54.855615 [ALERT] switch_core_io.c:416 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [TRANSCODING_NECESSARY] 2019-11-25 09:59:54.855615 [ALERT] switch_ivr.c:679 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [AUDIO_SYNC] 2019-11-25 09:59:54.955793 [DEBUG] mod_dialogflow.c:146 command 3a01d1ae-0f94-11ea-b754-2335b9dc4942 fourth-cedar-251319 en-US 30 2019-11-25 09:59:54.955793 [INFO] mod_dialogflow.c:86 a dialogflow is already running on this channel, we will stop it. 2019-11-25 09:59:54.955793 [INFO] mod_dialogflow.c:130 Received user command command to stop dialogflow. 2019-11-25 09:59:54.955793 [DEBUG] google_glue.cpp:370 google_dialogflow_session_cleanup: acquiring lock 2019-11-25 09:59:54.955793 [DEBUG] google_glue.cpp:372 google_dialogflow_session_cleanup: acquired lock 2019-11-25 09:59:54.955793 [DEBUG] google_glue.cpp:375 google_dialogflow_session_cleanup: sending writesDone.. 2019-11-25 09:59:54.955793 [DEBUG] google_glue.cpp:132 GStreamer::finish 0x7f3468148130 2019-11-25 09:59:54.955793 [INFO] google_glue.cpp:381 google_dialogflow_session_cleanup: waiting for read thread to complete 2019-11-25 09:59:54.955793 [INFO] google_glue.cpp:384 google_dialogflow_session_cleanup: read thread completed 2019-11-25 09:59:54.955793 [DEBUG] google_glue.cpp:79 GStreamer::~GStreamer wrote 39 packets 0x7f3468148130 2019-11-25 09:59:54.955793 [INFO] mod_dialogflow.c:57 Got SWITCH_ABC_TYPE_CLOSE. 2019-11-25 09:59:54.955793 [INFO] google_glue.cpp:397 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Bug is not attached. 2019-11-25 09:59:54.955793 [DEBUG] mod_dialogflow.c:60 Finished SWITCH_ABC_TYPE_CLOSE. 2019-11-25 09:59:54.955793 [DEBUG] switch_core_media_bug.c:1289 Removing BUG from sofia/drachtio_mrf/nobody@192.168.1.53:5060 2019-11-25 09:59:54.955793 [INFO] google_glue.cpp:392 google_dialogflow_session_cleanup: Closed google session 2019-11-25 09:59:54.955793 [INFO] mod_dialogflow.c:132 stopped dialogflow. 2019-11-25 09:59:54.955793 [INFO] mod_dialogflow.c:96 starting dialogflow with project fourth-cedar-251319, language en-US, event 30, text (null). 2019-11-25 09:59:54.955793 [DEBUG] google_glue.cpp:89 GStreamer::startStream set event 30, text (null) 0x7f346811f810 2019-11-25 09:59:55.015677 [INFO] mod_dialogflow.c:52 Got SWITCH_ABC_TYPE_INIT. 2019-11-25 09:59:55.015677 [DEBUG] switch_core_media_bug.c:970 Attaching BUG to sofia/drachtio_mrf/nobody@192.168.1.53:5060 2019-11-25 09:59:55.015677 [DEBUG] google_glue.cpp:179 grpc_read_thread: starting cb 0x7f3468164db0 2019-11-25 09:59:55.015677 [ALERT] switch_core_media.c:12382 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [HARD_MUTE] 2019-11-25 09:59:55.475773 [INFO] parser.cpp:20 GStrGRPCParser - parsing StreamingDetectIntentResponse 2019-11-25 09:59:55.475773 [INFO] parser.cpp:33 GStrGRPCParser - adding query result 2019-11-25 09:59:55.475773 [INFO] parser.cpp:45 GStrGRPCParser - adding audio config 2019-11-25 09:59:55.475773 [INFO] mod_dialogflow.c:26 json payload for type dialogflow::intent: {"response_id":"dbcf5ec7-d86d-4757-820d-92057b0608e9-9cc28bb4","query_result":{"query_text":"","language_code":"en","speech_recognition_confidence":0,"action":"input.unknown","parameters":{},"all_required_params_present":true,"fulfillment_text":"What was that?","fulfillment_messages":[{"platform":"PLATFORM_UNSPECIFIED","text":{"text":["What was that?"]}}],"webhook_source":"","output_contexts":[],"intent":{"name":"projects/fourth-cedar-251319/agent/intents/0db76238-e18b-437b-ad93-ff06a79fc11f","display_name":"Default Fallback Intent","webhook_state":"WEBHOOK_STATE_UNSPECIFIED","priority":0,"is_fallback":true,"ml_disabled":false,"end_interaction":false,"input_context_names":[],"events":[],"training_phrases":[],"action":"","output_contexts":[],"reset_contexts":false,"parameters":[],"messages":[],"default_response_platforms":[],"root_followup_intent_name":"","followup_intent_info":[]},"intent_detection_confidence":1,"sentiment_analysis_result":{"query_text_sentiment":{"score":0,"magnitude":0}},"knowledge_answers":{"answers":[]}},"alternative_query_results":[],"webhook_status":{"code":0,"message":""},"output_audio_config":{"audio_encoding":"OUTPUT_AUDIO_ENCODING_LINEAR_16","sample_rate_hertz":0,"synthesize_speech_config":{"speaking_rate":1.200000,"pitch":-2,"volume_gain_db":8,"effects_profile_id":["telephony-class-application"],"voice":{"name":"en-US-Wavenet-D","ssml_gender":"SSML_VOICE_GENDER_UNSPECIFIED"}}}}. 2019-11-25 09:59:55.695649 [DEBUG] google_glue.cpp:218 grpc_read_thread: received audio to play 2019-11-25 09:59:55.695649 [DEBUG] google_glue.cpp:222 grpc_read_thread: encoding is 1 2019-11-25 09:59:55.695649 [DEBUG] google_glue.cpp:236 grpc_read_thread: wrote audio to /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_3.wav 2019-11-25 09:59:55.695649 [INFO] mod_dialogflow.c:26 json payload for type dialogflow::audio_provided: {"path":"/tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_3.wav"}. 2019-11-25 09:59:55.695649 [DEBUG] google_glue.cpp:256 dialogflow read loop is done 2019-11-25 09:59:55.695649 [DEBUG] google_glue.cpp:132 GStreamer::finish 0x7f346811f810 2019-11-25 09:59:55.695649 [DEBUG] google_glue.cpp:276 dialogflow read thread exiting 2019-11-25 09:59:55.715639 [DEBUG] switch_ivr.c:632 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Command Execute [depth=1] playback(/tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_3.wav) 2019-11-25 09:59:55.715639 [ALERT] switch_core_session.c:2662 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Send KeyFrame EXECUTE [depth=1] sofia/drachtio_mrf/nobody@192.168.1.53:5060 playback(/tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_3.wav) 2019-11-25 09:59:55.715639 [ALERT] switch_core_session.c:2889 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC] 2019-11-25 09:59:55.715639 [DEBUG] switch_core_file.c:391 File /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_3.wav sample rate 24000 doesn't match requested rate 8000 2019-11-25 09:59:55.715639 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@8000hz 1 channels 20ms 2019-11-25 09:59:55.715639 [ALERT] switch_ivr_play_say.c:1423 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [AUDIO_SYNC] 2019-11-25 09:59:56.455706 [DEBUG] switch_ivr_play_say.c:1933 done playing file /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_3.wav 2019-11-25 09:59:56.455706 [ALERT] switch_core_session.c:2905 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC_COMPLETE] 2019-11-25 09:59:56.475819 [ALERT] switch_core_io.c:416 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [TRANSCODING_NECESSARY] 2019-11-25 09:59:56.475819 [ALERT] switch_ivr.c:679 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [AUDIO_SYNC] 2019-11-25 09:59:56.535625 [DEBUG] mod_dialogflow.c:146 command 3a01d1ae-0f94-11ea-b754-2335b9dc4942 fourth-cedar-251319 en-US 30 2019-11-25 09:59:56.535625 [INFO] mod_dialogflow.c:86 a dialogflow is already running on this channel, we will stop it. 2019-11-25 09:59:56.535625 [INFO] mod_dialogflow.c:130 Received user command command to stop dialogflow. 2019-11-25 09:59:56.535625 [DEBUG] google_glue.cpp:370 google_dialogflow_session_cleanup: acquiring lock 2019-11-25 09:59:56.535625 [DEBUG] google_glue.cpp:372 google_dialogflow_session_cleanup: acquired lock 2019-11-25 09:59:56.535625 [DEBUG] google_glue.cpp:375 google_dialogflow_session_cleanup: sending writesDone.. 2019-11-25 09:59:56.535625 [DEBUG] google_glue.cpp:132 GStreamer::finish 0x7f346811f810 2019-11-25 09:59:56.535625 [INFO] google_glue.cpp:381 google_dialogflow_session_cleanup: waiting for read thread to complete 2019-11-25 09:59:56.535625 [INFO] google_glue.cpp:384 google_dialogflow_session_cleanup: read thread completed 2019-11-25 09:59:56.535625 [DEBUG] google_glue.cpp:79 GStreamer::~GStreamer wrote 34 packets 0x7f346811f810 2019-11-25 09:59:56.535625 [INFO] mod_dialogflow.c:57 Got SWITCH_ABC_TYPE_CLOSE. 2019-11-25 09:59:56.535625 [INFO] google_glue.cpp:397 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Bug is not attached. 2019-11-25 09:59:56.535625 [DEBUG] mod_dialogflow.c:60 Finished SWITCH_ABC_TYPE_CLOSE. 2019-11-25 09:59:56.535625 [DEBUG] switch_core_media_bug.c:1289 Removing BUG from sofia/drachtio_mrf/nobody@192.168.1.53:5060 2019-11-25 09:59:56.535625 [INFO] google_glue.cpp:392 google_dialogflow_session_cleanup: Closed google session 2019-11-25 09:59:56.535625 [INFO] mod_dialogflow.c:132 stopped dialogflow. 2019-11-25 09:59:56.535625 [INFO] mod_dialogflow.c:96 starting dialogflow with project fourth-cedar-251319, language en-US, event 30, text (null). 2019-11-25 09:59:56.535625 [DEBUG] google_glue.cpp:89 GStreamer::startStream set event 30, text (null) 0x7f346815de00 2019-11-25 09:59:56.535625 [DEBUG] switch_ivr.c:632 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Command Execute [depth=1] hangup() 2019-11-25 09:59:56.535625 [ALERT] switch_core_session.c:2662 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Send KeyFrame EXECUTE [depth=1] sofia/drachtio_mrf/nobody@192.168.1.53:5060 hangup() 2019-11-25 09:59:56.535625 [ALERT] switch_core_session.c:2889 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC] 2019-11-25 09:59:56.535625 [NOTICE] mod_dptools.c:1380 Hangup sofia/drachtio_mrf/nobody@192.168.1.53:5060 [CS_EXECUTE] [NORMAL_CLEARING] 2019-11-25 09:59:56.535625 [DEBUG] google_glue.cpp:49 google_dialogflow_session_cleanup: removed audio file /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_1.wav 2019-11-25 09:59:56.535625 [DEBUG] google_glue.cpp:49 google_dialogflow_session_cleanup: removed audio file /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_2.wav 2019-11-25 09:59:56.535625 [DEBUG] google_glue.cpp:49 google_dialogflow_session_cleanup: removed audio file /tmp/3a01d1ae-0f94-11ea-b754-2335b9dc4942_3.wav 2019-11-25 09:59:56.535625 [ALERT] switch_core_session.c:2905 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC_COMPLETE] 2019-11-25 09:59:56.535625 [DEBUG] switch_core_session.c:2905 sofia/drachtio_mrf/nobody@192.168.1.53:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2019-11-25 09:59:56.535625 [ALERT] switch_ivr.c:679 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [AUDIO_SYNC] 2019-11-25 09:59:56.535625 [DEBUG] switch_ivr.c:679 sofia/drachtio_mrf/nobody@192.168.1.53:5060 skip receive message [AUDIO_SYNC] (channel is hungup already) 2019-11-25 09:59:56.535625 [ALERT] switch_core_session.c:2905 sofia/drachtio_mrf/nobody@192.168.1.53:5060 receive message [APPLICATION_EXEC_COMPLETE] 2019-11-25 09:59:56.535625 [DEBUG] switch_core_session.c:2905 sofia/drachtio_mrf/nobody@192.168.1.53:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2019-11-25 09:59:56.535625 [DEBUG] switch_core_state_machine.c:651 (sofia/drachtio_mrf/nobody@192.168.1.53:5060) State EXECUTE going to sleep 2019-11-25 09:59:56.535625 [DEBUG] switch_core_state_machine.c:585 (sofia/drachtio_mrf/nobody@192.168.1.53:5060) Running State Change CS_HANGUP (Cur 1 Tot 2) 2019-11-25 09:59:56.535625 [DEBUG] switch_core_state_machine.c:848 (sofia/drachtio_mrf/nobody@192.168.1.53:5060) Callstate Change ACTIVE -> HANGUP 2019-11-25 09:59:56.535625 [DEBUG] switch_core_state_machine.c:850 (sofia/drachtio_mrf/nobody@192.168.1.53:5060) State HANGUP 2019-11-25 09:59:56.535625 [ALERT] switch_core_state_machine.c:850 sofia/drachtio_mrf/nobody@192.168.1.53:5060 Send KeyFrame 2019-11-25 09:59:56.535625 [DEBUG] mod_sofia.c:460 Channel sofia/drachtio_mrf/nobody@192.168.1.53:5060 hanging up, cause: NORMAL_CLEARING 2019-11-25 09:59:56.535625 [DEBUG] mod_sofia.c:514 Sending BYE to sofia/drachtio_mrf/nobody@192.168.1.53:5060 send 506 bytes to udp/[192.168.1.53]:5060 at 09:59:56.542139: ------------------------------------------------------------------------ BYE sip:192.168.1.53:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.53:5080;rport;branch=z9hG4bKcm6XD9mptFcSp Max-Forwards: 70 From: ;tag=cmyDp5gpcZ5cj To: ;tag=e3gj17ryjmUyc Call-ID: 115ff229-8a37-1238-3b90-000c296e5b76 CSeq: 12793206 BYE User-Agent: drachtio MRF Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0