From shashi kumar, 6 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: cornflowerblue; font-weight: bold;"> d81122c6 2018-07-20 12:25:17.952196 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/9000@10.10.0.190:5060 [d81122c6-f88c-4026-bc30-40955415a4d0]
  2. 2018-07-20 12:25:18.172201 [DEBUG] switch_xml.c:1988 Cache Info
  3. Time Now:al/900015321147181722010) Running State Change CS_NEW (Cur 1 Tot 103)
  4. Expires: 2018-071532114513592195196 [DEBUG] sofia.c:9873 sofia/internal/9000@10.2018-07-20 12:25:18.172201 [DEBUG] switch_xml.c:1990 Cache expired for 3001@10.10.0.190, doing fresh lookup
  5. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [received][100]
  6. d81122c6 2018-07-20 12:25:17.952196 [DEBUG] switch_core_state_machine.c:603 (sofd81122c6 2018-07-20 12:25:18.372199 [DEBUG] sofia.c:7094 Remote SDP:
  7. v=08-07-20 12:25:17.952196 [DEBUG] sofia.c:2334 detaching session d81122c6-f88c-o=- 20082 20082 IN IP4 10.10.0.30
  8. s=SDP data 12:25:17.972193 [DEBUG] sofia.c:2442 Re-attaching to session d81122c6c=IN IP4 10.10.0.3055415a4d0
  9. t=0 022c6 2018-07-20 12:25:17.972193 [DEBUG] sofia.c:9873 sofia/internal/9000@10m=audio 12428 RTP/AVP 9 0 8 18 101om 10.10.0.30:5060 version: 1.6.20 -37-987c9b9a=rtpmap:9 G722/8000
  10. a=rtpmap:0 PCMU/8000972193 [DEBUG] sofia.c:10044 IP 10.10.0.30 Rejected by acl "a=rtpmap:8 PCMA/8000ck to Digest auth.
  11. a=rtpmap:18 G729/800072193 [DEBUG] switch_xml.c:1988 Cache Info
  12. a=fmtp:18 annexb=no2114717972193
  13. a=rtpmap:101 telephone-event/8000
  14. a=fmtp:101 0-1525:17.972193 [DEBUG] switch_xml.c:1990 Cache expired for 9000@10.a=ptime:20doing fresh lookup
  15.  
  16. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] sofia.c:7486 (sofia/internal/9000@10.10.0.190:5060) State Change CS_NEW -> CS_INIT
  17. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_INIT (Cur 1 Tot 103)
  18. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/9000@10.10.0.190:5060) State INIT
  19. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] mod_sofia.c:90 sofia/internal/9000@10.10.0.190:5060 SOFIA INIT
  20. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] switch_core_state_machine.c:40 sofia/internal/9000@10.10.0.190:5060 Standard INIT
  21. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/9000@10.10.0.190:5060) State Change CS_INIT -> CS_ROUTING
  22. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/9000@10.10.0.190:5060) State INIT going to sleep
  23. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_ROUTING (Cur 1 Tot 103)
  24. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] switch_channel.c:2249 (sofia/internal/9000@10.10.0.190:5060) Callstate Change DOWN -> RINGING
  25. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/9000@10.10.0.190:5060) State ROUTING
  26. >d81122c6 2018-07-20 12:25:18.372199 [DEBUG] mod_sofia.c:143 sofia/internal/9000@10.10.0.190:5060 SOFIA ROUTING
  27. d81122c6 2018-07-20 12:25:18.372199 [DEBUG] switch_core_state_machine.c:236 sofia/internal/9000@10.10.0.190:5060 Standard ROUTING
  28. d81122c6 2018-07-20 12:25:18.372199 [INFO] mod_dialplan_xml.c:637 Processing 9000 <9000>->3001 in context default
  29. 2018-07-20 12:25:18.752197 [DEBUG] switch_xml.c:1988 Cache Info
  30. Time Now:       1532114718752197
  31. Expires:        1532114513592195
  32. 2018-07-20 12:25:18.752197 [DEBUG] switch_xml.c:1990 Cache expired for 3001@10.10.0.190, doing fresh lookup
  33. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Bell_Ring] continue=false
  34. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Bell_Ring] destination_number(3001) =~ /^9010$/ break=on-false
  35. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Earthquake] continue=false
  36. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Earthquake] destination_number(3001) =~ /^9011$/ break=on-false
  37. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Fire] continue=false
  38. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Fire] destination_number(3001) =~ /^9012$/ break=on-false
  39. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Lightning] continue=false
  40. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Lightning] destination_number(3001) =~ /^9014$/ break=on-false
  41. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-National_Anthem] continue=false
  42. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-National_Anthem] destination_number(3001) =~ /^9015$/ break=on-false
  43. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Tornado] continue=false
  44. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Tornado] destination_number(3001) =~ /^9016$/ break=on-false
  45. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Lockout] continue=false
  46. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Lockout] destination_number(3001) =~ /^9017$/ break=on-false
  47. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Lockdown] continue=false
  48. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Lockdown] destination_number(3001) =~ /^9018$/ break=on-false
  49. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->notification-Custom_Announcement] continue=false
  50. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [notification-Custom_Announcement] destination_number(3001) =~ /^9999$/ break=on-false
  51. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->unloop] continue=false
  52. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  53. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  54. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->global-intercept] continue=false
  55. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [global-intercept] destination_number(3001) =~ /^886$/ break=on-false
  56. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->school_sipcall_intercom] continue=false
  57. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [school_sipcall_intercom] destination_number(3001) =~ /^(9000)$/ break=on-false
  58. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->school_sipcall_emergency] continue=false
  59. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [school_sipcall_emergency] destination_number(3001) =~ /^(9050)$/ break=on-false
  60. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->group-intercept] continue=false
  61. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group-intercept] destination_number(3001) =~ /^\*8$/ break=on-false
  62. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->intercept-ext] continue=false
  63. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [intercept-ext] destination_number(3001) =~ /^\*\*(\d+)$/ break=on-false
  64. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->redial] continue=false
  65. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [redial] destination_number(3001) =~ /^(redial|870)$/ break=on-false
  66. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->global] continue=true
  67. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  68. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Absolute Condition [global]
  69. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  70. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  71. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  72. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  73. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->eavesdrop] continue=false
  74. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [eavesdrop] destination_number(3001) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  75. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->eavesdrop] continue=false
  76. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [eavesdrop] destination_number(3001) =~ /^779$/ break=on-false
  77. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->call_return] continue=false
  78. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [call_return] destination_number(3001) =~ /^\*69$|^869$|^lcr$/ break=on-false
  79. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->extension-intercom] continue=false
  80. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [extension-intercom] destination_number(3001) =~ /^8([1-3]\d{3})$/ break=on-false
  81. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->main_ivr] continue=false
  82. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [main_ivr] destination_number(3001) =~ /^6000$/ break=on-false
  83. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->group_dial_paging_zones] continue=false
  84. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6060$/ break=never
  85. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6061$/ break=never
  86. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6062$/ break=never
  87. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6001$/ break=never
  88. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6002$/ break=never
  89. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6003$/ break=never
  90. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6004$/ break=never
  91. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6000$/ break=never
  92. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6005$/ break=never
  93. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6006$/ break=never
  94. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6200$/ break=never
  95. Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6(\d{3})$/ break=on-false
  96. [d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [group_dial_paging_zones] destination_number(3001) =~ /^6(\d{3})$/ break=on-false
  97. m[[mmffreerseweistwfictefscee@spes@wp>siw->rit-crxtwc>x@ws>w@-s w- pphhxx>>[d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->mad_boss_intercom] continue=false
  98. Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->mad_boss_intercom] continue=false
  99. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->mad_boss_intercom] continue=false
  100. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [mad_boss_intercom] destination_number(3001) =~ /^0912$/ break=on-false
  101. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->rtp_multicast_page] continue=false
  102. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [rtp_multicast_page] destination_number(3001) =~ /^pagegroup$|^7243$/ break=on-false
  103. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->park] continue=false
  104. -pd81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [park] destination_number(3001) =~ /^6100$/ break=on-false
  105. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->unpark] continue=false
  106. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [unpark] destination_number(3001) =~ /^6101$/ break=on-false
  107. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->valet_park] continue=false
  108. [d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [valet_park] destination_number(3001) =~ /^(6200)$/ break=on-false
  109. [0d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->valet_park] continue=false
  110. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [valet_park] destination_number(3001) =~ /^((?!6200)62\d{2})$/ break=on-false
  111.  
  112. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->fax_receive] continue=false
  113. [0d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [fax_receive] destination_number(3001) =~ /^9178$/ break=on-false
  114.  
  115. [d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 parsing [default->fax_transmit] continue=false
  116. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [fax_transmit] destination_number(3001) =~ /^9179$/ break=on-false
  117. Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [delay_echo] destination_number(3001) =~ /^9195$/ break=on-false
  118. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [delay_echo] destination_number(3001) =~ /^9195$/ break=on-false
  119. Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [echo] destination_number(3001) =~ /^9196$/ break=on-false
  120. d81122c6 Dialplan: sofia/internal/9000@10.10.0.190:5060 Regex (FAIL) [echo] dest2018-07-20 12:25:19.632199 [DEBUG] mod_sofia.c:198 sofia/internal/9000@10.10.0.190:5060 SOFIA EXECUTEfia/internal/9000@10.10.0.190:5060 parsing [default->milliwd81122c6 2018-07-20 12:25:19.632199 [DEBUG] mod_sofia.c:198 sofia/internal/9000@10.10.0.190:5060 SOFIA EXECUTEnal/9000@10.10.0.190:5060 Regex (FAIL) [milliwatt][mfrfereesweiswticht@cshf@-spfh-xprh>x rr>e erseweistwciht@cshw@-spwh-xp>hx>[[�        EXECUTE sofia/internal/9000@10.10.0.190:5060 hash(insert/10.10.0.190-spymap/9000/d81122c6-f88c-4026-bc30-40955415a4d0)
  121. d81122c6 EXECUTE sofia/internal/9000@10.10.0.190:5060 hash(insert/10.10.0.190-spymap/9000/d81122c6-f88c-4026-bc30-40955415a4d0)-false
  122. [[m[[mffrreeeesswwiitfrteehfwprisewehtwpphics> hw@tspwh-cp>h xh>@sw-phx>[35[3EXECUTE sofia/internal/9000@10.10.0.190:5060 hash(insert/10.10.0.190-last_dial/global/d81122c6-f88c-4026-bc30-40955415a4d0)
  123. d81122c6 EXECUTE sofia/internal/9000@10.10.0.190:5060 hash(insert/10.10.0.190-last_dial/global/d81122c6-f88c-4026-bc30-40955415a4d0)=on-false
  124. [[mfrfereeesswiwticthc@hs@wsfwpfhpxh>xr>prepeesewsiwticthc@hs@ws-wp-hpxh>x>[02018-07-20 12:25:19.632199 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 20 Jul 2018 12:25:19 -0700] switch_core_state_machine.c:286 ([d81122c6 2018-07-20 12:25:19.632199 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 20 Jul 2018 12:25:19 -0700]hine.c:584 (sofia/inter�  2018-07-20 12:25:19.632199 [DEBUG] switch_channel.c:1250 sofia/internal/9000@10.10.0.190:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 20 Jul 2018 12:25:19 -0700] to event0.0.190:5060) Running State Change CS_EXECUTE (Cur 1 Tot d81122c6 2018-07-20 12:25:19.632199 [DEBUG] switch_channel.c:1250 sofia/internal/9000@10.10.0.190:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 20 Jul 2018 12:25:19 -0700] to event
  125. [[mfrfereesewswiitcthc@h@ssww-p-hphxx>e>heh  swsiwtictchh@@ssww--pphhxx>>[37�  2018-07-20 12:25:19.632199 [DEBUG] switch_xml.c:1988 Cache Info
  126. Time Now:       1532114719632199
  127. Expires:        1532114513592195
  128. 2018-07-20 12:25:19.632199 [DEBUG] switch_xml.c:1988 Cache Info
  129. Time Now:       1532114719632199
  130. Expires:        1532114513592195
  131. [[mm[[mffrreeeessffiitrtereecesc@ss@ws-wp-hphttcc>>  hh@@ssww--pphhxx>>[[342018-07-20 12:25:19.632199 [DEBUG] switch_xml.c:1990 Cache expired for 3001@10.10.0.190, doing fresh lookup
  132. d81122c6 2018-07-20 12:25:20.032191 [DEBUG] switch_channel.c:1250 sofia/internal/9000@10.10.0.190:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 20 Jul 2018 12:25:19 -0700] to event
  133. d81122c6 2018-07-20 12:25:20.032191 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  134. f44745a4 2018-07-20 12:25:20.032191 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/3001@10.10.0.201 [f44745a4-6054-4153-b529-e57c25f2a27e]
  135. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] mod_sofia.c:4819 (sofia/internal/3001@10.10.0.201) State Change CS_NEW -> CS_INIT
  136. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/3001@10.10.0.201) State INIT
  137. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] mod_sofia.c:90 sofia/internal/3001@10.10.0.201 SOFIA INIT
  138. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] mod_sofia.c:90 sofia/internal/3001@10.10.0.201 SOFIA INIT
  139. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] sofia_glue.c:1295 sofia/internal/3001@10.10.0.201 sending invite version: 1.6.20 -37-987c9b9 64bit
  140. Local SDP:
  141. v=0
  142. o=FreeSWITCH 1532086614 1532086615 IN IP4 10.10.0.190
  143. s=FreeSWITCH
  144. c=IN IP4 10.10.0.190
  145. t=0 0
  146. m=audio 28106 RTP/AVP 0 8 101
  147. a=rtpmap:0 PCMU/8000
  148. a=rtpmap:8 PCMA/8000
  149. a=rtpmap:101 telephone-event/8000
  150. a=fmtp:101 0-16
  151. a=ptime:20
  152. a=sendrecv
  153.  
  154. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:40 sofia/internal/3001@10.10.0.201 Standard INIT
  155. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/3001@10.10.0.201) State Change CS_INIT -> CS_ROUTING
  156. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/3001@10.10.0.201) State INIT going to sleep
  157. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_ROUTING (Cur 2 Tot 104)
  158. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] sofia.c:7084 Channel sofia/internal/3001@10.10.0.201 entering state [calling][0]
  159. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/3001@10.10.0.201) State ROUTING
  160. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] mod_sofia.c:143 sofia/internal/3001@10.10.0.201 SOFIA ROUTING
  161. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/3001@10.10.0.201) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  162. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/3001@10.10.0.201) State ROUTING going to sleep
  163. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 104)
  164. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/3001@10.10.0.201) State CONSUME_MEDIA
  165. f44745a4 2018-07-20 12:25:20.032191 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/3001@10.10.0.201) State CONSUME_MEDIA going to sleep
  166. f44745a4 2018-07-20 12:25:20.052207 [DEBUG] sofia.c:7084 Channel sofia/internal/3001@10.10.0.201 entering state [proceeding][180]
  167. f44745a4 2018-07-20 12:25:20.052207 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/3001@10.10.0.201!
  168. f44745a4 2018-07-20 12:25:20.052207 [DEBUG] switch_channel.c:3346 (sofia/internal/3001@10.10.0.201) Callstate Change DOWN -> RINGING
  169. d81122c6 2018-07-20 12:25:20.052207 [NOTICE] mod_sofia.c:2273 Ring-Ready sofia/internal/9000@10.10.0.190:5060!
  170. d81122c6 2018-07-20 12:25:20.052207 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [early][180]
  171. d81122c6 2018-07-20 12:25:20.052207 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/internal/9000@10.10.0.190:5060!
  172. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] sofia.c:7084 Channel sofia/internal/3001@10.10.0.201 entering state [completing][200]
  173. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] sofia.c:7094 Remote SDP:
  174. v=0
  175. o=3001 1052092996 591622851 IN IP4 10.10.0.201
  176. s=-
  177. c=IN IP4 10.10.0.201
  178. t=0 0
  179. m=audio 8000 RTP/AVP 0
  180. a=rtpmap:0 PCMU/8000
  181. a=ptime:20
  182.  
  183. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] sofia.c:7084 Channel sofia/internal/3001@10.10.0.201 entering state [ready][200]
  184. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  185. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  186. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  187. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/3001@10.10.0.201 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  188. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_codec.c:111 sofia/internal/3001@10.10.0.201 Original read codec set to PCMU:0
  189. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4738 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  190. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4767 sofia/internal/3001@10.10.0.201 Set 2833 dtmf send payload to 101 recv payload to 101
  191. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/3001@10.10.0.201] 10.10.0.190 port 28106 -> 10.10.0.201 port 8000 codec: 0 ms: 20
  192. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  193. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:7180 sofia/internal/3001@10.10.0.201 Set 2833 dtmf send payload to 101
  194. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:7187 sofia/internal/3001@10.10.0.201 Set 2833 dtmf receive payload to 101
  195. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:7210 sofia/internal/3001@10.10.0.201 Set rtp dtmf delay to 40
  196. f44745a4 2018-07-20 12:25:20.072196 [NOTICE] sofia.c:8218 Channel [sofia/internal/3001@10.10.0.201] has been answered
  197. f44745a4 2018-07-20 12:25:20.072196 [DEBUG] switch_channel.c:3773 (sofia/internal/3001@10.10.0.201) Callstate Change RINGING -> ACTIVE
  198. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  199. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  200. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  201. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  202. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  203. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  204. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  205. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  206. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  207. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  208. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  209. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  210. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  211. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  212. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  213. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/9000@10.10.0.190:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  214. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_codec.c:111 sofia/internal/9000@10.10.0.190:5060 Original read codec set to PCMU:0
  215. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  216. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:4767 sofia/internal/9000@10.10.0.190:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  217. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/9000@10.10.0.190:5060] 10.10.0.190 port 17468 -> 10.10.0.30 port 12428 codec: 0 ms: 20
  218. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  219. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:7180 sofia/internal/9000@10.10.0.190:5060 Set 2833 dtmf send payload to 101
  220. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:7187 sofia/internal/9000@10.10.0.190:5060 Set 2833 dtmf receive payload to 101
  221. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:7210 sofia/internal/9000@10.10.0.190:5060 Set rtp dtmf delay to 40
  222. d81122c6 2018-07-20 12:25:20.072196 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/9000@10.10.0.190:5060!
  223. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_channel.c:3474 (sofia/internal/9000@10.10.0.190:5060) Callstate Change RINGING -> EARLY
  224. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/9000@10.10.0.190:5060.
  225. d81122c6 2018-07-20 12:25:20.072196 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/9000@10.10.0.190:5060:
  226. v=0
  227. o=FreeSWITCH 1532097252 1532097253 IN IP4 10.10.0.190
  228. s=FreeSWITCH
  229. c=IN IP4 10.10.0.190
  230. t=0 0
  231. m=audio 17468 RTP/AVP 0 101
  232. a=rtpmap:0 PCMU/8000
  233. a=rtpmap:101 telephone-event/8000
  234. a=fmtp:101 0-16
  235. a=ptime:20
  236. a=sendrecv
  237.  
  238. d81122c6 2018-07-20 12:25:20.072196 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/internal/9000@10.10.0.190:5060] has been answered
  239. d81122c6 2018-07-20 12:25:20.092193 [DEBUG] switch_channel.c:3773 (sofia/internal/9000@10.10.0.190:5060) Callstate Change EARLY -> ACTIVE
  240. d81122c6 2018-07-20 12:25:20.092193 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [completed][200]
  241. d81122c6 2018-07-20 12:25:20.092193 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/3001@10.10.0.201]
  242. d81122c6 2018-07-20 12:25:20.092193 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/3001@10.10.0.201]
  243. f44745a4 2018-07-20 12:25:20.092193 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/3001@10.10.0.201) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  244. f44745a4 2018-07-20 12:25:20.092193 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 104)
  245. f44745a4 2018-07-20 12:25:20.092193 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/3001@10.10.0.201) State EXCHANGE_MEDIA
  246. f44745a4 2018-07-20 12:25:20.092193 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  247. d81122c6 2018-07-20 12:25:20.092193 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [ready][200]
  248. d81122c6 2018-07-20 12:25:20.112197 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [calling][0]
  249. f44745a4 2018-07-20 12:25:20.132210 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  250. d81122c6 2018-07-20 12:25:20.132210 [DEBUG] sofia.c:7084 Channel sofia/internal/9000@10.10.0.190:5060 entering state [ready][200]
  251. frd81122c6e e2018-07-20 12:25:20.132210 [DEBUG] sofia.c:7094 Remote SDP:
  252. v=0
  253. o=- 20082 20083 IN IP4 10.10.0.30
  254. s=SDP data
  255. c=IN IP4 10.10.0.30
  256. t=0 0
  257. m=audio 12428 RTP/AVP 0 101
  258. a=rtpmap:0 PCMU/8000
  259. a=rtpmap:101 telephone-event/8000
  260. a=fmtp:101 0-15
  261. a=ptime:20
  262.  
  263. d81122c6 2018-07-20 12:25:20.132210 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  264. d81122c6 2018-07-20 12:25:20.132210 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  265. d81122c6 2018-07-20 12:25:20.132210 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  266. d81122c6 2018-07-20 12:25:20.132210 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  267. d81122c6 2018-07-20 12:25:20.132210 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  268. d81122c6 2018-07-20 12:25:20.132210 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  269. d81122c6 2018-07-20 12:25:20.132210 [DEBUG] switch_core_media.c:4767 sofia/internal/9000@10.10.0.190:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  270. d81122c6 2018-07-20 12:25:20.132210 [DEBUG] sofia.c:8061 Processing updated SDP
  271. phxd81122c6 2018-07-20 12:25:20.132210 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/9000@10.10.0.190:5060.
  272. d81122c6 2018-07-20 12:25:20.192190 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  273. d81122c6 2018-07-20 12:25:24.952219 [NOTICE] sofia.c:1012 Hangup sofia/internal/9000@10.10.0.190:5060 [CS_EXECUTE] [NORMAL_CLEARING]
  274. d81122c6 2018-07-20 12:25:24.952219 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/9000@10.10.0.190:5060]
  275. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/9000@10.10.0.190:5060 ending bridge by request from write function
  276. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/3001@10.10.0.201]
  277. f44745a4 2018-07-20 12:25:24.972192 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/internal/3001@10.10.0.201 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  278. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/3001@10.10.0.201) State EXCHANGE_MEDIA going to sleep
  279. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_HANGUP (Cur 2 Tot 104)
  280. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/3001@10.10.0.201) Callstate Change ACTIVE -> HANGUP
  281. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/3001@10.10.0.201) State HANGUP
  282. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] mod_sofia.c:432 sofia/internal/3001@10.10.0.201 Overriding SIP cause 480 with 200 from the other leg
  283. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] mod_sofia.c:438 Channel sofia/internal/3001@10.10.0.201 hanging up, cause: NORMAL_CLEARING
  284. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_ivr_bridge.c:1712 sofia/internal/3001@10.10.0.201 skip receive message [UNBRIDGE] (channel is hungup already)
  285. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_ivr_bridge.c:1715 sofia/internal/9000@10.10.0.190:5060 skip receive message [UNBRIDGE] (channel is hungup already)
  286. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/3001@10.10.0.201
  287. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:60 sofia/internal/3001@10.10.0.201 Standard HANGUP, cause: NORMAL_CLEARING
  288. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/3001@10.10.0.201) State HANGUP going to sleep
  289. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/3001@10.10.0.201) State Change CS_HANGUP -> CS_REPORTING
  290. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_session.c:2815 sofia/internal/9000@10.10.0.190:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  291. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/9000@10.10.0.190:5060) State EXECUTE going to sleep
  292. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_HANGUP (Cur 2 Tot 104)
  293. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/3001@10.10.0.201) Running State Change CS_REPORTING (Cur 2 Tot 104)
  294. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/3001@10.10.0.201) State REPORTING
  295. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:174 sofia/internal/3001@10.10.0.201 Standard REPORTING, cause: NORMAL_CLEARING
  296. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/3001@10.10.0.201) State REPORTING going to sleep
  297. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/9000@10.10.0.190:5060) Callstate Change ACTIVE -> HANGUP
  298. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/9000@10.10.0.190:5060) State HANGUP
  299. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] mod_sofia.c:438 Channel sofia/internal/9000@10.10.0.190:5060 hanging up, cause: NORMAL_CLEARING
  300. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/3001@10.10.0.201) State Change CS_REPORTING -> CS_DESTROY
  301. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_session.c:1665 Session 104 (sofia/internal/3001@10.10.0.201) Locked, Waiting on external entities
  302. f44745a4 2018-07-20 12:25:24.972192 [NOTICE] switch_core_session.c:1683 Session 104 (sofia/internal/3001@10.10.0.201) Ended
  303. f44745a4 2018-07-20 12:25:24.972192 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/3001@10.10.0.201 [CS_DESTROY]
  304. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:60 sofia/internal/9000@10.10.0.190:5060 Standard HANGUP, cause: NORMAL_CLEARING
  305. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/9000@10.10.0.190:5060) State HANGUP going to sleep
  306. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/9000@10.10.0.190:5060) State Change CS_HANGUP -> CS_REPORTING
  307. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/3001@10.10.0.201) Running State Change CS_DESTROY (Cur 1 Tot 104)
  308. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/3001@10.10.0.201) State DESTROY
  309. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_REPORTING (Cur 1 Tot 104)
  310. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] mod_sofia.c:343 sofia/internal/3001@10.10.0.201 SOFIA DESTROY
  311. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:181 sofia/internal/3001@10.10.0.201 Standard DESTROY
  312. f44745a4 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/3001@10.10.0.201) State DESTROY going to sleep
  313. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/9000@10.10.0.190:5060) State REPORTING
  314. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:174 sofia/internal/9000@10.10.0.190:5060 Standard REPORTING, cause: NORMAL_CLEARING
  315. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/9000@10.10.0.190:5060) State REPORTING going to sleep
  316. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/9000@10.10.0.190:5060) State Change CS_REPORTING -> CS_DESTROY
  317. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_session.c:1665 Session 103 (sofia/internal/9000@10.10.0.190:5060) Locked, Waiting on external entities
  318. d81122c6 2018-07-20 12:25:24.972192 [NOTICE] switch_core_session.c:1683 Session 103 (sofia/internal/9000@10.10.0.190:5060) Ended
  319. d81122c6 2018-07-20 12:25:24.972192 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/9000@10.10.0.190:5060 [CS_DESTROY]
  320. d81122c6 2018-07-20 12:25:24.972192 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/9000@10.10.0.190:5060) Running State Change CS_DESTROY (Cur 0 Tot2018-07-20 12:25:43.672217 [DEBUG] switch_xml.c:1988 Cache Info
  321. Time Now:0 12:251532114743672217G] switch_xml.c:1990 Cache expired for 9000@10.10.0.190, doing fresh lookup
  322. Expires:-07-20 11532114537672198DEBUG] switch_xml.c:1990 Cache expired for 2201@10.10.0.190, doing fresh lookup
  323. 2018-07-20 12:25:43.672217 [DEBUG] switch_xml.c:1990 Cache expired for 2201@10.10.0.190, doing fresh lookup
  324. 2018-07-20 12:25:44.752191 [DEBUG] switch_xml.c:1988 Cache Info
  325.