FreeSWITCH Pastebin

Registered users not being invited - multiple FS

From Simon Wilson, 6 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/9638f7f5
Embed
Download Paste or View Raw
  1. ********************************************************
  2. FREESWITCH2 - calls are not sent to the registered user
  3. ********************************************************
  4.  
  5. recv 1038 bytes from udp/[10.0.0.146]:5060 at 16:02:09.615698:
  6.    ------------------------------------------------------------------------
  7.    INVITE sip:2200@10.0.0.145 SIP/2.0
  8.    From: london.devserver.sytelco.com <sip:london.devserver.sytelco.com@sipserver.sytel.no-ip.com>;tag=ACU-1370-d75b057b
  9.    To: 2200 <sip:2200@10.0.0.145>
  10.    Contact: <sip:10.0.0.146>
  11.    Call-ID: 0044B001-0000C3E5-59400C81-00000009@10.0.0.146
  12.    CSeq: 28024 INVITE
  13.    Content-Length: 237
  14.    Content-Type: application/sdp
  15.    Allow: INVITE
  16.    Allow: ACK
  17.    Allow: BYE
  18.    Allow: CANCEL
  19.    Allow: OPTIONS
  20.    Allow: NOTIFY
  21.    Allow: REFER
  22.    Allow: PRACK
  23.   Allow: INFO
  24.    Allow: UPDATE
  25.    Allow: MESSAGE
  26.    Accept: application/sdp
  27.    Accept: application/isup
  28.    Accept: application/qsig
  29.    Accept: multipart/mixed
  30.    Accept-Encoding: identity
  31.    Accept-Language: en
  32.    Supported: replaces
  33.    Supported: 100rel
  34.    Via: SIP/2.0/UDP 10.0.0.146:5060;branch=z9hG4bKa7d0f6d5-5051-11e7-854a-c8c2c2f34e3c;rport
  35.    Max-Forwards: 70
  36.    
  37.    v=0
  38.    o=aculab-0044B001 25386 25386 IN IP4 10.0.0.146
  39.    s=-
  40.    c=IN IP4 10.0.0.146
  41.    t=0 0
  42.    m=audio 30790 RTP/AVP 8 18 96
  43.    a=rtpmap:8 PCMA/8000
  44.    a=ptime:20
  45.    a=rtpmap:18 G729/8000
  46.    a=ptime:30
  47.    a=rtpmap:96 telephone-event/8000
  48.    a=fmtp:96 0-15
  49.    ------------------------------------------------------------------------
  50. send 444 bytes to udp/[10.0.0.146]:5060 at 16:02:09.615698:
  51.    ------------------------------------------------------------------------
  52.    SIP/2.0 100 Trying
  53.    Via: SIP/2.0/UDP 10.0.0.146:5060;branch=z9hG4bKa7d0f6d5-5051-11e7-854a-c8c2c2f34e3c;rport=5060
  54.    From: london.devserver.sytelco.com <sip:london.devserver.sytelco.com@sipserver.sytel.no-ip.com>;tag=ACU-1370-d75b057b
  55.    To: 2200 <sip:2200@10.0.0.145>
  56.    Call-ID: 0044B001-0000C3E5-59400C81-00000009@10.0.0.146
  57.    CSeq: 28024 INVITE
  58.    User-Agent: FreeSWITCH-mod_sofia/1.6.17+git~20170417T142108Z~0fc0946461~64bit
  59.    Content-Length: 0
  60.    
  61.    ------------------------------------------------------------------------
  62. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com [1424da2f-2c00-4eef-a4c5-bfc6f7785de0]
  63. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_NEW (Cur 1 Tot 35)
  64. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] sofia.c:9834 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com receiving invite from 10.0.0.146:5060 version: 1.6.17 git 0fc0946 2017-04-17 14:21:08Z 64bit
  65. 2017-06-13 17:02:09.616638 [DEBUG] sofia.c:9950 IP 10.0.0.146 Approved by acl "domains[]". Access Granted.
  66. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] sofia.c:7048 Channel sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com entering state [received][100]
  67. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] sofia.c:7058 Remote SDP:
  68. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 v=0
  69. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 o=aculab-0044B001 25386 25386 IN IP4 10.0.0.146
  70. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 s=-
  71. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 c=IN IP4 10.0.0.146
  72. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 t=0 0
  73. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 m=audio 30790 RTP/AVP 8 18 96
  74. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 a=rtpmap:8 PCMA/8000
  75. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 a=rtpmap:18 G729/8000
  76. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 a=rtpmap:96 telephone-event/8000
  77. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 a=fmtp:96 0-15
  78. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 a=ptime:20
  79. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 a=ptime:30
  80. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0
  81. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] sofia.c:7450 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State Change CS_NEW -> CS_INIT
  82. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State NEW
  83. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_INIT (Cur 1 Tot 35)
  84. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State INIT
  85. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] mod_sofia.c:90 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com SOFIA INIT
  86. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:40 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Standard INIT
  87. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State Change CS_INIT -> CS_ROUTING
  88. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State INIT going to sleep
  89. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_ROUTING (Cur 1 Tot 35)
  90. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_channel.c:2249 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Callstate Change DOWN -> RINGING
  91. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State ROUTING
  92. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] mod_sofia.c:143 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com SOFIA ROUTING
  93. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:236 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Standard ROUTING
  94. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [INFO] mod_dialplan_xml.c:637 Processing london.devserver.sytelco.com <london.devserver.sytelco.com>->2200 in context public
  95. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com parsing [public->unloop] continue=false
  96. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  97. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  98. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com parsing [public->outside_call] continue=true
  99. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Absolute Condition [outside_call]
  100. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action set(outside_call=true)
  101. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  102. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com parsing [public->call_debug] continue=true
  103. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  104. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com parsing [public->extensions_nailup] continue=false
  105. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (PASS) [extensions_nailup] network_addr(10.0.0.146) =~ /10.0.0.146/ break=on-false
  106. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (PASS) [extensions_nailup] destination_number(2200) =~ /^(\d{4})$/ break=on-false
  107. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action export(dialed_extension=2200)
  108. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action set(call_timeout=30)
  109. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action bridge(user/${dialed_extension}@${caller_id_name})
  110. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action hangup()
  111. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State Change CS_ROUTING -> CS_EXECUTE
  112. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State ROUTING going to sleep
  113. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_EXECUTE (Cur 1 Tot 35)
  114. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State EXECUTE
  115. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] mod_sofia.c:198 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com SOFIA EXECUTE
  116. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_core_state_machine.c:328 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Standard EXECUTE
  117. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com set(outside_call=true)
  118. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] mod_dptools.c:1530 SET sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com [outside_call]=[true]
  119. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com export(RFC2822_DATE=Tue, 13 Jun 2017 17:02:09 +0100)
  120. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Tue, 13 Jun 2017 17:02:09 +0100]
  121. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com export(dialed_extension=2200)
  122. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[2200]
  123. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com set(call_timeout=30)
  124. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] mod_dptools.c:1530 SET sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com [call_timeout]=[30]
  125. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com bridge(user/2200@london.devserver.sytelco.com)
  126. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_channel.c:1250 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com EXPORTING[export_vars] [RFC2822_DATE]=[Tue, 13 Jun 2017 17:02:09 +0100] to event
  127. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_channel.c:1250 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com EXPORTING[export_vars] [dialed_extension]=[2200] to event
  128. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.616638 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  129. 2017-06-13 17:02:09.706637 [CONSOLE] mod_xml_curl.c:323 XML response is in C:/Windows/TEMP/bfdc098e-002b-471a-a893-2282c172f22d.tmp.xml
  130. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.706637 [DEBUG] switch_channel.c:1250 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com EXPORTING[export_vars] [RFC2822_DATE]=[Tue, 13 Jun 2017 17:02:09 +0100] to event
  131. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.706637 [DEBUG] switch_channel.c:1250 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com EXPORTING[export_vars] [dialed_extension]=[2200] to event
  132. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:09.706637 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  133. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.706637 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/2200@df7jal23ls0d.invalid [76552e5e-be86-445e-82e7-baafc543c76d]
  134. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.706637 [DEBUG] mod_sofia.c:4818 (sofia/internal/2200@df7jal23ls0d.invalid) State Change CS_NEW -> CS_INIT
  135. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_INIT (Cur 2 Tot 36)
  136. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/2200@df7jal23ls0d.invalid) State INIT
  137. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] mod_sofia.c:90 sofia/internal/2200@df7jal23ls0d.invalid SOFIA INIT
  138. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] sofia_glue.c:1264 sips:2200@10.1.0.41:58790;rtcweb-breaker=no;transport=wss Setting proxy route to sofia/internal/2200@df7jal23ls0d.invalid
  139. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] sofia_glue.c:1295 sofia/internal/2200@df7jal23ls0d.invalid sending invite version: 1.6.17 git 0fc0946 2017-04-17 14:21:08Z 64bit
  140. 76552e5e-be86-445e-82e7-baafc543c76d Local SDP:
  141. 76552e5e-be86-445e-82e7-baafc543c76d v=0
  142. 76552e5e-be86-445e-82e7-baafc543c76d o=FreeSWITCH 1497345553 1497345554 IN IP4 10.0.0.145
  143. 76552e5e-be86-445e-82e7-baafc543c76d s=FreeSWITCH
  144. 76552e5e-be86-445e-82e7-baafc543c76d c=IN IP4 10.0.0.145
  145. 76552e5e-be86-445e-82e7-baafc543c76d t=0 0
  146. 76552e5e-be86-445e-82e7-baafc543c76d a=msid-semantic: WMS LCZEb2kW6BGxMQ9H9n0JARxq4poeyZ9H
  147. 76552e5e-be86-445e-82e7-baafc543c76d m=audio 24176 RTP/SAVPF 8 101
  148. 76552e5e-be86-445e-82e7-baafc543c76d a=rtpmap:8 PCMA/8000
  149. 76552e5e-be86-445e-82e7-baafc543c76d a=rtpmap:101 telephone-event/8000
  150. 76552e5e-be86-445e-82e7-baafc543c76d a=fingerprint:sha-256 D9:2E:2E:8B:CA:AE:77:2D:DC:B1:81:66:A6:16:16:70:64:92:E3:5F:30:DA:90:68:22:88:D8:78:50:30:9C:9A
  151. 76552e5e-be86-445e-82e7-baafc543c76d a=setup:actpass
  152. 76552e5e-be86-445e-82e7-baafc543c76d a=rtcp-mux
  153. 76552e5e-be86-445e-82e7-baafc543c76d a=rtcp:24176 IN IP4 10.0.0.145
  154. 76552e5e-be86-445e-82e7-baafc543c76d a=ssrc:733119777 cname:ayoj9r3Cp2nsbNai
  155. 76552e5e-be86-445e-82e7-baafc543c76d a=ssrc:733119777 msid:LCZEb2kW6BGxMQ9H9n0JARxq4poeyZ9H a0
  156. 76552e5e-be86-445e-82e7-baafc543c76d a=ssrc:733119777 mslabel:LCZEb2kW6BGxMQ9H9n0JARxq4poeyZ9H
  157. 76552e5e-be86-445e-82e7-baafc543c76d a=ssrc:733119777 label:LCZEb2kW6BGxMQ9H9n0JARxq4poeyZ9Ha0
  158. 76552e5e-be86-445e-82e7-baafc543c76d a=ice-ufrag:PPMxsmnCfT15WBc4
  159. 76552e5e-be86-445e-82e7-baafc543c76d a=ice-pwd:SM4FAIgCr8ysHd7WSwAO9QWu
  160. 76552e5e-be86-445e-82e7-baafc543c76d a=candidate:0246028599 1 udp 659136 10.0.0.145 24176 typ host generation 0
  161. 76552e5e-be86-445e-82e7-baafc543c76d a=candidate:0246028599 2 udp 659136 10.0.0.145 24176 typ host generation 0
  162. 76552e5e-be86-445e-82e7-baafc543c76d a=ptime:20
  163. 76552e5e-be86-445e-82e7-baafc543c76d a=sendrecv
  164. 76552e5e-be86-445e-82e7-baafc543c76d
  165. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2200@df7jal23ls0d.invalid Standard INIT
  166. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2200@df7jal23ls0d.invalid) State Change CS_INIT -> CS_ROUTING
  167. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/2200@df7jal23ls0d.invalid) State INIT going to sleep
  168. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_ROUTING (Cur 2 Tot 36)
  169. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/2200@df7jal23ls0d.invalid) State ROUTING
  170. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] mod_sofia.c:143 sofia/internal/2200@df7jal23ls0d.invalid SOFIA ROUTING
  171. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/2200@df7jal23ls0d.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  172. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/2200@df7jal23ls0d.invalid) State ROUTING going to sleep
  173. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 36)
  174. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] sofia.c:7048 Channel sofia/internal/2200@df7jal23ls0d.invalid entering state [calling][0]
  175. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/2200@df7jal23ls0d.invalid) State CONSUME_MEDIA
  176. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:09.726640 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/2200@df7jal23ls0d.invalid) State CONSUME_MEDIA going to sleep
  177.  
  178.  
  179. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [NOTICE] switch_ivr_originate.c:3605 Hangup sofia/internal/2200@df7jal23ls0d.invalid [CS_CONSUME_MEDIA] [NO_ANSWER]
  180. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_HANGUP (Cur 2 Tot 36)
  181. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2200@df7jal23ls0d.invalid) Callstate Change DOWN -> HANGUP
  182. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/2200@df7jal23ls0d.invalid) State HANGUP
  183. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] mod_sofia.c:438 Channel sofia/internal/2200@df7jal23ls0d.invalid hanging up, cause: NO_ANSWER
  184. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.006713 [NOTICE] switch_ivr_originate.c:2845 Cannot create outgoing channel of type [user] cause: [NO_ANSWER]
  185. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.006713 [DEBUG] switch_ivr_originate.c:3833 Originate Resulted in Error Cause: 19 [NO_ANSWER]
  186. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/2200@df7jal23ls0d.invalid
  187. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2200@df7jal23ls0d.invalid Standard HANGUP, cause: NO_ANSWER
  188. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/2200@df7jal23ls0d.invalid) State HANGUP going to sleep
  189. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/2200@df7jal23ls0d.invalid) State Change CS_HANGUP -> CS_REPORTING
  190. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_REPORTING (Cur 2 Tot 36)
  191. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/2200@df7jal23ls0d.invalid) State REPORTING
  192. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2200@df7jal23ls0d.invalid Standard REPORTING, cause: NO_ANSWER
  193. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/2200@df7jal23ls0d.invalid) State REPORTING going to sleep
  194. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/2200@df7jal23ls0d.invalid) State Change CS_REPORTING -> CS_DESTROY
  195. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_session.c:1664 Session 36 (sofia/internal/2200@df7jal23ls0d.invalid) Locked, Waiting on external entities
  196. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [NOTICE] switch_core_session.c:1682 Session 36 (sofia/internal/2200@df7jal23ls0d.invalid) Ended
  197. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/2200@df7jal23ls0d.invalid [CS_DESTROY]
  198. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_DESTROY (Cur 1 Tot 36)
  199. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/2200@df7jal23ls0d.invalid) State DESTROY
  200. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] mod_sofia.c:343 sofia/internal/2200@df7jal23ls0d.invalid SOFIA DESTROY
  201. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:181 sofia/internal/2200@df7jal23ls0d.invalid Standard DESTROY
  202. 76552e5e-be86-445e-82e7-baafc543c76d 2017-06-13 17:02:39.006713 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/2200@df7jal23ls0d.invalid) State DESTROY going to sleep
  203. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.006713 [INFO] mod_dptools.c:3418 Originate Failed.  Cause: NO_ANSWER
  204. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com hangup()
  205. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [NOTICE] mod_dptools.c:1286 Hangup sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com [CS_EXECUTE] [NORMAL_CLEARING]
  206. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Callstate Change RINGING -> HANGUP
  207. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State HANGUP
  208. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] mod_sofia.c:438 Channel sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com hanging up, cause: NORMAL_CLEARING
  209. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480
  210. send 967 bytes to udp/[10.0.0.146]:5060 at 16:02:39.023638:
  211.    ------------------------------------------------------------------------
  212.    SIP/2.0 480 Temporarily Unavailable
  213.    Via: SIP/2.0/UDP 10.0.0.146:5060;branch=z9hG4bKa7d0f6d5-5051-11e7-854a-c8c2c2f34e3c;rport=5060
  214.    Max-Forwards: 70
  215.    From: london.devserver.sytelco.com <sip:london.devserver.sytelco.com@sipserver.sytel.no-ip.com>;tag=ACU-1370-d75b057b
  216.    To: 2200 <sip:2200@10.0.0.145>;tag=3cZyv237Sream
  217.    Call-ID: 0044B001-0000C3E5-59400C81-00000009@10.0.0.146
  218.    CSeq: 28024 INVITE
  219.    User-Agent: FreeSWITCH-mod_sofia/1.6.17+git~20170417T142108Z~0fc0946461~64bit
  220.    Accept: application/sdp
  221.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  222.    Supported: timer, path, replaces
  223.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  224.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  225.    Content-Length: 0
  226.    Remote-Party-ID: "2200" <sip:2200@10.0.0.145>;party=calling;privacy=off;screen=no
  227.    
  228.    ------------------------------------------------------------------------
  229. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:60 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Standard HANGUP, cause: NORMAL_CLEARING
  230. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State HANGUP going to sleep
  231. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_session.c:2814 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  232. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State EXECUTE going to sleep
  233. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_HANGUP (Cur 1 Tot 36)
  234. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State Change CS_HANGUP -> CS_REPORTING
  235. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_REPORTING (Cur 1 Tot 36)
  236. recv 406 bytes from udp/[10.0.0.146]:5060 at 16:02:39.025638:
  237.    ------------------------------------------------------------------------
  238.    ACK sip:2200@10.0.0.145 SIP/2.0
  239.    From: london.devserver.sytelco.com <sip:london.devserver.sytelco.com@sipserver.sytel.no-ip.com>;tag=ACU-1370-d75b057b
  240.    To: 2200 <sip:2200@10.0.0.145>;tag=3cZyv237Sream
  241.    Call-ID: 0044B001-0000C3E5-59400C81-00000009@10.0.0.146
  242.    CSeq: 28024 ACK
  243.    Content-Length: 0
  244.    Via: SIP/2.0/UDP 10.0.0.146:5060;branch=z9hG4bKa7d0f6d5-5051-11e7-854a-c8c2c2f34e3c;rport
  245.    Max-Forwards: 70
  246.    
  247.    ------------------------------------------------------------------------
  248. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State REPORTING
  249. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:174 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Standard REPORTING, cause: NORMAL_CLEARING
  250. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State REPORTING going to sleep
  251. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State Change CS_REPORTING -> CS_DESTROY
  252. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_session.c:1664 Session 35 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Locked, Waiting on external entities
  253. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [NOTICE] switch_core_session.c:1682 Session 35 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Ended
  254. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com [CS_DESTROY]
  255. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_DESTROY (Cur 0 Tot 36)
  256. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State DESTROY
  257. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.016637 [DEBUG] mod_sofia.c:343 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com SOFIA DESTROY
  258. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.026637 [DEBUG] switch_core_state_machine.c:181 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Standard DESTROY
  259. 1424da2f-2c00-4eef-a4c5-bfc6f7785de0 2017-06-13 17:02:39.026637 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State DESTROY going to sleep
  260.  
  261. **********************************************************
  262. FREESWITCH1 - calls are presented to the registered users
  263. **********************************************************
  264.  
  265. recv 1035 bytes from udp/[10.0.0.146]:5060 at 16:06:47.991677:
  266.    ------------------------------------------------------------------------
  267.    INVITE sip:2200@10.0.0.34 SIP/2.0
  268.    From: london.devserver.sytelco.com <sip:london.devserver.sytelco.com@sipserver.sytel.no-ip.com>;tag=ACU-18be-e7f2ccd2
  269.    To: 2200 <sip:2200@10.0.0.34>
  270.    Contact: <sip:10.0.0.146>
  271.    Call-ID: 00447001-0000C3F8-59400D97-00000001@10.0.0.146
  272.    CSeq: 26500 INVITE
  273.    Content-Length: 236
  274.    Content-Type: application/sdp
  275.    Allow: INVITE
  276.    Allow: ACK
  277.    Allow: BYE
  278.    Allow: CANCEL
  279.    Allow: OPTIONS
  280.    Allow: NOTIFY
  281.    Allow: REFER
  282.    Allow: PRACK
  283.   Allow: INFO
  284.    Allow: UPDATE
  285.    Allow: MESSAGE
  286.    Accept: application/sdp
  287.    Accept: application/isup
  288.    Accept: application/qsig
  289.    Accept: multipart/mixed
  290.    Accept-Encoding: identity
  291.    Accept-Language: en
  292.    Supported: replaces
  293.    Supported: 100rel
  294.    Via: SIP/2.0/UDP 10.0.0.146:5060;branch=z9hG4bK4dbee997-5052-11e7-97ac-9a138dd73e1d;rport
  295.    Max-Forwards: 70
  296.    
  297.    v=0
  298.    o=aculab-00447001 18467 18467 IN IP4 10.0.0.146
  299.    s=-
  300.    c=IN IP4 10.0.0.146
  301.    t=0 0
  302.    m=audio 9022 RTP/AVP 8 18 96
  303.    a=rtpmap:8 PCMA/8000
  304.    a=ptime:20
  305.    a=rtpmap:18 G729/8000
  306.    a=ptime:30
  307.    a=rtpmap:96 telephone-event/8000
  308.    a=fmtp:96 0-15
  309.    ------------------------------------------------------------------------
  310. send 443 bytes to udp/[10.0.0.146]:5060 at 16:06:47.992669:
  311.    ------------------------------------------------------------------------
  312.    SIP/2.0 100 Trying
  313.    Via: SIP/2.0/UDP 10.0.0.146:5060;branch=z9hG4bK4dbee997-5052-11e7-97ac-9a138dd73e1d;rport=5060
  314.    From: london.devserver.sytelco.com <sip:london.devserver.sytelco.com@sipserver.sytel.no-ip.com>;tag=ACU-18be-e7f2ccd2
  315.    To: 2200 <sip:2200@10.0.0.34>
  316.    Call-ID: 00447001-0000C3F8-59400D97-00000001@10.0.0.146
  317.    CSeq: 26500 INVITE
  318.    User-Agent: FreeSWITCH-mod_sofia/1.6.17+git~20170417T142108Z~0fc0946461~64bit
  319.    Content-Length: 0
  320.    
  321.    ------------------------------------------------------------------------
  322. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:47.992669 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com [8d3a228a-1026-458b-9305-252733d0b5ad]
  323. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:47.992669 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_NEW (Cur 1 Tot 33)
  324. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:47.992669 [DEBUG] sofia.c:9834 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com receiving invite from 10.0.0.146:5060 version: 1.6.17 git 0fc0946 2017-04-17 14:21:08Z 64bit
  325. 2017-06-13 17:06:47.992669 [DEBUG] sofia.c:9950 IP 10.0.0.146 Approved by acl "domains[]". Access Granted.
  326. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:47.992669 [DEBUG] sofia.c:7048 Channel sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com entering state [received][100]
  327. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:47.992669 [DEBUG] sofia.c:7058 Remote SDP:
  328. 8d3a228a-1026-458b-9305-252733d0b5ad v=0
  329. 8d3a228a-1026-458b-9305-252733d0b5ad o=aculab-00447001 18467 18467 IN IP4 10.0.0.146
  330. 8d3a228a-1026-458b-9305-252733d0b5ad s=-
  331. 8d3a228a-1026-458b-9305-252733d0b5ad c=IN IP4 10.0.0.146
  332. 8d3a228a-1026-458b-9305-252733d0b5ad t=0 0
  333. 8d3a228a-1026-458b-9305-252733d0b5ad m=audio 9022 RTP/AVP 8 18 96
  334. 8d3a228a-1026-458b-9305-252733d0b5ad a=rtpmap:8 PCMA/8000
  335. 8d3a228a-1026-458b-9305-252733d0b5ad a=rtpmap:18 G729/8000
  336. 8d3a228a-1026-458b-9305-252733d0b5ad a=rtpmap:96 telephone-event/8000
  337. 8d3a228a-1026-458b-9305-252733d0b5ad a=fmtp:96 0-15
  338. 8d3a228a-1026-458b-9305-252733d0b5ad a=ptime:20
  339. 8d3a228a-1026-458b-9305-252733d0b5ad a=ptime:30
  340. 8d3a228a-1026-458b-9305-252733d0b5ad
  341. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:47.992669 [DEBUG] sofia.c:7450 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State Change CS_NEW -> CS_INIT
  342. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:47.992669 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State NEW
  343. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:47.992669 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_INIT (Cur 1 Tot 33)
  344. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State INIT
  345. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] mod_sofia.c:90 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com SOFIA INIT
  346. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:40 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Standard INIT
  347. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State Change CS_INIT -> CS_ROUTING
  348. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State INIT going to sleep
  349. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_ROUTING (Cur 1 Tot 33)
  350. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_channel.c:2249 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Callstate Change DOWN -> RINGING
  351. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State ROUTING
  352. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] mod_sofia.c:143 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com SOFIA ROUTING
  353. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:236 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Standard ROUTING
  354. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [INFO] mod_dialplan_xml.c:637 Processing london.devserver.sytelco.com <london.devserver.sytelco.com>->2200 in context public
  355. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com parsing [public->unloop] continue=false
  356. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  357. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  358. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com parsing [public->outside_call] continue=true
  359. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Absolute Condition [outside_call]
  360. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action set(outside_call=true)
  361. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  362. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com parsing [public->call_debug] continue=true
  363. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  364. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com parsing [public->extensions_nailup] continue=false
  365. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (PASS) [extensions_nailup] network_addr(10.0.0.146) =~ /10.0.0.146/ break=on-false
  366. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Regex (PASS) [extensions_nailup] destination_number(2200) =~ /^(\d{4})$/ break=on-false
  367. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action export(dialed_extension=2200)
  368. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action set(call_timeout=30)
  369. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action bridge(user/${dialed_extension}@${caller_id_name})
  370. 8d3a228a-1026-458b-9305-252733d0b5ad Dialplan: sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Action hangup()
  371. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State Change CS_ROUTING -> CS_EXECUTE
  372. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State ROUTING going to sleep
  373. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Running State Change CS_EXECUTE (Cur 1 Tot 33)
  374. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) State EXECUTE
  375. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] mod_sofia.c:198 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com SOFIA EXECUTE
  376. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_core_state_machine.c:328 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Standard EXECUTE
  377. 8d3a228a-1026-458b-9305-252733d0b5ad EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com set(outside_call=true)
  378. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] mod_dptools.c:1530 SET sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com [outside_call]=[true]
  379. 8d3a228a-1026-458b-9305-252733d0b5ad EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com export(RFC2822_DATE=Tue, 13 Jun 2017 17:06:48 +0100)
  380. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Tue, 13 Jun 2017 17:06:48 +0100]
  381. 8d3a228a-1026-458b-9305-252733d0b5ad EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com export(dialed_extension=2200)
  382. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[2200]
  383. 8d3a228a-1026-458b-9305-252733d0b5ad EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com set(call_timeout=30)
  384. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] mod_dptools.c:1530 SET sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com [call_timeout]=[30]
  385. 8d3a228a-1026-458b-9305-252733d0b5ad EXECUTE sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com bridge(user/2200@london.devserver.sytelco.com)
  386. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_channel.c:1250 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com EXPORTING[export_vars] [RFC2822_DATE]=[Tue, 13 Jun 2017 17:06:48 +0100] to event
  387. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_channel.c:1250 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com EXPORTING[export_vars] [dialed_extension]=[2200] to event
  388. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.002668 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  389. 2017-06-13 17:06:48.052666 [CONSOLE] mod_xml_curl.c:323 XML response is in C:/Windows/TEMP/2905685b-9571-429b-ae87-d661dcb0a0bc.tmp.xml
  390. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.062670 [DEBUG] switch_channel.c:1250 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com EXPORTING[export_vars] [RFC2822_DATE]=[Tue, 13 Jun 2017 17:06:48 +0100] to event
  391. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.062670 [DEBUG] switch_channel.c:1250 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com EXPORTING[export_vars] [dialed_extension]=[2200] to event
  392. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.062670 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  393. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/2200@df7jal23ls0d.invalid [1ed73279-3e4e-414d-9143-d6c6f1bb1352]
  394. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] mod_sofia.c:4818 (sofia/internal/2200@df7jal23ls0d.invalid) State Change CS_NEW -> CS_INIT
  395. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_INIT (Cur 2 Tot 34)
  396. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/2200@df7jal23ls0d.invalid) State INIT
  397. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] mod_sofia.c:90 sofia/internal/2200@df7jal23ls0d.invalid SOFIA INIT
  398. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] sofia_glue.c:1264 sips:2200@10.1.0.41:58790;rtcweb-breaker=no;transport=wss Setting proxy route to sofia/internal/2200@df7jal23ls0d.invalid
  399. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] sofia_glue.c:1295 sofia/internal/2200@df7jal23ls0d.invalid sending invite version: 1.6.17 git 0fc0946 2017-04-17 14:21:08Z 64bit
  400. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 Local SDP:
  401. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 v=0
  402. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 o=FreeSWITCH 1497347900 1497347901 IN IP4 10.0.0.34
  403. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 s=FreeSWITCH
  404. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 c=IN IP4 10.0.0.34
  405. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 t=0 0
  406. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=msid-semantic: WMS DRYvfQD51qFR8xKidCQOKIZqx6MB1uuV
  407. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 m=audio 22108 RTP/SAVPF 8 101
  408. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=rtpmap:8 PCMA/8000
  409. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=rtpmap:101 telephone-event/8000
  410. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=fingerprint:sha-256 9F:F9:B2:1A:10:6A:EE:3C:33:45:D0:58:44:03:05:7A:20:9D:0B:63:74:B8:3C:DC:B3:AA:8B:82:35:26:F2:3C
  411. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=setup:actpass
  412. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=rtcp-mux
  413. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=rtcp:22108 IN IP4 10.0.0.34
  414. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ssrc:1892802664 cname:KJpeKVpU3LpVj2qu
  415. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ssrc:1892802664 msid:DRYvfQD51qFR8xKidCQOKIZqx6MB1uuV a0
  416. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ssrc:1892802664 mslabel:DRYvfQD51qFR8xKidCQOKIZqx6MB1uuV
  417. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ssrc:1892802664 label:DRYvfQD51qFR8xKidCQOKIZqx6MB1uuVa0
  418. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ice-ufrag:yk9A2CQurT3y8oo9
  419. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ice-pwd:OSGllaBX1wrD5dLHKHQhqJyx
  420. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=candidate:6918049260 1 udp 659136 10.0.0.34 22108 typ host generation 0
  421. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=candidate:6918049260 2 udp 659136 10.0.0.34 22108 typ host generation 0
  422. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ptime:20
  423. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=sendrecv
  424. 1ed73279-3e4e-414d-9143-d6c6f1bb1352
  425. send 2065 bytes to wss/[10.1.0.41]:58790 at 16:06:48.067667:
  426.    ------------------------------------------------------------------------
  427.    INVITE sip:2200@df7jal23ls0d.invalid;rtcweb-breaker=no;transport=wss SIP/2.0
  428.    Via: SIP/2.0/WSS 10.0.0.34:7443;branch=z9hG4bK5mmS7Ka2j1Dvp
  429.    Route: <sips:2200@10.1.0.41:58790>;rtcweb-breaker=no;transport=wss
  430.    Max-Forwards: 69
  431.    From: "london.devserver.sytelco.com" <sip:london.devserver.sytelco.com@london.devserver.sytelco.com>;tag=Q767mQpBKvU0j
  432.    To: <sip:2200@df7jal23ls0d.invalid;rtcweb-breaker=no;transport=wss>
  433.    Call-ID: 2527af44-caf5-1235-1984-9d7e09c72572
  434.    CSeq: 108348940 INVITE
  435.    Contact: <sip:mod_sofia@10.0.0.34:5060>
  436.    User-Agent: FreeSWITCH-mod_sofia/1.6.17+git~20170417T142108Z~0fc0946461~64bit
  437.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  438.    Supported: timer, path, replaces
  439.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  440.    Content-Type: application/sdp
  441.    Content-Disposition: session
  442.    Content-Length: 871
  443.    X-FS-Support: update_display,send_info
  444.    Remote-Party-ID: "london.devserver.sytelco.com" <sip:london.devserver.sytelco.com@london.devserver.sytelco.com>;party=calling;screen=yes;privacy=off
  445.    
  446.    v=0
  447.    o=FreeSWITCH 1497347900 1497347901 IN IP4 10.0.0.34
  448.    s=FreeSWITCH
  449.    c=IN IP4 10.0.0.34
  450.    t=0 0
  451.    a=msid-semantic: WMS DRYvfQD51qFR8xKidCQOKIZqx6MB1uuV
  452.    m=audio 22108 RTP/SAVPF 8 101
  453.    a=rtpmap:8 PCMA/8000
  454.    a=rtpmap:101 telephone-event/8000
  455.    a=fingerprint:sha-256 9F:F9:B2:1A:10:6A:EE:3C:33:45:D0:58:44:03:05:7A:20:9D:0B:63:74:B8:3C:DC:B3:AA:8B:82:35:26:F2:3C
  456.    a=setup:actpass
  457.    a=rtcp-mux
  458.    a=rtcp:22108 IN IP4 10.0.0.34
  459.    a=ssrc:1892802664 cname:KJpeKVpU3LpVj2qu
  460.    a=ssrc:1892802664 msid:DRYvfQD51qFR8xKidCQOKIZqx6MB1uuV a0
  461.    a=ssrc:1892802664 mslabel:DRYvfQD51qFR8xKidCQOKIZqx6MB1uuV
  462.    a=ssrc:1892802664 label:DRYvfQD51qFR8xKidCQOKIZqx6MB1uuVa0
  463.    a=ice-ufrag:yk9A2CQurT3y8oo9
  464.    a=ice-pwd:OSGllaBX1wrD5dLHKHQhqJyx
  465.    a=candidate:6918049260 1 udp 659136 10.0.0.34 22108 typ host generation 0
  466.    a=candidate:6918049260 2 udp 659136 10.0.0.34 22108 typ host generation 0
  467.    a=ptime:20
  468.    ------------------------------------------------------------------------
  469. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2200@df7jal23ls0d.invalid Standard INIT
  470. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2200@df7jal23ls0d.invalid) State Change CS_INIT -> CS_ROUTING
  471. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/2200@df7jal23ls0d.invalid) State INIT going to sleep
  472. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_ROUTING (Cur 2 Tot 34)
  473. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] sofia.c:7048 Channel sofia/internal/2200@df7jal23ls0d.invalid entering state [calling][0]
  474. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/2200@df7jal23ls0d.invalid) State ROUTING
  475. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] mod_sofia.c:143 sofia/internal/2200@df7jal23ls0d.invalid SOFIA ROUTING
  476. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/2200@df7jal23ls0d.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  477. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/2200@df7jal23ls0d.invalid) State ROUTING going to sleep
  478. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 34)
  479. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/2200@df7jal23ls0d.invalid) State CONSUME_MEDIA
  480. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.062670 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/2200@df7jal23ls0d.invalid) State CONSUME_MEDIA going to sleep
  481. recv 395 bytes from wss/[10.1.0.41]:58790 at 16:06:48.081667:
  482.    ------------------------------------------------------------------------
  483.    SIP/2.0 100 Trying (sent from the Transaction Layer)
  484.    Via: SIP/2.0/WSS 10.0.0.34:7443;branch=z9hG4bK5mmS7Ka2j1Dvp
  485.    From: "london.devserver.sytelco.com"<sip:london.devserver.sytelco.com@london.devserver.sytelco.com>;tag=Q767mQpBKvU0j
  486.    To: <sip:2200@df7jal23ls0d.invalid;rtcweb-breaker=no;transport=wss>
  487.    Call-ID: 2527af44-caf5-1235-1984-9d7e09c72572
  488.    CSeq: 108348940 INVITE
  489.    Content-Length: 0
  490.    
  491.    ------------------------------------------------------------------------
  492. recv 525 bytes from wss/[10.1.0.41]:58790 at 16:06:48.114669:
  493.    ------------------------------------------------------------------------
  494.    SIP/2.0 180 Ringing
  495.    Via: SIP/2.0/WSS 10.0.0.34:7443;branch=z9hG4bK5mmS7Ka2j1Dvp
  496.    From: "london.devserver.sytelco.com"<sip:london.devserver.sytelco.com@london.devserver.sytelco.com>;tag=Q767mQpBKvU0j
  497.    To: <sip:2200@df7jal23ls0d.invalid;rtcweb-breaker=no;transport=wss>;tag=Wr8bdHMXPuA9FKAkhxJr
  498.    Contact: <sips:2200@df7jal23ls0d.invalid;transport=wss>
  499.    Call-ID: 2527af44-caf5-1235-1984-9d7e09c72572
  500.    CSeq: 108348940 INVITE
  501.    Content-Length: 0
  502.    Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE
  503.    
  504.    ------------------------------------------------------------------------
  505. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.112668 [DEBUG] sofia.c:7048 Channel sofia/internal/2200@df7jal23ls0d.invalid entering state [proceeding][180]
  506. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.112668 [NOTICE] sofia.c:7156 Ring-Ready sofia/internal/2200@df7jal23ls0d.invalid!
  507. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:48.112668 [DEBUG] switch_channel.c:3345 (sofia/internal/2200@df7jal23ls0d.invalid) Callstate Change DOWN -> RINGING
  508. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.132669 [NOTICE] mod_sofia.c:2272 Ring-Ready sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com!
  509. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.132669 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com!
  510. send 943 bytes to udp/[10.0.0.146]:5060 at 16:06:48.145669:
  511.    ------------------------------------------------------------------------
  512.    SIP/2.0 180 Ringing
  513.    Via: SIP/2.0/UDP 10.0.0.146:5060;branch=z9hG4bK4dbee997-5052-11e7-97ac-9a138dd73e1d;rport=5060
  514.    From: london.devserver.sytelco.com <sip:london.devserver.sytelco.com@sipserver.sytel.no-ip.com>;tag=ACU-18be-e7f2ccd2
  515.    To: 2200 <sip:2200@10.0.0.34>;tag=pyDFKv57NK5DQ
  516.    Call-ID: 00447001-0000C3F8-59400D97-00000001@10.0.0.146
  517.    CSeq: 26500 INVITE
  518.    Contact: <sip:2200@10.0.0.34:5060;transport=udp>
  519.    User-Agent: FreeSWITCH-mod_sofia/1.6.17+git~20170417T142108Z~0fc0946461~64bit
  520.    Accept: application/sdp
  521.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  522.    Supported: timer, path, replaces
  523.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  524.    Content-Length: 0
  525.    Remote-Party-ID: "Outbound Call" <sip:2200@10.0.0.34>;party=calling;privacy=off;screen=no
  526.    
  527.    ------------------------------------------------------------------------
  528. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:48.152668 [DEBUG] sofia.c:7048 Channel sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com entering state [early][180]
  529. recv 1846 bytes from wss/[10.1.0.41]:58790 at 16:06:53.837716:
  530.    ------------------------------------------------------------------------
  531.    SIP/2.0 200 OK
  532.    Via: SIP/2.0/WSS 10.0.0.34:7443;branch=z9hG4bK5mmS7Ka2j1Dvp
  533.    From: "london.devserver.sytelco.com"<sip:london.devserver.sytelco.com@london.devserver.sytelco.com>;tag=Q767mQpBKvU0j
  534.    To: <sip:2200@df7jal23ls0d.invalid;rtcweb-breaker=no;transport=wss>;tag=Wr8bdHMXPuA9FKAkhxJr
  535.    Contact: <sips:2200@df7jal23ls0d.invalid;transport=wss>
  536.    Call-ID: 2527af44-caf5-1235-1984-9d7e09c72572
  537.    CSeq: 108348940 INVITE
  538.    Content-Type: application/sdp
  539.    Content-Length: 1292
  540.    Allow: ACK, BYE, CANCEL, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE
  541.    
  542.    v=0
  543.    o=- 5251170910027638000 2 IN IP4 127.0.0.1
  544.    s=Doubango Telecom - chrome
  545.    t=0 0
  546.    a=msid-semantic: WMS 6IxRuwNx2omtA3zF3Zx8QkLBXwsrXKJxSg0Z
  547.    m=audio 56341 UDP/TLS/RTP/SAVPF 8 101
  548.    c=IN IP4 81.139.175.134
  549.    a=rtcp:9 IN IP4 0.0.0.0
  550.    a=candidate:3891828076 1 udp 2122260223 10.1.0.41 56341 typ host generation 0 network-id 1 network-cost 10
  551.    a=candidate:2775935972 1 udp 1686052607 81.139.175.134 56341 typ srflx raddr 10.1.0.41 rport 56341 generation 0 network-id 1 network-cost 10
  552.    a=candidate:2775935972 1 udp 1686052607 80.229.34.139 56341 typ srflx raddr 10.1.0.41 rport 56341 generation 0 network-id 1 network-cost 10
  553.    a=candidate:2843437468 1 tcp 1518280447 10.1.0.41 9 typ host tcptype active generation 0 network-id 1 network-cost 10
  554.    a=ice-ufrag:w3tC
  555.    a=ice-pwd:RGBx+5m4Kwu79vAA2r4Y6/q+
  556.    a=fingerprint:sha-256 49:2B:B4:95:65:7E:33:8C:07:54:09:E0:65:10:53:73:04:C0:4E:17:DE:21:85:1C:D0:9A:35:0E:98:5C:68:F4
  557.    a=setup:active
  558.    a=mid:audio
  559.    a=sendrecv
  560.    a=rtcp-mux
  561.    a=rtpmap:8 PCMA/8000
  562.    a=rtpmap:101 telephone-event/8000
  563.    a=ssrc:1017001704 cname:MMIn28bnaOKRkdSt
  564.    a=ssrc:1017001704 msid:6IxRuwNx2omtA3zF3Zx8QkLBXwsrXKJxSg0Z a4e0e59c-af2d-44c9-93d5-1380d410a1b0
  565.    a=ssrc:1017001704 mslabel:6IxRuwNx2omtA3zF3Zx8QkLBXwsrXKJxSg0Z
  566.    a=ssrc:1017001704 label:a4e0e59c-af2d-44c9-93d5-1380d410a1b0
  567.    ------------------------------------------------------------------------
  568. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] sofia.c:7048 Channel sofia/internal/2200@df7jal23ls0d.invalid entering state [completing][200]
  569. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] sofia.c:7058 Remote SDP:
  570. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 v=0
  571. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 o=- 5251170910027638000 2 IN IP4 127.0.0.1
  572. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 s=Doubango Telecom - chrome
  573. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 t=0 0
  574. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=msid-semantic: WMS 6IxRuwNx2omtA3zF3Zx8QkLBXwsrXKJxSg0Z
  575. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 m=audio 56341 UDP/TLS/RTP/SAVPF 8 101
  576. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 c=IN IP4 81.139.175.134
  577. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=rtpmap:8 PCMA/8000
  578. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=rtpmap:101 telephone-event/8000
  579. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=rtcp:9 IN IP4 0.0.0.0
  580. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=candidate:3891828076 1 udp 2122260223 10.1.0.41 56341 typ host generation 0 network-id 1 network-cost 10
  581. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=candidate:2775935972 1 udp 1686052607 81.139.175.134 56341 typ srflx raddr 10.1.0.41 rport 56341 generation 0 network-id 1 network-cost 10
  582. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=candidate:2775935972 1 udp 1686052607 80.229.34.139 56341 typ srflx raddr 10.1.0.41 rport 56341 generation 0 network-id 1 network-cost 10
  583. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=candidate:2843437468 1 tcp 1518280447 10.1.0.41 9 typ host tcptype active generation 0 network-id 1 network-cost 10
  584. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ice-ufrag:w3tC
  585. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ice-pwd:RGBx+5m4Kwu79vAA2r4Y6/q+
  586. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=fingerprint:sha-256 49:2B:B4:95:65:7E:33:8C:07:54:09:E0:65:10:53:73:04:C0:4E:17:DE:21:85:1C:D0:9A:35:0E:98:5C:68:F4
  587. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=setup:active
  588. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=mid:audio
  589. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=rtcp-mux
  590. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ssrc:1017001704 cname:MMIn28bnaOKRkdSt
  591. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ssrc:1017001704 msid:6IxRuwNx2omtA3zF3Zx8QkLBXwsrXKJxSg0Z a4e0e59c-af2d-44c9-93d5-1380d410a1b0
  592. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ssrc:1017001704 mslabel:6IxRuwNx2omtA3zF3Zx8QkLBXwsrXKJxSg0Z
  593. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 a=ssrc:1017001704 label:a4e0e59c-af2d-44c9-93d5-1380d410a1b0
  594. 1ed73279-3e4e-414d-9143-d6c6f1bb1352
  595. send 481 bytes to wss/[10.1.0.41]:58790 at 16:06:53.843728:
  596.    ------------------------------------------------------------------------
  597.    ACK sips:2200@df7jal23ls0d.invalid;transport=wss SIP/2.0
  598.    Via: SIP/2.0/WSS 10.0.0.34:7443;branch=z9hG4bK6XDj9eU5Fa4ej
  599.    Max-Forwards: 70
  600.    From: "london.devserver.sytelco.com" <sip:london.devserver.sytelco.com@london.devserver.sytelco.com>;tag=Q767mQpBKvU0j
  601.    To: <sip:2200@df7jal23ls0d.invalid;rtcweb-breaker=no;transport=wss>;tag=Wr8bdHMXPuA9FKAkhxJr
  602.    Call-ID: 2527af44-caf5-1235-1984-9d7e09c72572
  603.    CSeq: 108348940 ACK
  604.    Contact: <sip:mod_sofia@10.0.0.34:5060>
  605.    Content-Length: 0
  606.    
  607.    ------------------------------------------------------------------------
  608. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] sofia.c:7048 Channel sofia/internal/2200@df7jal23ls0d.invalid entering state [ready][200]
  609. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  610. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  611. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 101@8000
  612. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/2200@df7jal23ls0d.invalid PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  613. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_codec.c:111 sofia/internal/2200@df7jal23ls0d.invalid Original read codec set to PCMA:8
  614. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [WARNING] switch_core_media.c:3434 NO candidate ACL defined, Defaulting to wan.auto
  615. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3464 Save audio Candidate cid: 1 proto: udp type: host addr: 10.1.0.41:56341
  616. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3464 Save audio Candidate cid: 1 proto: udp type: srflx addr: 81.139.175.134:56341
  617. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3464 Save audio Candidate cid: 1 proto: udp type: srflx addr: 80.229.34.139:56341
  618. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3506 Searching for rtp candidate.
  619. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3511 Choose rtp candidate, index 1, 81.139.175.134:56341
  620. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3285 sofia/internal/2200@df7jal23ls0d.invalid choosing family v4
  621. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3522 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 81.139.175.134:56341
  622. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3569 setting remote audio ice addr to index 1 81.139.175.134:56341 based on candidate
  623. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:3601 Setting remote rtcp audio addr to 81.139.175.134:56341 based on candidate
  624. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 101@8000
  625. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:4754 sofia/internal/2200@df7jal23ls0d.invalid Set 2833 dtmf send payload to 101 recv payload to 101
  626. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:6865 AUDIO RTP [sofia/internal/2200@df7jal23ls0d.invalid] 10.0.0.34 port 22108 -> 81.139.175.134 port 56341 codec: 8 ms: 20
  627. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms
  628. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [INFO] switch_core_media.c:7042 Activating Audio ICE
  629. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [NOTICE] switch_rtp.c:4591 Activating RTP audio ICE: w3tC:yk9A2CQurT3y8oo9 81.139.175.134:56341
  630. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:7085 Activating RTCP PORT 56341
  631. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_rtp.c:4488 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 56341
  632. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [INFO] switch_core_media.c:7093 Skipping RTCP ICE (Same as RTP)
  633. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [INFO] switch_rtp.c:3574 Activate RTP/RTCP audio DTLS server
  634. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [INFO] switch_rtp.c:3723 Changing audio DTLS state from OFF to HANDSHAKE
  635. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:7166 sofia/internal/2200@df7jal23ls0d.invalid Set 2833 dtmf send payload to 101
  636. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:7173 sofia/internal/2200@df7jal23ls0d.invalid Set 2833 dtmf receive payload to 101
  637. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_core_media.c:7196 sofia/internal/2200@df7jal23ls0d.invalid Set rtp dtmf delay to 40
  638. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [NOTICE] sofia.c:8182 Channel [sofia/internal/2200@df7jal23ls0d.invalid] has been answered
  639. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.842725 [DEBUG] switch_channel.c:3772 (sofia/internal/2200@df7jal23ls0d.invalid) Callstate Change RINGING -> ACTIVE
  640. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[opus:116:48000:20:0:1]
  641. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[PCMU:0:8000:20:64000:1]
  642. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
  643. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4472 Audio Codec Compare [PCMA:8:8000:20:64000:1] is saved as a near-match
  644. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:30:8000:1]/[opus:116:48000:20:0:1]
  645. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:30:8000:1]/[PCMU:0:8000:20:64000:1]
  646. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:30:8000:1]/[PCMA:8:8000:20:64000:1]
  647. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 96@8000
  648. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4543 Substituting codec PCMA@30i@8000h@1c
  649. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com PCMA/8000 30 ms 240 samples 64000 bits 1 channels
  650. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_codec.c:111 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Original read codec set to PCMA:8
  651. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 96@8000
  652. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:4754 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Set 2833 dtmf send payload to 96 recv payload to 96
  653. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:6865 AUDIO RTP [sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com] 10.0.0.34 port 18212 -> 10.0.0.146 port 9022 codec: 8 ms: 30
  654. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 240 bytes per 30ms
  655. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:7166 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Set 2833 dtmf send payload to 96
  656. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:7173 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Set 2833 dtmf receive payload to 96
  657. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:7196 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Set rtp dtmf delay to 40
  658. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com!
  659. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_channel.c:3473 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Callstate Change RINGING -> EARLY
  660. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_core_media.c:6848 Audio params are unchanged for sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com.
  661. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com:
  662. 8d3a228a-1026-458b-9305-252733d0b5ad v=0
  663. 8d3a228a-1026-458b-9305-252733d0b5ad o=FreeSWITCH 1497351801 1497351802 IN IP4 10.0.0.34
  664. 8d3a228a-1026-458b-9305-252733d0b5ad s=FreeSWITCH
  665. 8d3a228a-1026-458b-9305-252733d0b5ad c=IN IP4 10.0.0.34
  666. 8d3a228a-1026-458b-9305-252733d0b5ad t=0 0
  667. 8d3a228a-1026-458b-9305-252733d0b5ad m=audio 18212 RTP/AVP 8 96
  668. 8d3a228a-1026-458b-9305-252733d0b5ad a=rtpmap:8 PCMA/8000
  669. 8d3a228a-1026-458b-9305-252733d0b5ad a=rtpmap:96 telephone-event/8000
  670. 8d3a228a-1026-458b-9305-252733d0b5ad a=fmtp:96 0-16
  671. 8d3a228a-1026-458b-9305-252733d0b5ad a=ptime:30
  672. 8d3a228a-1026-458b-9305-252733d0b5ad a=sendrecv
  673. 8d3a228a-1026-458b-9305-252733d0b5ad
  674. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [NOTICE] switch_ivr_originate.c:3632 Channel [sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com] has been answered
  675. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_channel.c:3772 (sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com) Callstate Change EARLY -> ACTIVE
  676. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] switch_ivr_originate.c:3690 Originate Resulted in Success: [sofia/internal/2200@df7jal23ls0d.invalid]
  677. send 1187 bytes to udp/[10.0.0.146]:5060 at 16:06:53.878667:
  678.    ------------------------------------------------------------------------
  679.    SIP/2.0 200 OK
  680.    Via: SIP/2.0/UDP 10.0.0.146:5060;branch=z9hG4bK4dbee997-5052-11e7-97ac-9a138dd73e1d;rport=5060
  681.    From: london.devserver.sytelco.com <sip:london.devserver.sytelco.com@sipserver.sytel.no-ip.com>;tag=ACU-18be-e7f2ccd2
  682.    To: 2200 <sip:2200@10.0.0.34>;tag=pyDFKv57NK5DQ
  683.    Call-ID: 00447001-0000C3F8-59400D97-00000001@10.0.0.146
  684.    CSeq: 26500 INVITE
  685.    Contact: <sip:2200@10.0.0.34:5060;transport=udp>
  686.    User-Agent: FreeSWITCH-mod_sofia/1.6.17+git~20170417T142108Z~0fc0946461~64bit
  687.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  688.    Supported: timer, path, replaces
  689.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  690.    Content-Type: application/sdp
  691.    Content-Disposition: session
  692.    Content-Length: 211
  693.    Remote-Party-ID: "Outbound Call" <sip:2200@10.0.0.34>;party=calling;privacy=off;screen=no
  694.    
  695.    v=0
  696.    o=FreeSWITCH 1497351801 1497351802 IN IP4 10.0.0.34
  697.    s=FreeSWITCH
  698.    c=IN IP4 10.0.0.34
  699.    t=0 0
  700.    m=audio 18212 RTP/AVP 8 96
  701.    a=rtpmap:8 PCMA/8000
  702.    a=rtpmap:96 telephone-event/8000
  703.    a=fmtp:96 0-16
  704.    a=ptime:30
  705.    ------------------------------------------------------------------------
  706. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] sofia.c:7048 Channel sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com entering state [completed][200]
  707. recv 423 bytes from udp/[10.0.0.146]:5060 at 16:06:53.880667:
  708.    ------------------------------------------------------------------------
  709.    ACK sip:2200@10.0.0.34:5060;transport=udp SIP/2.0
  710.    From: london.devserver.sytelco.com <sip:london.devserver.sytelco.com@sipserver.sytel.no-ip.com>;tag=ACU-18be-e7f2ccd2
  711.    To: 2200 <sip:2200@10.0.0.34>;tag=pyDFKv57NK5DQ
  712.    Max-Forwards: 70
  713.    Call-ID: 00447001-0000C3F8-59400D97-00000001@10.0.0.146
  714.    CSeq: 26500 ACK
  715.    Content-Length: 0
  716.    Via: SIP/2.0/UDP 10.0.0.146:5060;branch=z9hG4bK51417e52-5052-11e7-97ac-9a138dd73e1d;rport
  717.    
  718.    ------------------------------------------------------------------------
  719. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.872667 [DEBUG] sofia.c:7048 Channel sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com entering state [ready][200]
  720. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.882667 [DEBUG] switch_ivr_originate.c:3690 Originate Resulted in Success: [sofia/internal/2200@df7jal23ls0d.invalid]
  721. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.882667 [DEBUG] switch_ivr_bridge.c:1601 (sofia/internal/2200@df7jal23ls0d.invalid) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  722. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.882667 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/2200@df7jal23ls0d.invalid) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 34)
  723. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.882667 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/2200@df7jal23ls0d.invalid) State EXCHANGE_MEDIA
  724. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.882667 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  725. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:53.973669 [DEBUG] switch_rtp.c:7229 Correct audio ip/port confirmed.
  726. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:53.973669 [DEBUG] switch_core_io.c:1448 Engaging Write Buffer at 320 bytes to accommodate 320->320
  727. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:54.062667 [NOTICE] switch_rtp.c:1278 Auto Changing audio stun/rtp/dtls port from 81.139.175.134:56341 to 10.1.0.41:56341
  728. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:54.192669 [WARNING] switch_core_media.c:2475 [CBR]: Asynchronous PTIME not supported, changing our end from 30 to 20
  729. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:54.202668 [INFO] switch_rtp.c:3185 Changing audio DTLS state from HANDSHAKE to SETUP
  730. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:54.212669 [DEBUG] switch_core_media.c:2954 Changing Codec from PCMA@30ms@8000hz to PCMA@20ms@8000hz
  731. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:54.222667 [INFO] switch_rtp.c:3094 audio Fingerprint Verified.
  732. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:54.222667 [INFO] switch_rtp.c:3908 Activating audio Secure RTP SEND
  733. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:54.222667 [INFO] switch_rtp.c:3886 Activating audio Secure RTP RECV
  734. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:54.222667 [INFO] switch_rtp.c:3134 Changing audio DTLS state from SETUP to READY
  735. 2017-06-13 17:06:54.222667 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
  736. 2017-06-13 17:06:54.222667 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
  737. 1ed73279-3e4e-414d-9143-d6c6f1bb1352 2017-06-13 17:06:54.274668 [DEBUG] switch_rtp.c:1885 rtcp_stats_init: audio ssrc[1017001704] base_seq[765]
  738. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:54.274668 [DEBUG] switch_rtp.c:3974 RE-Starting timer [soft] 160 bytes per 20ms
  739. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:54.274668 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  740. 8d3a228a-1026-458b-9305-252733d0b5ad 2017-06-13 17:06:54.274668 [DEBUG] switch_core_codec.c:123 sofia/internal/london.devserver.sytelco.com@sipserver.sytel.no-ip.com Original read codec replaced with PCMA:8
  741.  
  742.  

Reply to "Registered users not being invited - multiple FS"