FS_MASTER LOG 2017-06-21 13:42:22.301710 [NOTICE] switch_loadable_module.c:1055 Deleting Application 'socket' 2017-06-21 13:42:22.301710 [DEBUG] switch_loadable_module.c:1057 Write lock interface 'socket' to wait for existing references. 2017-06-21 13:42:22.301710 [NOTICE] switch_loadable_module.c:1110 Deleting API Function 'event_sink' 2017-06-21 13:42:22.301710 [DEBUG] switch_loadable_module.c:1112 Write lock interface 'event_sink' to wait for existing references. 2017-06-21 13:42:22.301710 [CONSOLE] switch_loadable_module.c:2014 Stopping: mod_event_socket 2017-06-21 13:42:22.301710 [NOTICE] mod_event_socket.c:2989 Shutting Down 2017-06-21 13:42:22.301710 [NOTICE] switch_loadable_module.c:115 Thread ended for mod_event_socket 2017-06-21 13:42:22.341666 [INFO] mod_enum.c:879 ENUM Reloaded 2017-06-21 13:42:22.341666 [INFO] switch_time.c:1423 Timezone reloaded 1750 definitions 2017-06-21 13:42:22.341666 [NOTICE] switch_event.c:2140 Event Binding deleted for mod_event_socket:ALL 2017-06-21 13:42:22.341666 [CONSOLE] switch_loadable_module.c:2030 mod_event_socket stopping runtime thread. 2017-06-21 13:42:22.341666 [CONSOLE] switch_loadable_module.c:2034 mod_event_socket unloaded. 2017-06-21 13:42:22.341666 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_event_socket] 2017-06-21 13:42:22.341666 [NOTICE] switch_loadable_module.c:292 Adding Application 'socket' 2017-06-21 13:42:22.341666 [NOTICE] switch_loadable_module.c:338 Adding API Function 'event_sink' 2017-06-21 13:42:22.381642 [DEBUG] mod_event_socket.c:2961 Socket up listening on :::8021 2017-06-21 13:42:34.481702 [WARNING] mod_event_socket.c:2659 IP ::ffff:127.0.0.1 Rejected by acl "eventsocket" 2017-06-21 13:43:08.801672 [WARNING] mod_event_socket.c:2659 IP ::ffff:172.20.100.153 Rejected by acl "eventsocket" 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [NOTICE] switch_channel.c:1104 New Channel sofia/external/767432661@172.20.100.3 [25e10655-a9b9-4447-bbe0-4c3081149afb] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:584 (sofia/external/767432661@172.20.100.3) Running State Change CS_NEW (Cur 1 Tot 1) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] sofia.c:9837 sofia/external/767432661@172.20.100.3 receiving invite from 172.20.100.158:5060 version: 1.6.18 -35-6e79667 64bit 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] sofia.c:7048 Channel sofia/external/767432661@172.20.100.3 entering state [received][100] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] sofia.c:7058 Remote SDP: 25e10655-a9b9-4447-bbe0-4c3081149afb v=0 25e10655-a9b9-4447-bbe0-4c3081149afb o=CiscoSystemsSIP-GW-UserAgent 3801 8704 IN IP4 172.20.100.3 25e10655-a9b9-4447-bbe0-4c3081149afb s=SIP Call 25e10655-a9b9-4447-bbe0-4c3081149afb c=IN IP4 172.20.100.3 25e10655-a9b9-4447-bbe0-4c3081149afb t=0 0 25e10655-a9b9-4447-bbe0-4c3081149afb m=audio 17184 RTP/AVP 0 8 18 101 25e10655-a9b9-4447-bbe0-4c3081149afb c=IN IP4 172.20.100.3 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:0 PCMU/8000 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:8 PCMA/8000 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:18 G729/8000 25e10655-a9b9-4447-bbe0-4c3081149afb a=fmtp:18 annexb=yes 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:101 telephone-event/8000 25e10655-a9b9-4447-bbe0-4c3081149afb a=fmtp:101 0-16 25e10655-a9b9-4447-bbe0-4c3081149afb 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] sofia.c:7450 (sofia/external/767432661@172.20.100.3) State Change CS_NEW -> CS_INIT 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:603 (sofia/external/767432661@172.20.100.3) State NEW 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:584 (sofia/external/767432661@172.20.100.3) Running State Change CS_INIT (Cur 1 Tot 1) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:627 (sofia/external/767432661@172.20.100.3) State INIT 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] mod_sofia.c:90 sofia/external/767432661@172.20.100.3 SOFIA INIT 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:40 sofia/external/767432661@172.20.100.3 Standard INIT 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:48 (sofia/external/767432661@172.20.100.3) State Change CS_INIT -> CS_ROUTING 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:627 (sofia/external/767432661@172.20.100.3) State INIT going to sleep 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:584 (sofia/external/767432661@172.20.100.3) Running State Change CS_ROUTING (Cur 1 Tot 1) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_channel.c:2249 (sofia/external/767432661@172.20.100.3) Callstate Change DOWN -> RINGING 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:643 (sofia/external/767432661@172.20.100.3) State ROUTING 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] mod_sofia.c:143 sofia/external/767432661@172.20.100.3 SOFIA ROUTING 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] switch_core_state_machine.c:236 sofia/external/767432661@172.20.100.3 Standard ROUTING 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [INFO] mod_dialplan_xml.c:637 Processing 767432661 <767432661>->6891 in context public 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 parsing [public->test] continue=false 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Regex (PASS) [test] destination_number(6891) =~ /[^ ]*/ break=on-false 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(companyid=4) 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(tenantid=1) 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(dvp_app_id=5) 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(DVP_CALL_DIRECTION=inbound) 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action set(effective_caller_id_number=767432661) 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action set(DVP_OPERATION_CAT=HTTAPI) 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(dvp_app_type=HTTAPI) 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action answer() 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action httapi({url=http://httpprogrammingapi.facetone.combank.lk/}) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_state_machine.c:286 (sofia/external/767432661@172.20.100.3) State Change CS_ROUTING -> CS_EXECUTE 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_state_machine.c:643 (sofia/external/767432661@172.20.100.3) State ROUTING going to sleep 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_state_machine.c:584 (sofia/external/767432661@172.20.100.3) Running State Change CS_EXECUTE (Cur 1 Tot 1) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_state_machine.c:650 (sofia/external/767432661@172.20.100.3) State EXECUTE 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] mod_sofia.c:198 sofia/external/767432661@172.20.100.3 SOFIA EXECUTE 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_state_machine.c:328 sofia/external/767432661@172.20.100.3 Standard EXECUTE 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(companyid=4) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [companyid]=[4] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(tenantid=1) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [tenantid]=[1] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(dvp_app_id=5) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dvp_app_id]=[5] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(DVP_CALL_DIRECTION=inbound) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [DVP_CALL_DIRECTION]=[inbound] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 set(effective_caller_id_number=767432661) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] mod_dptools.c:1530 SET sofia/external/767432661@172.20.100.3 [effective_caller_id_number]=[767432661] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 set(DVP_OPERATION_CAT=HTTAPI) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] mod_dptools.c:1530 SET sofia/external/767432661@172.20.100.3 [DVP_OPERATION_CAT]=[HTTAPI] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(dvp_app_type=HTTAPI) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dvp_app_type]=[HTTAPI] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 answer() 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:3912 Activate Buggy RFC2833 Mode! 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:3057 Set Codec sofia/external/767432661@172.20.100.3 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_codec.c:111 sofia/external/767432661@172.20.100.3 Original read codec set to PCMU:0 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:4763 sofia/external/767432661@172.20.100.3 Set 2833 dtmf send payload to 101 recv payload to 101 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/external/767432661@172.20.100.3] 172.20.100.156 port 21788 -> 172.20.100.3 port 17184 codec: 0 ms: 20 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [DEBUG] switch_core_media.c:7175 sofia/external/767432661@172.20.100.3 Set 2833 dtmf send payload to 101 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [DEBUG] switch_core_media.c:7182 sofia/external/767432661@172.20.100.3 Set 2833 dtmf receive payload to 101 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [DEBUG] switch_core_media.c:7205 sofia/external/767432661@172.20.100.3 Set rtp dtmf delay to 40 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/767432661@172.20.100.3! 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [DEBUG] switch_channel.c:3474 (sofia/external/767432661@172.20.100.3) Callstate Change RINGING -> EARLY 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/external/767432661@172.20.100.3. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/767432661@172.20.100.3: 25e10655-a9b9-4447-bbe0-4c3081149afb v=0 25e10655-a9b9-4447-bbe0-4c3081149afb o=FreeSWITCH 1498011131 1498011132 IN IP4 172.20.100.156 25e10655-a9b9-4447-bbe0-4c3081149afb s=FreeSWITCH 25e10655-a9b9-4447-bbe0-4c3081149afb c=IN IP4 172.20.100.156 25e10655-a9b9-4447-bbe0-4c3081149afb t=0 0 25e10655-a9b9-4447-bbe0-4c3081149afb m=audio 21788 RTP/AVP 0 101 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:0 PCMU/8000 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:101 telephone-event/8000 25e10655-a9b9-4447-bbe0-4c3081149afb a=fmtp:101 0-16 25e10655-a9b9-4447-bbe0-4c3081149afb a=ptime:20 25e10655-a9b9-4447-bbe0-4c3081149afb a=sendrecv 25e10655-a9b9-4447-bbe0-4c3081149afb 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/767432661@172.20.100.3] has been answered 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [DEBUG] switch_channel.c:3773 (sofia/external/767432661@172.20.100.3) Callstate Change EARLY -> ACTIVE 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.861653 [DEBUG] sofia.c:7048 Channel sofia/external/767432661@172.20.100.3 entering state [completed][200] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 httapi({url=http://httpprogrammingapi.facetone.combank.lk/}) 2017-06-21 13:45:19.921638 [CRIT] mod_httapi.c:1160 Debugging Return Data: [object Object] 2017-06-21 13:45:19.921638 [DEBUG] mod_httapi.c:1229 Process Tag: [continue] 2017-06-21 13:45:19.941642 [CRIT] mod_httapi.c:1160 Debugging Return Data: 2017-06-21 13:45:19.941642 [DEBUG] mod_httapi.c:1229 Process Tag: [getVariable] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.941642 [DEBUG] sofia.c:7048 Channel sofia/external/767432661@172.20.100.3 entering state [ready][200] 2017-06-21 13:45:20.001637 [CRIT] mod_httapi.c:1160 Debugging Return Data: 2017-06-21 13:45:20.001637 [DEBUG] mod_httapi.c:1229 Process Tag: [execute] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 play_and_get_digits(1 1 1 5000 # http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/Com_WelcomeDB.wav ./invalid.wav result \S{1}) 2017-06-21 13:45:20.021673 [DEBUG] mod_httapi.c:2640 caching: url:http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/Com_WelcomeDB.wav to /var/lib/freeswitch/storage/http_file_cache/2ee30b959a3effeba0fd010a9f7ac591.wav (167362 bytes) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:20.021673 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:20.021673 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:21.581729 [DEBUG] switch_rtp.c:7480 RTP RECV DTMF 1:904 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:21.581729 [INFO] switch_channel.c:515 RECV DTMF 1:904 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:21.581729 [DEBUG] switch_ivr_play_say.c:1942 done playing file http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/Com_WelcomeDB.wav 2017-06-21 13:45:21.601646 [DEBUG] mod_httapi.c:1229 Process Tag: [getVariable] 2017-06-21 13:45:21.601646 [CRIT] mod_httapi.c:1160 Debugging Return Data: eng 2017-06-21 13:45:21.601646 [DEBUG] mod_httapi.c:1229 Process Tag: [continue] 2017-06-21 13:45:21.661710 [CRIT] mod_httapi.c:1160 Debugging Return Data: 2017-06-21 13:45:21.661710 [DEBUG] mod_httapi.c:1229 Process Tag: [execute] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 play_and_get_digits(1 1 1 5000 # http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/selectbankingoptionEng.wav ./invalid.wav 1 \S{1}) 2017-06-21 13:45:21.681645 [DEBUG] mod_httapi.c:2640 caching: url:http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/selectbankingoptionEng.wav to /var/lib/freeswitch/storage/http_file_cache/840249112939f1e74fa1031032a1d82c.wav (64658 bytes) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:21.701684 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:25.221696 [DEBUG] switch_rtp.c:7480 RTP RECV DTMF 1:824 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:25.221696 [INFO] switch_channel.c:515 RECV DTMF 1:824 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:25.221696 [DEBUG] switch_ivr_play_say.c:1942 done playing file http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/selectbankingoptionEng.wav 2017-06-21 13:45:25.241688 [DEBUG] mod_httapi.c:1229 Process Tag: [getVariable] 2017-06-21 13:45:25.281699 [CRIT] mod_httapi.c:1160 Debugging Return Data: ~\d{1} 2017-06-21 13:45:25.281699 [DEBUG] mod_httapi.c:1229 Process Tag: [playback] 2017-06-21 13:45:25.281699 [INFO] switch_ivr_async.c:214 Digit parser HTTAPI: Setting realm to 'default' 2017-06-21 13:45:25.281699 [DEBUG] switch_ivr_async.c:320 Digit parser HTTAPI: binding \d{1}/default/0 callback: 0x7f8a248ff240 data: 0x7f89e004e7b8 2017-06-21 13:45:25.281699 [INFO] switch_ivr_async.c:214 Digit parser HTTAPI: Setting realm to 'default' 2017-06-21 13:45:25.281699 [DEBUG] switch_ivr_async.c:203 Digit parser HTTAPI: Setting terminators for realm 'default' to '#' 2017-06-21 13:45:25.301647 [DEBUG] mod_httapi.c:2640 caching: url:http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/CallWillBeRecordedDBEng.wav to /var/lib/freeswitch/storage/http_file_cache/e026ea7e5d1c644be8869a6c5952d80b.wav (32235 bytes) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:25.301647 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:29.301696 [DEBUG] switch_ivr_play_say.c:1942 done playing file http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/CallWillBeRecordedDBEng.wav 2017-06-21 13:45:29.381635 [CRIT] mod_httapi.c:1160 Debugging Return Data: 2,9 0 English 0 4 1 English.wav false en 2017-06-21 13:45:29.381635 [DEBUG] mod_httapi.c:1229 Process Tag: [execute] 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 ards(2,9,1,4,) 2017-06-21 13:45:29.421705 [NOTICE] mod_ards.c:1784 Possition recived for 25e10655-a9b9-4447-bbe0-4c3081149afb and the position is 12017-06-21 13:45:29.461683 [DEBUG] mod_httapi.c:2640 caching: url:http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/English.wav to /var/lib/freeswitch/storage/http_file_cache/af9d0a5ca39a2fb1cedd873fc2387486.wav (1135879 bytes) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:29.461683 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:30.061699 [DEBUG] mod_ards.c:1324 OutBound Started 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:30.061699 [DEBUG] mod_ards.c:1359 Setting outbound caller_id_name to: 767432661 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:30.061699 [DEBUG] mod_ards.c:1360 Setting outbound caller_id_number to: 767432661 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:30.061699 [INFO] mod_ards.c:1361 Setting channel name to: sofia/external/767432661@172.20.100.3 2017-06-21 13:45:30.081648 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2017-06-21 13:45:30.121660 [DEBUG] switch_xml.c:2073 caching lookup for user 1004@172.20.100.156 indefinitely 2017-06-21 13:45:30.141651 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/sineth@192.168.116.35:5060 [8b0f74c6-86b4-46f2-b77e-c4e758a5932b] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] mod_sofia.c:4819 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_NEW -> CS_INIT 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_INIT (Cur 2 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/sineth@192.168.116.35:5060) State INIT 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] mod_sofia.c:90 sofia/internal/sineth@192.168.116.35:5060 SOFIA INIT 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] sofia_glue.c:1264 sip:sineth@192.168.116.35:5060 Setting proxy route to sofia/internal/sineth@192.168.116.35:5060 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] sofia_glue.c:1295 sofia/internal/sineth@192.168.116.35:5060 sending invite version: 1.6.18 -35-6e79667 64bit 8b0f74c6-86b4-46f2-b77e-c4e758a5932b Local SDP: 8b0f74c6-86b4-46f2-b77e-c4e758a5932b v=0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b o=FreeSWITCH 1498003506 1498003507 IN IP4 172.20.100.156 8b0f74c6-86b4-46f2-b77e-c4e758a5932b s=FreeSWITCH 8b0f74c6-86b4-46f2-b77e-c4e758a5932b c=IN IP4 172.20.100.156 8b0f74c6-86b4-46f2-b77e-c4e758a5932b t=0 0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b m=audio 29424 RTP/AVP 0 8 101 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:0 PCMU/8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:8 PCMA/8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:101 telephone-event/8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=fmtp:101 0-16 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=ptime:20 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=sendrecv 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:40 sofia/internal/sineth@192.168.116.35:5060 Standard INIT 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_INIT -> CS_ROUTING 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/sineth@192.168.116.35:5060) State INIT going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_ROUTING (Cur 2 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/sineth@192.168.116.35:5060) State ROUTING 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] mod_sofia.c:143 sofia/internal/sineth@192.168.116.35:5060 SOFIA ROUTING 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/sineth@192.168.116.35:5060) State ROUTING going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] sofia.c:7048 Channel sofia/internal/sineth@192.168.116.35:5060 entering state [calling][0] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/sineth@192.168.116.35:5060) State CONSUME_MEDIA 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.141651 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/sineth@192.168.116.35:5060) State CONSUME_MEDIA going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.161645 [DEBUG] sofia.c:7048 Channel sofia/internal/sineth@192.168.116.35:5060 entering state [proceeding][180] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.161645 [NOTICE] sofia.c:7156 Ring-Ready sofia/internal/sineth@192.168.116.35:5060! 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:30.161645 [DEBUG] switch_channel.c:3346 (sofia/internal/sineth@192.168.116.35:5060) Callstate Change DOWN -> RINGING 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] sofia.c:7048 Channel sofia/internal/sineth@192.168.116.35:5060 entering state [completing][200] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] sofia.c:7058 Remote SDP: 8b0f74c6-86b4-46f2-b77e-c4e758a5932b v=0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b o=- 257604662 1 IN IP4 192.168.116.35 8b0f74c6-86b4-46f2-b77e-c4e758a5932b s=portsip.com 8b0f74c6-86b4-46f2-b77e-c4e758a5932b c=IN IP4 192.168.116.35 8b0f74c6-86b4-46f2-b77e-c4e758a5932b t=0 0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b m=audio 20004 RTP/AVP 0 8 101 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:0 PCMU/8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:8 PCMA/8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:101 telephone-event/8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=fmtp:101 0-16 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] sofia.c:7048 Channel sofia/internal/sineth@192.168.116.35:5060 entering state [ready][200] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:3057 Set Codec sofia/internal/sineth@192.168.116.35:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_codec.c:111 sofia/internal/sineth@192.168.116.35:5060 Original read codec set to PCMU:0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:4763 sofia/internal/sineth@192.168.116.35:5060 Set 2833 dtmf send payload to 101 recv payload to 101 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/internal/sineth@192.168.116.35:5060] 172.20.100.156 port 29424 -> 192.168.116.35 port 20004 codec: 0 ms: 20 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.761642 [DEBUG] switch_core_media.c:7175 sofia/internal/sineth@192.168.116.35:5060 Set 2833 dtmf send payload to 101 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.761642 [DEBUG] switch_core_media.c:7182 sofia/internal/sineth@192.168.116.35:5060 Set 2833 dtmf receive payload to 101 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.761642 [DEBUG] switch_core_media.c:7205 sofia/internal/sineth@192.168.116.35:5060 Set rtp dtmf delay to 40 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.761642 [NOTICE] sofia.c:8182 Channel [sofia/internal/sineth@192.168.116.35:5060] has been answered 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.761642 [DEBUG] switch_channel.c:3773 (sofia/internal/sineth@192.168.116.35:5060) Callstate Change RINGING -> ACTIVE 2017-06-21 13:45:35.761642 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/sineth@192.168.116.35:5060] 2017-06-21 13:45:35.761642 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/sineth@192.168.116.35:5060] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.781653 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/external/767432661@172.20.100.3 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.781653 [DEBUG] switch_ivr_async.c:1500 No silence detection configured; assuming start of speech 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [ERR] mod_ards.c:1546 Agent leg binding 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [INFO] switch_ivr_async.c:4173 Bound B-Leg: *3 execute_extension::att_xfer XML ARDSFeatures|1|4 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [ERR] mod_ards.c:1554 Agent leg binding 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [INFO] switch_ivr_async.c:4173 Bound B-Leg: *6 execute_extension::att_xfer_outbound XML ARDSFeatures|1|4 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [ERR] mod_ards.c:1563 Agent leg binding 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [INFO] switch_ivr_async.c:4173 Bound B-Leg: *9 execute_extension::att_xfer_ivr XML ARDSFeatures|1|4 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [DEBUG] switch_ivr_bridge.c:2030 (sofia/external/767432661@172.20.100.3) State Change CS_EXECUTE -> CS_HIBERNATE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_ivr_bridge.c:2032 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_CONSUME_MEDIA -> CS_HIBERNATE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_HIBERNATE (Cur 2 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_core_state_machine.c:665 (sofia/internal/sineth@192.168.116.35:5060) State HIBERNATE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] mod_sofia.c:180 sofia/internal/sineth@192.168.116.35:5060 SOFIA HIBERNATE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_ivr_bridge.c:984 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_HIBERNATE -> CS_RESET 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_core_state_machine.c:665 (sofia/internal/sineth@192.168.116.35:5060) State HIBERNATE going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_RESET (Cur 2 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/sineth@192.168.116.35:5060) State RESET 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] mod_sofia.c:161 sofia/internal/sineth@192.168.116.35:5060 SOFIA RESET 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_ivr_bridge.c:969 sofia/internal/sineth@192.168.116.35:5060 CUSTOM RESET 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_core_state_machine.c:188 sofia/internal/sineth@192.168.116.35:5060 Standard RESET 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.801645 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/sineth@192.168.116.35:5060) State RESET going to sleep 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [DEBUG] switch_ivr_play_say.c:1942 done playing file http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/English.wav 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:650 (sofia/external/767432661@172.20.100.3) State EXECUTE going to sleep 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:584 (sofia/external/767432661@172.20.100.3) Running State Change CS_HIBERNATE (Cur 2 Tot 2) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:665 (sofia/external/767432661@172.20.100.3) State HIBERNATE 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] mod_sofia.c:180 sofia/external/767432661@172.20.100.3 SOFIA HIBERNATE 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_ivr_bridge.c:984 (sofia/external/767432661@172.20.100.3) State Change CS_HIBERNATE -> CS_RESET 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:665 (sofia/external/767432661@172.20.100.3) State HIBERNATE going to sleep 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:584 (sofia/external/767432661@172.20.100.3) Running State Change CS_RESET (Cur 2 Tot 2) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:646 (sofia/external/767432661@172.20.100.3) State RESET 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] mod_sofia.c:161 sofia/external/767432661@172.20.100.3 SOFIA RESET 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_ivr_bridge.c:969 sofia/external/767432661@172.20.100.3 CUSTOM RESET 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_ivr_bridge.c:976 (sofia/external/767432661@172.20.100.3) State Change CS_RESET -> CS_SOFT_EXECUTE 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:646 (sofia/external/767432661@172.20.100.3) State RESET going to sleep 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:584 (sofia/external/767432661@172.20.100.3) Running State Change CS_SOFT_EXECUTE (Cur 2 Tot 2) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:656 (sofia/external/767432661@172.20.100.3) State SOFT_EXECUTE 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] mod_sofia.c:637 SOFIA SOFT_EXECUTE 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] switch_ivr_bridge.c:994 sofia/external/767432661@172.20.100.3 CUSTOM SOFT_EXECUTE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.341646 [DEBUG] switch_ivr_bridge.c:1026 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_RESET -> CS_SOFT_EXECUTE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_SOFT_EXECUTE (Cur 2 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:656 (sofia/internal/sineth@192.168.116.35:5060) State SOFT_EXECUTE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.341646 [DEBUG] mod_sofia.c:637 SOFIA SOFT_EXECUTE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.341646 [DEBUG] switch_ivr_bridge.c:994 sofia/internal/sineth@192.168.116.35:5060 CUSTOM SOFT_EXECUTE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:400 sofia/internal/sineth@192.168.116.35:5060 Standard SOFT_EXECUTE 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.341646 [DEBUG] switch_core_state_machine.c:656 (sofia/internal/sineth@192.168.116.35:5060) State SOFT_EXECUTE going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_ivr_bridge.c:1509 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/sineth@192.168.116.35:5060) State CONSUME_MEDIA 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_ivr_bridge.c:933 sofia/internal/sineth@192.168.116.35:5060 CUSTOM HOLD 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/sineth@192.168.116.35:5060) State CONSUME_MEDIA going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/sineth@192.168.116.35:5060) State EXCHANGE_MEDIA 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.361660 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed. 2017-06-21 13:45:45.441709 [INFO] mod_shout.c:332 LAME 3.99.5 64bits (http://lame.sf.net) 2017-06-21 13:45:45.441709 [INFO] mod_shout.c:332 polyphase lowpass filter disabled 2017-06-21 13:46:40.301675 [CRIT] switch_core.c:2723 Declinatio Mortuus Obfirmo! ==================================================================================================================================================== FS_SLAVE LOG 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 04:14:21.607165 [NOTICE] switch_channel.c:1104 New Channel sofia/external/767432661@172.20.100.3 [25e10655-a9b9-4447-bbe0-4c3081149afb] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 04:14:21.607165 [NOTICE] switch_core_session.c:2226 Close Channel sofia/external/767432661@172.20.100.3 [CS_NEW] 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 04:14:21.607165 [DEBUG] switch_core_state_machine.c:741 (sofia/external/767432661@172.20.100.3) Running State Change CS_DESTROY (Cur 0 Tot 1) 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 04:14:21.607165 [DEBUG] switch_core_state_machine.c:751 (sofia/external/767432661@172.20.100.3) State DESTROY 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 04:14:21.607165 [DEBUG] mod_sofia.c:343 sofia/external/767432661@172.20.100.3 SOFIA DESTROY 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 04:14:21.607165 [DEBUG] switch_core_state_machine.c:751 (sofia/external/767432661@172.20.100.3) State DESTROY going to sleep 2017-06-21 04:14:21.607165 [WARNING] switch_core_sqldb.c:2987 Invalid cdr data, call not recovered 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/sineth@192.168.116.35:5060 [8b0f74c6-86b4-46f2-b77e-c4e758a5932b] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [NOTICE] switch_channel.c:1102 Rename Channel sofia/internal/sineth@192.168.116.35:5060->sofia/internal/sineth@192.168.116.35:5060 [8b0f74c6-86b4-46f2-b77e-c4e758a5932b] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_media.c:3057 Set Codec sofia/internal/sineth@192.168.116.35:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_codec.c:111 sofia/internal/sineth@192.168.116.35:5060 Original read codec set to PCMU:0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/internal/sineth@192.168.116.35:5060] 172.20.100.156 port 29424 -> 192.168.116.35 port 20004 codec: 0 ms: 20 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_media.c:7175 sofia/internal/sineth@192.168.116.35:5060 Set 2833 dtmf send payload to 101 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_media.c:7182 sofia/internal/sineth@192.168.116.35:5060 Set 2833 dtmf receive payload to 101 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_media.c:7205 sofia/internal/sineth@192.168.116.35:5060 Set rtp dtmf delay to 40 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_sqldb.c:3041 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_NEW -> CS_INIT 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [NOTICE] switch_core_sqldb.c:3042 Resurrecting fallen channel sofia/internal/sineth@192.168.116.35:5060 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_INIT (Cur 1 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/sineth@192.168.116.35:5060) State INIT 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] mod_sofia.c:90 sofia/internal/sineth@192.168.116.35:5060 SOFIA INIT 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] sofia_glue.c:1264 sip:(null)@192.168.116.35:5060 Setting proxy route to sofia/internal/sineth@192.168.116.35:5060 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] sofia_glue.c:1295 sofia/internal/sineth@192.168.116.35:5060 sending invite version: 1.6.18 -35-6e79667 64bit 8b0f74c6-86b4-46f2-b77e-c4e758a5932b Local SDP: 8b0f74c6-86b4-46f2-b77e-c4e758a5932b v=0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b o=FreeSWITCH 1498003437 1498003439 IN IP4 172.20.100.156 8b0f74c6-86b4-46f2-b77e-c4e758a5932b s=FreeSWITCH 8b0f74c6-86b4-46f2-b77e-c4e758a5932b c=IN IP4 172.20.100.156 8b0f74c6-86b4-46f2-b77e-c4e758a5932b t=0 0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b m=audio 29424 RTP/AVP 0 101 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:0 PCMU/8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:101 telephone-event/0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=fmtp:101 0-16 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=ptime:20 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=sendrecv 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_state_machine.c:40 sofia/internal/sineth@192.168.116.35:5060 Standard INIT 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_core_state_machine.c:43 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_INIT -> CS_RESET 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.627178 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/sineth@192.168.116.35:5060) State INIT going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.627178 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_RESET (Cur 1 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.627178 [DEBUG] sofia.c:7048 Channel sofia/internal/sineth@192.168.116.35:5060 entering state [calling][0] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.627178 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/sineth@192.168.116.35:5060) State RESET 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.627178 [DEBUG] mod_sofia.c:161 sofia/internal/sineth@192.168.116.35:5060 SOFIA RESET 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.627178 [DEBUG] switch_core_state_machine.c:188 sofia/internal/sineth@192.168.116.35:5060 Standard RESET 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.627178 [DEBUG] switch_core_state_machine.c:646 (sofia/internal/sineth@192.168.116.35:5060) State RESET going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.907224 [DEBUG] sofia.c:7048 Channel sofia/internal/sineth@192.168.116.35:5060 entering state [completing][200] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.907224 [DEBUG] sofia.c:7058 Remote SDP: 8b0f74c6-86b4-46f2-b77e-c4e758a5932b v=0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b o=- 257604662 2 IN IP4 192.168.116.35 8b0f74c6-86b4-46f2-b77e-c4e758a5932b s=portsip.com 8b0f74c6-86b4-46f2-b77e-c4e758a5932b c=IN IP4 192.168.116.35 8b0f74c6-86b4-46f2-b77e-c4e758a5932b t=0 0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b m=audio 20004 RTP/AVP 0 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:0 PCMU/8000 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.907224 [DEBUG] sofia.c:7048 Channel sofia/internal/sineth@192.168.116.35:5060 entering state [ready][200] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.947158 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.947158 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.947158 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.947158 [DEBUG] switch_core_media.c:4734 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.947158 [DEBUG] switch_core_media.c:4763 sofia/internal/sineth@192.168.116.35:5060 Set 2833 dtmf send payload to 101 recv payload to 101 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.947158 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/internal/sineth@192.168.116.35:5060. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [NOTICE] sofia.c:1012 Hangup sofia/internal/sineth@192.168.116.35:5060 [CS_RESET] [NORMAL_CLEARING] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_HANGUP (Cur 1 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/sineth@192.168.116.35:5060) Callstate Change DOWN -> HANGUP 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/sineth@192.168.116.35:5060) State HANGUP 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [DEBUG] mod_sofia.c:438 Channel sofia/internal/sineth@192.168.116.35:5060 hanging up, cause: NORMAL_CLEARING 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [DEBUG] switch_core_state_machine.c:60 sofia/internal/sineth@192.168.116.35:5060 Standard HANGUP, cause: NORMAL_CLEARING 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/sineth@192.168.116.35:5060) State HANGUP going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_HANGUP -> CS_REPORTING 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_REPORTING (Cur 1 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/sineth@192.168.116.35:5060) State REPORTING 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.307218 [INFO] mod_json_cdr.c:271 Process [8b0f74c6-86b4-46f2-b77e-c4e758a5932b.cdr.json] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [DEBUG] switch_core_state_machine.c:174 sofia/internal/sineth@192.168.116.35:5060 Standard REPORTING, cause: NORMAL_CLEARING 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/sineth@192.168.116.35:5060) State REPORTING going to sleep 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/sineth@192.168.116.35:5060) State Change CS_REPORTING -> CS_DESTROY 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [DEBUG] switch_core_session.c:1664 Session 2 (sofia/internal/sineth@192.168.116.35:5060) Locked, Waiting on external entities 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [NOTICE] switch_core_session.c:1682 Session 2 (sofia/internal/sineth@192.168.116.35:5060) Ended 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/sineth@192.168.116.35:5060 [CS_DESTROY] 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/sineth@192.168.116.35:5060) Running State Change CS_DESTROY (Cur 0 Tot 2) 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/sineth@192.168.116.35:5060) State DESTROY 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [DEBUG] mod_sofia.c:343 sofia/internal/sineth@192.168.116.35:5060 SOFIA DESTROY 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [DEBUG] switch_core_state_machine.c:181 sofia/internal/sineth@192.168.116.35:5060 Standard DESTROY 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:15:03.367218 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/sineth@192.168.116.35:5060) State DESTROY going to sleep ================================================================================================================================================== EXTERNAL Profile ======================================================================================================================= INTERNAL Profile =================================================================================================================================== SWITCH.CONF.XML