From achinthau, 6 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. FS_MASTER LOG
  2.  
  3. 2017-06-21 13:42:22.301710 [NOTICE] switch_loadable_module.c:1055 Deleting Application 'socket'
  4. 2017-06-21 13:42:22.301710 [DEBUG] switch_loadable_module.c:1057 Write lock interface 'socket' to wait for existing references.
  5. 2017-06-21 13:42:22.301710 [NOTICE] switch_loadable_module.c:1110 Deleting API Function 'event_sink'
  6. 2017-06-21 13:42:22.301710 [DEBUG] switch_loadable_module.c:1112 Write lock interface 'event_sink' to wait for existing references.
  7. 2017-06-21 13:42:22.301710 [CONSOLE] switch_loadable_module.c:2014 Stopping: mod_event_socket
  8. 2017-06-21 13:42:22.301710 [NOTICE] mod_event_socket.c:2989 Shutting Down
  9. 2017-06-21 13:42:22.301710 [NOTICE] switch_loadable_module.c:115 Thread ended for mod_event_socket
  10. 2017-06-21 13:42:22.341666 [INFO] mod_enum.c:879 ENUM Reloaded
  11. 2017-06-21 13:42:22.341666 [INFO] switch_time.c:1423 Timezone reloaded 1750 definitions
  12. 2017-06-21 13:42:22.341666 [NOTICE] switch_event.c:2140 Event Binding deleted for mod_event_socket:ALL
  13. 2017-06-21 13:42:22.341666 [CONSOLE] switch_loadable_module.c:2030 mod_event_socket stopping runtime thread.
  14. 2017-06-21 13:42:22.341666 [CONSOLE] switch_loadable_module.c:2034 mod_event_socket unloaded.
  15. 2017-06-21 13:42:22.341666 [CONSOLE] switch_loadable_module.c:1540 Successfully Loaded [mod_event_socket]
  16. 2017-06-21 13:42:22.341666 [NOTICE] switch_loadable_module.c:292 Adding Application 'socket'
  17. 2017-06-21 13:42:22.341666 [NOTICE] switch_loadable_module.c:338 Adding API Function 'event_sink'
  18. 2017-06-21 13:42:22.381642 [DEBUG] mod_event_socket.c:2961 Socket up listening on :::8021
  19. 2017-06-21 13:42:34.481702 [WARNING] mod_event_socket.c:2659 IP ::ffff:127.0.0.1 Rejected by acl "eventsocket"
  20. 2017-06-21 13:43:08.801672 [WARNING] mod_event_socket.c:2659 IP ::ffff:172.20.100.153 Rejected by acl "eventsocket"
  21. 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]
  22. 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)
  23. 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
  24. 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]
  25. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.761720 [DEBUG] sofia.c:7058 Remote SDP:
  26. 25e10655-a9b9-4447-bbe0-4c3081149afb v=0
  27. 25e10655-a9b9-4447-bbe0-4c3081149afb o=CiscoSystemsSIP-GW-UserAgent 3801 8704 IN IP4 172.20.100.3
  28. 25e10655-a9b9-4447-bbe0-4c3081149afb s=SIP Call
  29. 25e10655-a9b9-4447-bbe0-4c3081149afb c=IN IP4 172.20.100.3
  30. 25e10655-a9b9-4447-bbe0-4c3081149afb t=0 0
  31. 25e10655-a9b9-4447-bbe0-4c3081149afb m=audio 17184 RTP/AVP 0 8 18 101
  32. 25e10655-a9b9-4447-bbe0-4c3081149afb c=IN IP4 172.20.100.3
  33. 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:0 PCMU/8000
  34. 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:8 PCMA/8000
  35. 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:18 G729/8000
  36. 25e10655-a9b9-4447-bbe0-4c3081149afb a=fmtp:18 annexb=yes
  37. 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:101 telephone-event/8000
  38. 25e10655-a9b9-4447-bbe0-4c3081149afb a=fmtp:101 0-16
  39. 25e10655-a9b9-4447-bbe0-4c3081149afb
  40. 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
  41. 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
  42. 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)
  43. 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
  44. 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
  45. 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
  46. 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
  47. 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
  48. 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)
  49. 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
  50. 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
  51. 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
  52. 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
  53. 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
  54. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 parsing [public->test] continue=false
  55. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Regex (PASS) [test] destination_number(6891) =~ /[^ ]*/ break=on-false
  56. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(companyid=4)
  57. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(tenantid=1)
  58. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(dvp_app_id=5)
  59. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(DVP_CALL_DIRECTION=inbound)
  60. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action set(effective_caller_id_number=767432661)
  61. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action set(DVP_OPERATION_CAT=HTTAPI)
  62. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action export(dvp_app_type=HTTAPI)
  63. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action answer()
  64. 25e10655-a9b9-4447-bbe0-4c3081149afb Dialplan: sofia/external/767432661@172.20.100.3 Action httapi({url=http://httpprogrammingapi.facetone.combank.lk/})
  65. 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
  66. 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
  67. 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)
  68. 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
  69. 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
  70. 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
  71. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(companyid=4)
  72. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [companyid]=[4]
  73. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(tenantid=1)
  74. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [tenantid]=[1]
  75. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(dvp_app_id=5)
  76. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dvp_app_id]=[5]
  77. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(DVP_CALL_DIRECTION=inbound)
  78. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [DVP_CALL_DIRECTION]=[inbound]
  79. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 set(effective_caller_id_number=767432661)
  80. 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]
  81. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 set(DVP_OPERATION_CAT=HTTAPI)
  82. 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]
  83. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 export(dvp_app_type=HTTAPI)
  84. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dvp_app_type]=[HTTAPI]
  85. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 answer()
  86. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_core_media.c:3912 Activate Buggy RFC2833 Mode!
  87. 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]
  88. 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
  89. 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]
  90. 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]
  91. 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]
  92. 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
  93. 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]
  94. 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]
  95. 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
  96. 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
  97. 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
  98. 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
  99. 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
  100. 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
  101. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:19.841700 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
  102. 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
  103. 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
  104. 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
  105. 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!
  106. 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
  107. 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.
  108. 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:
  109. 25e10655-a9b9-4447-bbe0-4c3081149afb v=0
  110. 25e10655-a9b9-4447-bbe0-4c3081149afb o=FreeSWITCH 1498011131 1498011132 IN IP4 172.20.100.156
  111. 25e10655-a9b9-4447-bbe0-4c3081149afb s=FreeSWITCH
  112. 25e10655-a9b9-4447-bbe0-4c3081149afb c=IN IP4 172.20.100.156
  113. 25e10655-a9b9-4447-bbe0-4c3081149afb t=0 0
  114. 25e10655-a9b9-4447-bbe0-4c3081149afb m=audio 21788 RTP/AVP 0 101
  115. 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:0 PCMU/8000
  116. 25e10655-a9b9-4447-bbe0-4c3081149afb a=rtpmap:101 telephone-event/8000
  117. 25e10655-a9b9-4447-bbe0-4c3081149afb a=fmtp:101 0-16
  118. 25e10655-a9b9-4447-bbe0-4c3081149afb a=ptime:20
  119. 25e10655-a9b9-4447-bbe0-4c3081149afb a=sendrecv
  120. 25e10655-a9b9-4447-bbe0-4c3081149afb
  121. 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
  122. 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
  123. 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]
  124. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 httapi({url=http://httpprogrammingapi.facetone.combank.lk/})
  125. 2017-06-21 13:45:19.921638 [CRIT] mod_httapi.c:1160 Debugging Return Data:
  126. <?xml version="1.0"?>
  127. <document type="text/freeswitch-httapi">
  128.   <variables>
  129.     <tags>[object Object]</tags>
  130.   </variables>
  131.   <work>
  132.     <continue action="http://httpprogrammingapi.facetone.combank.lk"/>
  133.   </work>
  134. </document>
  135. 2017-06-21 13:45:19.921638 [DEBUG] mod_httapi.c:1229 Process Tag: [continue]
  136. 2017-06-21 13:45:19.941642 [CRIT] mod_httapi.c:1160 Debugging Return Data:
  137. <?xml version="1.0"?>
  138. <document type="text/freeswitch-httapi">
  139.   <variables/>
  140.   <params/>
  141.   <work>
  142.     <getVariable action="http://httpprogrammingapi.facetone.combank.lk" temp-action="http://httpprogrammingapi.facetone.combank.lk" name="effective_caller_id_number" permanent="true"/>
  143.   </work>
  144. </document>
  145. 2017-06-21 13:45:19.941642 [DEBUG] mod_httapi.c:1229 Process Tag: [getVariable]
  146. 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]
  147. 2017-06-21 13:45:20.001637 [CRIT] mod_httapi.c:1160 Debugging Return Data:
  148. <?xml version="1.0"?>
  149. <document type="text/freeswitch-httapi">
  150.   <variables/>
  151.   <params/>
  152.   <work>
  153.     <execute action="http://httpprogrammingapi.facetone.combank.lk" temp-action="http://httpprogrammingapi.facetone.combank.lk" application="play_and_get_digits" data="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}"/>
  154.     <getVariable name="result"/>
  155.   </work>
  156. </document>
  157. 2017-06-21 13:45:20.001637 [DEBUG] mod_httapi.c:1229 Process Tag: [execute]
  158. 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})
  159. span style="color: goldenrod; font-weight: bold;"> 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)
  160. 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
  161. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:20.021673 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed.
  162. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:21.581729 [DEBUG] switch_rtp.c:7480 RTP RECV DTMF 1:904
  163. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:21.581729 [INFO] switch_channel.c:515 RECV DTMF 1:904
  164. 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
  165. 2017-06-21 13:45:21.601646 [DEBUG] mod_httapi.c:1229 Process Tag: [getVariable]
  166. 2017-06-21 13:45:21.601646 [CRIT] mod_httapi.c:1160 Debugging Return Data:
  167. <?xml version="1.0"?>
  168. <document type="text/freeswitch-httapi">
  169.   <variables>
  170.     <default_language>eng</default_language>
  171.   </variables>
  172.   <work>
  173.     <continue action="http://httpprogrammingapi.facetone.combank.lk"/>
  174.   </work>
  175. </document>
  176. 2017-06-21 13:45:21.601646 [DEBUG] mod_httapi.c:1229 Process Tag: [continue]
  177. 2017-06-21 13:45:21.661710 [CRIT] mod_httapi.c:1160 Debugging Return Data:
  178. <?xml version="1.0"?>
  179. <document type="text/freeswitch-httapi">
  180.   <variables/>
  181.   <params/>
  182.   <work>
  183.     <execute action="http://httpprogrammingapi.facetone.combank.lk" temp-action="http://httpprogrammingapi.facetone.combank.lk" application="play_and_get_digits" data="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}"/>
  184.     <getVariable name="1"/>
  185.   </work>
  186. </document>
  187. 2017-06-21 13:45:21.661710 [DEBUG] mod_httapi.c:1229 Process Tag: [execute]
  188. 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})
  189. span style="color: goldenrod; font-weight: bold;"> 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)
  190. 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
  191. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:25.221696 [DEBUG] switch_rtp.c:7480 RTP RECV DTMF 1:824
  192. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:25.221696 [INFO] switch_channel.c:515 RECV DTMF 1:824
  193. 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
  194. 2017-06-21 13:45:25.241688 [DEBUG] mod_httapi.c:1229 Process Tag: [getVariable]
  195. 2017-06-21 13:45:25.281699 [CRIT] mod_httapi.c:1160 Debugging Return Data:
  196. <?xml version="1.0"?>
  197. <document type="text/freeswitch-httapi">
  198.   <variables/>
  199.   <params/>
  200.   <work>
  201.    <playback action="http://httpprogrammingapi.facetone.combank.lk" temp-action="http://httpprogrammingapi.facetone.combank.lk" name="result" file="http://fileservice.facetone.combank.lk/DVP/API/1.0.0.0/InternalFileService/File/DownloadLatest/1/4/CallWillBeRecordedDBEng.wav" error-file="" digit-timeout="1" input-timeout="1" loops="0" terminators="#">
  202.       <bind strip="#">~\d{1}</bind>
  203.     </playback>
  204.   </work>
  205. </document>
  206. 2017-06-21 13:45:25.281699 [DEBUG] mod_httapi.c:1229 Process Tag: [playback]
  207. 2017-06-21 13:45:25.281699 [INFO] switch_ivr_async.c:214 Digit parser HTTAPI: Setting realm to 'default'
  208. 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
  209. 2017-06-21 13:45:25.281699 [INFO] switch_ivr_async.c:214 Digit parser HTTAPI: Setting realm to 'default'
  210. 2017-06-21 13:45:25.281699 [DEBUG] switch_ivr_async.c:203 Digit parser HTTAPI: Setting terminators for realm 'default' to '#'
  211. span style="color: goldenrod; font-weight: bold;"> 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)
  212. 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
  213. 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
  214. 2017-06-21 13:45:29.381635 [CRIT] mod_httapi.c:1160 Debugging Return Data:
  215. <?xml version="1.0"?>
  216. <document type="text/freeswitch-httapi">
  217.   <variables>
  218.     <ards_skill>2,9</ards_skill>
  219.     <ards_priority>0</ards_priority>
  220.     <ards_skill_display>English</ards_skill_display>
  221.     <ards_max_queue_time>0</ards_max_queue_time>
  222.     <companyid>4</companyid>
  223.     <tenantid>1</tenantid>
  224.     <ards_hold_music>English.wav</ards_hold_music>
  225.     <ards_first_announcement/>
  226.     <ards_announcement/>
  227.     <ards_announcement_time/>
  228.     <ards_position_announcement>false</ards_position_announcement>
  229.     <ards_position_language>en</ards_position_language>
  230.   </variables>
  231.   <params/>
  232.   <work>
  233.     <execute action="http://httpprogrammingapi.facetone.combank.lk" temp-action="http://httpprogrammingapi.facetone.combank.lk" application="ards" data="2,9,1,4,"/>
  234.   </work>
  235. </document>
  236. 2017-06-21 13:45:29.381635 [DEBUG] mod_httapi.c:1229 Process Tag: [execute]
  237. 25e10655-a9b9-4447-bbe0-4c3081149afb EXECUTE sofia/external/767432661@172.20.100.3 ards(2,9,1,4,)
  238. 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)
  239. 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
  240. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:30.061699 [DEBUG] mod_ards.c:1324 OutBound Started
  241. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:30.061699 [DEBUG] mod_ards.c:1359 Setting outbound caller_id_name to: 767432661
  242. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:30.061699 [DEBUG] mod_ards.c:1360 Setting outbound caller_id_number to: 767432661
  243. 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
  244. 2017-06-21 13:45:30.081648 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  245. 2017-06-21 13:45:30.121660 [DEBUG] switch_xml.c:2073 caching lookup for user 1004@172.20.100.156 indefinitely
  246. 2017-06-21 13:45:30.141651 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  247. 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]
  248. 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
  249. 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)
  250. 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
  251. 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
  252. 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
  253. 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
  254. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b Local SDP:
  255. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b v=0
  256. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b o=FreeSWITCH 1498003506 1498003507 IN IP4 172.20.100.156
  257. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b s=FreeSWITCH
  258. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b c=IN IP4 172.20.100.156
  259. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b t=0 0
  260. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b m=audio 29424 RTP/AVP 0 8 101
  261. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:0 PCMU/8000
  262. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:8 PCMA/8000
  263. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:101 telephone-event/8000
  264. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=fmtp:101 0-16
  265. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=ptime:20
  266. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=sendrecv
  267. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b
  268. 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
  269. 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
  270. 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
  271. 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)
  272. 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
  273. 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
  274. 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
  275. 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
  276. 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)
  277. 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]
  278. 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
  279. 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
  280. 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]
  281. 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!
  282. 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
  283. 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]
  284. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] sofia.c:7058 Remote SDP:
  285. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b v=0
  286. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b o=- 257604662 1 IN IP4 192.168.116.35
  287. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b s=portsip.com
  288. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b c=IN IP4 192.168.116.35
  289. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b t=0 0
  290. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b m=audio 20004 RTP/AVP 0 8 101
  291. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:0 PCMU/8000
  292. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:8 PCMA/8000
  293. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:101 telephone-event/8000
  294. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=fmtp:101 0-16
  295. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b
  296. 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]
  297. 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]
  298. 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
  299. 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]
  300. 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]
  301. 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]
  302. 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
  303. 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
  304. 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
  305. 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
  306. 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
  307. 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
  308. 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
  309. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:35.741638 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
  310. 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
  311. 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
  312. 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
  313. 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
  314. 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
  315. 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]
  316. 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]
  317. 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
  318. 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
  319. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [ERR] mod_ards.c:1546 Agent leg binding
  320. 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
  321. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [ERR] mod_ards.c:1554 Agent leg binding
  322. 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
  323. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:35.801645 [ERR] mod_ards.c:1563 Agent leg binding
  324. 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
  325. 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
  326. 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
  327. 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)
  328. 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
  329. 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
  330. 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
  331. 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
  332. 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)
  333. 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
  334. 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
  335. 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
  336. 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
  337. 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
  338. 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
  339. 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
  340. 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)
  341. 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
  342. 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
  343. 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
  344. 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
  345. 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)
  346. 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
  347. 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
  348. 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
  349. 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
  350. 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
  351. 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)
  352. 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
  353. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.341646 [DEBUG] mod_sofia.c:637 SOFIA SOFT_EXECUTE
  354. 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
  355. 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
  356. 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)
  357. 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
  358. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.341646 [DEBUG] mod_sofia.c:637 SOFIA SOFT_EXECUTE
  359. 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
  360. 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
  361. 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
  362. 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
  363. 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)
  364. 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
  365. 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
  366. 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
  367. 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
  368. 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)
  369. 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
  370. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  371. 25e10655-a9b9-4447-bbe0-4c3081149afb 2017-06-21 13:45:41.361660 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
  372. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 13:45:41.361660 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed.
  373. 2017-06-21 13:45:45.441709 [INFO] mod_shout.c:332 LAME 3.99.5 64bits (http://lame.sf.net)
  374. 2017-06-21 13:45:45.441709 [INFO] mod_shout.c:332 polyphase lowpass filter disabled
  375. 2017-06-21 13:46:40.301675 [CRIT] switch_core.c:2723 Declinatio Mortuus Obfirmo!
  376.  
  377. ====================================================================================================================================================
  378.  
  379.  
  380. FS_SLAVE LOG
  381.  
  382.  
  383. 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]
  384. 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]
  385. 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)
  386. 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
  387. 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
  388. 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
  389. 2017-06-21 04:14:21.607165 [WARNING] switch_core_sqldb.c:2987 Invalid cdr data, call not recovered
  390. 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]
  391. 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]
  392. 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
  393. 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
  394. 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
  395. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.607165 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
  396. 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
  397. 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
  398. 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
  399. 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
  400. 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
  401. 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)
  402. 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
  403. 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
  404. 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
  405. 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
  406. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b Local SDP:
  407. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b v=0
  408. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b o=FreeSWITCH 1498003437 1498003439 IN IP4 172.20.100.156
  409. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b s=FreeSWITCH
  410. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b c=IN IP4 172.20.100.156
  411. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b t=0 0
  412. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b m=audio 29424 RTP/AVP 0 101
  413. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:0 PCMU/8000
  414. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:101 telephone-event/0
  415. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=fmtp:101 0-16
  416. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=ptime:20
  417. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=sendrecv
  418. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b
  419. 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
  420. 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
  421. 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
  422. 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)
  423. 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]
  424. 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
  425. 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
  426. 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
  427. 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
  428. 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]
  429. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b 2017-06-21 04:14:21.907224 [DEBUG] sofia.c:7058 Remote SDP:
  430. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b v=0
  431. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b o=- 257604662 2 IN IP4 192.168.116.35
  432. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b s=portsip.com
  433. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b c=IN IP4 192.168.116.35
  434. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b t=0 0
  435. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b m=audio 20004 RTP/AVP 0
  436. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b a=rtpmap:0 PCMU/8000
  437. 8b0f74c6-86b4-46f2-b77e-c4e758a5932b
  438. 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]
  439. 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]
  440. 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
  441. 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]
  442. 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.
  443. 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
  444. 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.
  445. 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]
  446. 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)
  447. 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
  448. 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
  449. 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
  450. 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
  451. 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
  452. 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
  453. 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)
  454. 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
  455. 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]
  456. 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
  457. 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
  458. 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
  459. 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
  460. 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
  461. 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]
  462. 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)
  463. 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
  464. 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
  465. 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
  466. 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
  467.  
  468.  
  469.  
  470. ==================================================================================================================================================
  471.  
  472.  
  473. EXTERNAL Profile
  474.  
  475.  
  476. <profile name="external">
  477.   <gateways>
  478.     <X-PRE-PROCESS cmd="include" data="external/*.xml"/>
  479.   </gateways>
  480.   <aliases>  
  481.   </aliases>
  482.   <domains>
  483.     <domain name="all" alias="false" parse="true"/>
  484.   </domains>
  485.   <settings>
  486.     <param name="debug" value="0"/>
  487.     <param name="sip-trace" value="no"/>
  488.     <param name="sip-capture" value="no"/>
  489.     <param name="rfc2833-pt" value="101"/>
  490.     <param name="sip-port" value="$${external_sip_port}"/>
  491.     <param name="dialplan" value="XML"/>
  492.     <param name="context" value="public"/>
  493.     <param name="dtmf-duration" value="2000"/>
  494.     <param name="inbound-codec-prefs" value="$${global_codec_prefs}"/>
  495.     <param name="outbound-codec-prefs" value="$${outbound_codec_prefs}"/>
  496.     <param name="hold-music" value="$${hold_music}"/>
  497.     <param name="rtp-timer-name" value="soft"/>
  498.         <param name="rtp-rewrite-timestamps" value="true"/>
  499.         <param name="local-network-acl" value="localnet.auto"/>
  500.     <param name="manage-presence" value="false"/>
  501.     <param name="track-calls" value="true"/>
  502.     <param name="inbound-codec-negotiation" value="generous"/>
  503.     <param name="nonce-ttl" value="60"/>
  504.     <param name="auth-calls" value="false"/>
  505.     <param name="inbound-late-negotiation" value="true"/>
  506.     <param name="inbound-zrtp-passthru" value="true"/>
  507.     <param name="rtp-ip" value="172.20.100.156"/>
  508.     <param name="sip-ip" value="172.20.100.156"/>
  509.     <param name="ext-rtp-ip" value="172.20.100.156"/>
  510.     <param name="ext-sip-ip" value="172.20.100.156"/>
  511.     <param name="rtp-timeout-sec" value="300"/>
  512.     <param name="rtp-hold-timeout-sec" value="1800"/>
  513.     <param name="odbc-dsn" value="odbc://freeswitch:DB_User:DB_PWD"/>
  514.     <param name="tls" value="$${external_ssl_enable}"/>
  515.     <param name="tls-only" value="false"/>
  516.     <param name="tls-bind-params" value="transport=tls"/>
  517.     <param name="tls-sip-port" value="$${external_tls_port}"/>
  518.         <param name="tls-passphrase" value=""/>
  519.     <param name="tls-verify-date" value="true"/>
  520.         <param name="tls-verify-policy" value="none"/>
  521.     <param name="tls-verify-depth" value="2"/>
  522.     <param name="tls-verify-in-subjects" value=""/>
  523.     <param name="tls-version" value="$${sip_tls_version}"/>
  524.   </settings>
  525. </profile>
  526.  
  527.  
  528.  
  529. =======================================================================================================================
  530.  
  531. INTERNAL Profile
  532.  
  533.  
  534. <profile name="internal">
  535.   <aliases>
  536.   </aliases>
  537.   <gateways>
  538.   </gateways>
  539.   <domains>
  540.     <domain name="all" alias="true" parse="false"/>
  541.   </domains>
  542.   <settings>
  543.     <param name="debug" value="0"/>
  544.     <param name="sip-trace" value="no"/>
  545.     <param name="sip-capture" value="no"/>
  546.     <param name="watchdog-enabled" value="no"/>
  547.     <param name="watchdog-step-timeout" value="30000"/>
  548.     <param name="watchdog-event-timeout" value="30000"/>
  549.     <param name="log-auth-failures" value="false"/>
  550.     <param name="forward-unsolicited-mwi-notify" value="false"/>
  551.     <param name="context" value="public"/>
  552.     <param name="rfc2833-pt" value="101"/>
  553.         <param name="sip-port" value="$${internal_sip_port}"/>
  554.     <param name="dialplan" value="XML"/>
  555.     <param name="dtmf-duration" value="2000"/>
  556.     <param name="inbound-codec-prefs" value="$${global_codec_prefs}"/>
  557.     <param name="outbound-codec-prefs" value="$${global_codec_prefs}"/>
  558.     <param name="rtp-timer-name" value="soft"/>
  559.         <param name="rtp-ip" value="172.20.100.156"/>
  560.         <param name="sip-ip" value="172.20.100.156"/>
  561.     <param name="hold-music" value="$${hold_music}"/>
  562.     <param name="apply-nat-acl" value="nat.auto"/>
  563.     <param name="track-calls" value="true"/>
  564.         <param name="apply-candidate-acl" value="rfc1918.auto"/>
  565.     <param name="apply-inbound-acl" value="domains"/>
  566.     <param name="local-network-acl" value="localnet.auto"/>
  567.     <param name="record-path" value="$${recordings_dir}"/>
  568.     <param name="record-template" value="${caller_id_number}.${target_domain}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav"/>
  569.     <param name="manage-presence" value="true"/>
  570.     <param name="presence-hosts" value="172.20.100.156"/>
  571.     <param name="presence-privacy" value="$${presence_privacy}"/>
  572.     <param name="inbound-codec-negotiation" value="generous"/>
  573.     <param name="tls" value="$${internal_ssl_enable}"/>
  574.     <param name="tls-only" value="false"/>
  575.         <param name="tls-bind-params" value="transport=tls"/>
  576.         <param name="tls-sip-port" value="$${internal_tls_port}"/>
  577.         <param name="tls-passphrase" value=""/>
  578.         <param name="tls-verify-date" value="true"/>
  579.     <param name="tls-verify-policy" value="none"/>
  580.         <param name="tls-verify-depth" value="2"/>
  581.         <param name="tls-verify-in-subjects" value=""/>
  582.         <param name="tls-version" value="$${sip_tls_version}"/>
  583.         <param name="tls-ciphers" value="$${sip_tls_ciphers}"/>
  584.         <param name="odbc-dsn" value="odbc://freeswitch:DB_User:DB_PWD"/>
  585.         <param name="inbound-late-negotiation" value="true"/>
  586.         <param name="inbound-zrtp-passthru" value="true"/>
  587.         <param name="nonce-ttl" value="60"/>
  588.         <param name="auth-calls" value="$${internal_auth_calls}"/>
  589.         <param name="inbound-reg-force-matching-username" value="true"/>
  590.         <param name="auth-all-packets" value="false"/>
  591.         <param name="ext-rtp-ip" value="172.20.100.156"/>
  592.     <param name="ext-sip-ip" value="172.20.100.156"/>
  593.         <param name="rtp-timeout-sec" value="300"/>
  594.     <param name="rtp-hold-timeout-sec" value="1800"/>
  595.         <param name="force-register-domain" value="$${domain}"/>
  596.         <param name="force-subscription-domain" value="$${domain}"/>
  597.         <param name="force-register-db-domain" value="$${domain}"/>
  598.         <param name="ws-binding"  value=":5066"/>
  599.         <param name="wss-binding" value=":7443"/>    
  600.         <param name="challenge-realm" value="auto_from"/>
  601.   </settings>
  602. </profile>
  603.  
  604.  
  605. ===================================================================================================================================
  606.  
  607. SWITCH.CONF.XML
  608.  
  609.  
  610. <configuration name="switch.conf" description="Core Configuration">
  611.  
  612.   <cli-keybindings>
  613.     <key name="1" value="help"/>
  614.     <key name="2" value="status"/>
  615.     <key name="3" value="show channels"/>
  616.     <key name="4" value="show calls"/>
  617.     <key name="5" value="sofia status"/>
  618.     <key name="6" value="reloadxml"/>
  619.     <key name="7" value="console loglevel 0"/>
  620.     <key name="8" value="console loglevel 7"/>
  621.     <key name="9" value="sofia status profile internal"/>
  622.     <key name="10" value="sofia profile internal siptrace on"/>
  623.     <key name="11" value="sofia profile internal siptrace off"/>
  624.     <key name="12" value="version"/>
  625.   </cli-keybindings>
  626.  
  627.   <default-ptimes>
  628.   </default-ptimes>
  629.  
  630.   <settings>
  631.     <param name="colorize-console" value="true"/>
  632.     <param name="dialplan-timestamps" value="false"/>
  633.     <param name="switchname" value="2"/>
  634.     <param name="max-db-handles" value="50"/>
  635.     <param name="db-handle-timeout" value="10"/>
  636.     <param name="max-sessions" value="1000"/>
  637.     <param name="sessions-per-second" value="30"/>
  638.     <param name="loglevel" value="debug"/>
  639.     <param name="mailer-app" value="sendmail"/>
  640.     <param name="mailer-app-args" value="-t"/>
  641.     <param name="dump-cores" value="yes"/>
  642.     <param name="rtp-enable-zrtp" value="false"/>
  643.     <param name="core-db-dsn" value="odbc://freeswitch:DB_User:DB_PWD"/>
  644.     <param name="core-recovery-db-dsn" value="odbc://freeswitch:DB_User:DB_PWD"/>
  645.     <param name="core-dbtype" value="PGSQL"/>
  646.  
  647.   </settings>
  648.  
  649. </configuration>
  650.  
  651.