FreeSWITCH Pastebin

RFC1918 setup

From Jamie, 6 Years ago, written in Plain Text.
- view diff
URL https://pastebin.freeswitch.org/view/86874769
Embed
Download Paste or View Raw
  1. freeswitch@bstnma-freeswitch6> reloadxml
  2. +OK [Success]
  3.  
  4. 2018-03-22 10:50:00.038858 [INFO] switch_xml.c:1313 No files to include at /etc/freeswitch/dialplan/default/*.xml
  5. 2018-03-22 10:50:00.038858 [INFO] switch_xml.c:1313 No files to include at /etc/freeswitch/dialplan/public/*.xml
  6. 2018-03-22 10:50:00.119258 [INFO] switch_time.c:1423 Timezone reloaded 1750 definitions
  7. freeswitch@bstnma-freeswitch6> reloadacl
  8. +OK acl reloaded
  9.  
  10. 2018-03-22 10:50:03.778858 [INFO] switch_xml.c:1313 No files to include at /etc/freeswitch/dialplan/default/*.xml
  11. 2018-03-22 10:50:03.778858 [INFO] switch_xml.c:1313 No files to include at /etc/freeswitch/dialplan/public/*.xml
  12. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1472 Created ip list rfc6598.auto default (deny)
  13. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 100.64.0.0/10 (allow) [] to list rfc6598.auto
  14. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1478 Created ip list rfc1918.auto default (deny)
  15. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 10.0.0.0/8 (allow) [] to list rfc1918.auto
  16. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 172.16.0.0/12 (allow) [] to list rfc1918.auto
  17. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 192.168.0.0/16 (allow) [] to list rfc1918.auto
  18. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding fe80::/10 (allow) [] to list rfc1918.auto
  19. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1487 Created ip list wan.auto default (allow)
  20. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 0.0.0.0/8 (deny) [] to list wan.auto
  21. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 10.0.0.0/8 (deny) [] to list wan.auto
  22. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 172.16.0.0/12 (deny) [] to list wan.auto
  23. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 192.168.0.0/16 (deny) [] to list wan.auto
  24. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 169.254.0.0/16 (deny) [] to list wan.auto
  25. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding fe80::/10 (deny) [] to list wan.auto
  26. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1498 Created ip list wan_v6.auto default (allow)
  27. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 0.0.0.0/0 (deny) [] to list wan_v6.auto
  28. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding fe80::/10 (deny) [] to list wan_v6.auto
  29. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1506 Created ip list wan_v4.auto default (allow)
  30. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 0.0.0.0/8 (deny) [] to list wan_v4.auto
  31. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 10.0.0.0/8 (deny) [] to list wan_v4.auto
  32. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 172.16.0.0/12 (deny) [] to list wan_v4.auto
  33. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 192.168.0.0/16 (deny) [] to list wan_v4.auto
  34. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 169.254.0.0/16 (deny) [] to list wan_v4.auto
  35. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding ::/0 (deny) [] to list wan_v4.auto
  36. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1518 Created ip list any_v6.auto default (allow)
  37. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 0.0.0.0/0 (deny) [] to list any_v6.auto
  38. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1525 Created ip list any_v4.auto default (allow)
  39. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding ::/0 (deny) [] to list any_v4.auto
  40. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1532 Created ip list nat.auto default (deny)
  41. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1534 Adding 216.93.246.114/255.255.255.0 (deny) to list nat.auto
  42. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 10.0.0.0/8 (allow) [] to list nat.auto
  43. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 172.16.0.0/12 (allow) [] to list nat.auto
  44. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 192.168.0.0/16 (allow) [] to list nat.auto
  45. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 100.64.0.0/10 (allow) [] to list nat.auto
  46. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1544 Created ip list loopback.auto default (deny)
  47. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 127.0.0.0/8 (allow) [] to list loopback.auto
  48. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding ::1/128 (allow) [] to list loopback.auto
  49. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1551 Created ip list localnet.auto default (deny)
  50. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1554 Adding 216.93.246.114/255.255.255.0 (allow) to list localnet.auto
  51. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1579 Created ip list stretto default (deny)
  52. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 216.93.246.0/24 (allow) [] to list stretto
  53. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 127.0.0.1/32 (allow) [] to list stretto
  54. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1579 Created ip list lan default (allow)
  55. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 192.0.0.0/8 (deny) [] to list lan
  56. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 192.168.42.42/32 (allow) [] to list lan
  57. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1579 Created ip list vancouversbc default (deny)
  58. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 69.90.51.17/32 (allow) [] to list vancouversbc
  59. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 10.128.66.0/24 (allow) [] to list vancouversbc
  60. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 10.128.200.0/24 (allow) [] to list vancouversbc
  61. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 216.93.246.0/24 (allow) [] to list vancouversbc
  62. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1579 Created ip list domains default (deny)
  63. 2018-03-22 10:50:03.868860 [INFO] switch_time.c:1423 Timezone reloaded 1750 definitions
  64. 2018-03-22 10:50:03.868860 [ERR] mod_xml_curl.c:315 Received HTTP error 404 trying to fetch http://127.0.0.1:8077/
  65. data: [hostname=bstnma-freeswitch6&section=directory&tag_name=domain&key_name=name&key_value=216.93.246.114&Event-Name=GENERAL&Core-UUID=737b34cf-9168-4989-bf2a-ae3f1505234d&FreeSWITCH-Hostname=bstnma-freeswitch6&FreeSWITCH-Switchname=bstnma-freeswitch6&FreeSWITCH-IPv4=216.93.246.114&FreeSWITCH-IPv6=%3A%3A1&Event-Date-Local=2018-03-22%2010%3A50%3A03&Event-Date-GMT=Thu,%2022%20Mar%202018%2014%3A50%3A03%20GMT&Event-Date-Timestamp=1521730203868860&Event-Calling-File=switch_core.c&Event-Calling-Function=switch_load_network_lists&Event-Calling-Line-Number=1604&Event-Sequence=990&domain=216.93.246.114&purpose=network-list]
  66. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 192.0.2.0/24 (allow) [brian@216.93.246.114] to list domains
  67. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 216.93.246.0/24 (allow) [] to list domains
  68. 2018-03-22 10:50:03.868860 [NOTICE] switch_core.c:1579 Created ip list rfc1918 default (deny)
  69. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 10.0.0.0/8 (allow) [] to list rfc1918
  70. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 172.16.0.0/12 (allow) [] to list rfc1918
  71. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 192.168.0.0/16 (allow) [] to list rfc1918
  72. 2018-03-22 10:50:03.868860 [NOTICE] switch_utils.c:545 Adding 192.0.0.0/24 (allow) [] to list rfc1918
  73. tport.c:2760 tport_wakeup_pri() tport_wakeup_pri(0x7f95dc004f40): events IN
  74. tport.c:862 tport_alloc_secondary() tport_alloc_secondary(0x7f95dc004f40): new secondary tport 0x7f95dc02e120
  75. tport_type_tcp.c:203 tport_tcp_init_secondary() tport_tcp_init_secondary(0x7f95dc02e120): Setting TCP_KEEPIDLE to 30
  76. tport_type_tcp.c:209 tport_tcp_init_secondary() tport_tcp_init_secondary(0x7f95dc02e120): Setting TCP_KEEPINTVL to 30
  77. tport_type_tls.c:610 tport_tls_accept() tport_tls_accept(0x7f95dc02e120): new connection from tls/24.114.108.163:57304/sips
  78. tport_tls.c:956 tls_connect() tls_connect(0x7f95dc02e120): events NEGOTIATING
  79. tport_tls.c:956 tls_connect() tls_connect(0x7f95dc02e120): events NEGOTIATING
  80. tport_tls.c:599 tls_post_connection_check() tls_post_connection_check(0x7f95dc02e120): TLS cipher chosen (name): ECDHE-RSA-AES256-GCM-SHA384
  81. tport_tls.c:601 tls_post_connection_check() tls_post_connection_check(0x7f95dc02e120): TLS cipher chosen (version): TLSv1/SSLv3
  82. tport_tls.c:604 tls_post_connection_check() tls_post_connection_check(0x7f95dc02e120): TLS cipher chosen (bits/alg_bits): 256/256
  83. tport_tls.c:607 tls_post_connection_check() tls_post_connection_check(0x7f95dc02e120): TLS cipher chosen (description): ECDHE-RSA-AES256-GCM-SHA384 TLSv1.2 Kx=ECDH     Au=RSA  Enc=AESGCM(256) Mac=AEAD
  84.  
  85. tport_tls.c:612 tls_post_connection_check() tls_post_connection_check(0x7f95dc02e120): Peer did not provide X.509 Certificate.
  86. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  87. tport.c:2784 tport_wakeup() tport_wakeup(0x7f95dc02e120): events IN
  88. tport.c:2875 tport_recv_event() tport_recv_event(0x7f95dc02e120)
  89. tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f95dc02e120): tls_read() returned -1
  90. tport_type_tls.c:338 tport_tls_set_events() tport_tls_set_events(0x7f95dc02e120): logical events IN real IN
  91. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  92. tport.c:2784 tport_wakeup() tport_wakeup(0x7f95dc02e120): events IN
  93. tport.c:2875 tport_recv_event() tport_recv_event(0x7f95dc02e120)
  94. tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f95dc02e120): tls_read() returned 2129
  95. tport.c:3216 tport_recv_iovec() tport_recv_iovec(0x7f95dc02e120) msg 0x7f95dc02b8d0 from (tls/24.114.108.163:57304) has 2129 bytes, veclen = 1
  96. recv 2129 bytes from tls/[24.114.108.163]:57304 at 10:51:24.059922:
  97.    ------------------------------------------------------------------------
  98.    INVITE sip:5000@meetings6.softphone.com SIP/2.0
  99.    Via: SIP/2.0/TLS 192.0.0.4:53696;branch=z9hG4bK-524287-1---648b1a5f11a76ad9;rport
  100.    Max-Forwards: 70
  101.    Contact: <sip:guest_JamieStracha_3486d97@192.0.0.4:53696;ob;transport=tls>;+sip.instance="<urn:uuid:3a361d20-76c8-5514-b4b9-8b1f7b473efd>"
  102.    To: <sip:5000@meetings6.softphone.com>
  103.    From: <sip:guest_JamieStracha_3486d97@meetings6.softphone.com>;tag=4fddd918
  104.    Call-ID: 151911_rel67NDUwNDIwMTIzNGFiYTY0ZmNjZjc0ZDQzMzVhZTFjM2Y
  105.    CSeq: 1 INVITE
  106.    Allow: INVITE, ACK, CANCEL, BYE, REFER, INFO, NOTIFY, OPTIONS, UPDATE, PRACK, SUBSCRIBE, MESSAGE
  107.    Content-Type: application/sdp
  108.    Supported: outbound, path, replaces, 100rel
  109.    User-Agent: Bria Stretto Android 5.2.2 build 103528
  110.    Content-Length: 1389
  111.    
  112.    v=0
  113.    o=- 13865856205 1 IN IP4 192.0.0.4
  114.    s=Cpc session
  115.    c=IN IP4 192.0.0.4
  116.    t=0 0
  117.    m=audio 53200 RTP/SAVP 120 121 18 0 101
  118.    a=rtpmap:120 opus/48000/2
  119.    a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  120.    a=rtpmap:121 SILK/8000
  121.    a=rtpmap:18 G729/8000
  122.    a=fmtp:18 annexb=no
  123.    a=rtpmap:101 telephone-event/8000
  124.    a=fmtp:101 0-15
  125.    a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==
  126.    a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==
  127.    a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY
  128.    a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:CMT57vV8srZCT73qSK4n/b78Dz/hqRHydQzt+E/m
  129.    a=sendrecv
  130.    m=video 64246 RTP/SAVP 125 126 120
  131.    a=rtpmap:125 H264/90000
  132.    a=fmtp:125 profile-level-id=42801f;packetization-mode=0
  133.    a=rtpmap:126 H264/90000
  134.    a=fmtp:126 profile-level-id=42801f;packetization-mode=1
  135.    a=rtpmap:120 VP8/90000
  136.    a=rtcp-fb:* nack
  137.    a=rtcp-fb:* nack pli
  138.    a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==
  139.    a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==
  140.    a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY
  141.    a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:CMT57vV8srZCT73qSK4n/b78Dz/hqRHydQzt+E/m
  142.    a=sendrecv
  143.    ------------------------------------------------------------------------
  144. tport.c:3034 tport_deliver() tport_deliver(0x7f95dc02e120): msg 0x7f95dc02b8d0 (2129 bytes) from tls/24.114.108.163:57304/sips next=(nil)
  145. nta.c:2880 agent_recv_request() nta: received INVITE sip:5000@meetings6.softphone.com SIP/2.0 (CSeq 1)
  146. nta.c:3174 agent_check_request_via() nta: Via check: received=24.114.108.163
  147. nta.c:3085 agent_recv_request() nta: INVITE (1) going to a default leg
  148. nta.c:1350 set_timeout() nta: timer set to 2000 ms
  149. nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
  150. nua_stack.c:899 nh_create() nua: nh_create: entering
  151. nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
  152. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  153. soa.c:280 soa_clone() soa_clone(static::0x7f95dc001930, 0x7f95dc001130, 0x7f95dc032d50) called
  154. soa.c:403 soa_set_params() soa_set_params(static::0x7f95dc01d8c0, ...) called
  155. nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7f95dc022e20)
  156. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f95dc01d8c0) called
  157. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f95dc01d8c0, (nil), 0x7f95dc02c9f4, 1389) called
  158. nua_dialog.c:338 nua_dialog_usage_add() nua(0x7f95dc032d50): adding session usage
  159. tport.c:3268 tport_tsend() tport_tsend(0x7f95dc02e120) XXXXX tpn = TLS/24.114.108.163:57304
  160. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc033cd0 133 (133)
  161. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc02c85a 77 (77)
  162. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc02c832 40 (40)
  163. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc02c8a7 82 (82)
  164. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc033d55 79 (79)
  165. tport.c:3605 tport_vsend() tport_vsend(0x7f95dc02e120): 411 bytes of 411 to tls/24.114.108.163:57304
  166. tport.c:3503 tport_send_msg() tport_vsend returned 411
  167. send 411 bytes to tls/[24.114.108.163]:57304 at 10:51:24.060613:
  168.    ------------------------------------------------------------------------
  169.    SIP/2.0 100 Trying
  170.    Via: SIP/2.0/TLS 192.0.0.4:53696;branch=z9hG4bK-524287-1---648b1a5f11a76ad9;rport=57304;received=24.114.108.163
  171.    From: <sip:guest_JamieStracha_3486d97@meetings6.softphone.com>;tag=4fddd918
  172.    To: <sip:5000@meetings6.softphone.com>
  173.    Call-ID: 151911_rel67NDUwNDIwMTIzNGFiYTY0ZmNjZjc0ZDQzMzVhZTFjM2Y
  174.    CSeq: 1 INVITE
  175.    User-Agent: FreeSWITCH-mod_sofia/1.6.20-37-987c9b9~64bit
  176.    Content-Length: 0
  177.    
  178.    ------------------------------------------------------------------------
  179. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  180. nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (1)
  181. nua_stack.c:271 nua_stack_event() nua(0x7f95dc032d50): event i_invite 100 Trying
  182. nua_session.c:4139 signal_call_state_change() nua(0x7f95dc032d50): call state changed: init -> received, received offer
  183. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f95dc01d8c0, [0x7f961a8828b8], [0x7f961a8828c0], [(nil)]) called
  184. nua_stack.c:271 nua_stack_event() nua(0x7f95dc032d50): event i_state 100 Trying
  185. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  186. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  187. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
  188. 2018-03-22 10:51:24.058843 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com [6fa735f3-f396-4893-bce4-9777442cce3b]
  189. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  190. 2018-03-22 10:51:24.058843 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_NEW (Cur 1 Tot 3)
  191. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  192. 2018-03-22 10:51:24.058843 [DEBUG] sofia.c:9873 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com receiving invite from 24.114.108.163:57304 version: 1.6.20 -37-987c9b9 64bit
  193. 2018-03-22 10:51:24.058843 [DEBUG] sofia.c:10044 IP 24.114.108.163 Rejected by acl "domains". Falling back to Digest auth.
  194. nua.c:879 nua_respond() nua: nua_respond: entering
  195. nua_stack.c:529 nua_signal() nua(0x7f95dc032d50): sent signal r_respond
  196. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  197. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  198. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  199. 2018-03-22 10:51:24.058843 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State NEW
  200. nua_stack.c:573 nua_stack_signal() nua(0x7f95dc032d50): recv signal r_respond 407 Proxy Authentication Required
  201. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  202. soa.c:403 soa_set_params() soa_set_params(static::0x7f95dc01d8c0, ...) called
  203. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
  204. soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7f95dc01d8c0) called
  205. tport.c:3268 tport_tsend() tport_tsend(0x7f95dc02e120) XXXXX tpn = TLS/24.114.108.163:57304
  206. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc034630 156 (156)
  207. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc02c85a 77 (77)
  208. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc0346cc 58 (58)
  209. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc02c8a7 82 (82)
  210. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc034706 552 (552)
  211. tport.c:3605 tport_vsend() tport_vsend(0x7f95dc02e120): 925 bytes of 925 to tls/24.114.108.163:57304
  212. tport.c:3503 tport_send_msg() tport_vsend returned 925
  213. send 925 bytes to tls/[24.114.108.163]:57304 at 10:51:24.062281:
  214.    ------------------------------------------------------------------------
  215.    SIP/2.0 407 Proxy Authentication Required
  216.    Via: SIP/2.0/TLS 192.0.0.4:53696;branch=z9hG4bK-524287-1---648b1a5f11a76ad9;rport=57304;received=24.114.108.163
  217.    From: <sip:guest_JamieStracha_3486d97@meetings6.softphone.com>;tag=4fddd918
  218.    To: <sip:5000@meetings6.softphone.com>;tag=He77HrXQ3c8Zm
  219.    Call-ID: 151911_rel67NDUwNDIwMTIzNGFiYTY0ZmNjZjc0ZDQzMzVhZTFjM2Y
  220.    CSeq: 1 INVITE
  221.    User-Agent: FreeSWITCH-mod_sofia/1.6.20-37-987c9b9~64bit
  222.    Accept: application/sdp
  223.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  224.    Supported: timer, path, replaces
  225.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  226.    Proxy-Authenticate: Digest realm="meetings6.softphone.com", nonce="e7e3fa5c-9b45-4fad-81c4-bb254cbc70c8", algorithm=MD5, qop="auth"
  227.    Content-Length: 0
  228.    
  229.    ------------------------------------------------------------------------
  230. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  231. nta.c:6791 incoming_reply() nta: sent 407 Proxy Authentication Required for INVITE (1)
  232. nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7f95dc032d50): removing session usage
  233. nua_session.c:4139 signal_call_state_change() nua(0x7f95dc032d50): call state changed: received -> terminated
  234. nua_stack.c:271 nua_stack_event() nua(0x7f95dc032d50): event i_state 407 Proxy Authentication Required
  235. nua_stack.c:271 nua_stack_event() nua(0x7f95dc032d50): event i_terminated 407 Proxy Authentication Required
  236. soa.c:356 soa_destroy() soa_destroy(static::0x7f95dc01d8c0) called
  237. nta.c:4470 nta_leg_destroy() nta_leg_destroy(0x7f95dc022e20)
  238. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  239. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  240. 2018-03-22 10:51:24.058843 [DEBUG] sofia.c:2334 detaching session 6fa735f3-f396-4893-bce4-9777442cce3b
  241. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  242. nua_stack.c:529 nua_signal() nua(0x7f95dc032d50): sent signal r_destroy
  243. nua_stack.c:569 nua_stack_signal() nua(0x7f95dc032d50): recv signal r_destroy
  244. nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
  245. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  246. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  247. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  248. tport.c:2784 tport_wakeup() tport_wakeup(0x7f95dc02e120): events IN
  249. tport.c:2875 tport_recv_event() tport_recv_event(0x7f95dc02e120)
  250. tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f95dc02e120): tls_read() returned 382
  251. tport.c:3216 tport_recv_iovec() tport_recv_iovec(0x7f95dc02e120) msg 0x7f95dc034280 from (tls/24.114.108.163:57304) has 382 bytes, veclen = 1
  252. recv 382 bytes from tls/[24.114.108.163]:57304 at 10:51:24.158805:
  253.    ------------------------------------------------------------------------
  254.    ACK sip:5000@meetings6.softphone.com SIP/2.0
  255.    Via: SIP/2.0/TLS 192.0.0.4:53696;branch=z9hG4bK-524287-1---648b1a5f11a76ad9;rport
  256.    Max-Forwards: 70
  257.    To: <sip:5000@meetings6.softphone.com>;tag=He77HrXQ3c8Zm
  258.    From: <sip:guest_JamieStracha_3486d97@meetings6.softphone.com>;tag=4fddd918
  259.    Call-ID: 151911_rel67NDUwNDIwMTIzNGFiYTY0ZmNjZjc0ZDQzMzVhZTFjM2Y
  260.    CSeq: 1 ACK
  261.    Content-Length: 0
  262.    
  263.    ------------------------------------------------------------------------
  264. tport.c:3034 tport_deliver() tport_deliver(0x7f95dc02e120): msg 0x7f95dc034280 (382 bytes) from tls/24.114.108.163:57304/sips next=(nil)
  265. nta.c:2880 agent_recv_request() nta: received ACK sip:5000@meetings6.softphone.com SIP/2.0 (CSeq 1)
  266. nta.c:3174 agent_check_request_via() nta: Via check: received=24.114.108.163
  267. nta.c:3019 agent_recv_request() nta: ACK (1) is going to INVITE (1)
  268. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  269. tport.c:2784 tport_wakeup() tport_wakeup(0x7f95dc02e120): events IN
  270. tport.c:2875 tport_recv_event() tport_recv_event(0x7f95dc02e120)
  271. tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f95dc02e120): tls_read() returned -1
  272. tport_type_tls.c:338 tport_tls_set_events() tport_tls_set_events(0x7f95dc02e120): logical events IN real IN
  273. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  274. tport.c:2784 tport_wakeup() tport_wakeup(0x7f95dc02e120): events IN
  275. tport.c:2875 tport_recv_event() tport_recv_event(0x7f95dc02e120)
  276. tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f95dc02e120): tls_read() returned 2433
  277. tport.c:3216 tport_recv_iovec() tport_recv_iovec(0x7f95dc02e120) msg 0x7f95dc034280 from (tls/24.114.108.163:57304) has 2433 bytes, veclen = 1
  278. recv 2433 bytes from tls/[24.114.108.163]:57304 at 10:51:24.240914:
  279.    ------------------------------------------------------------------------
  280.    INVITE sip:5000@meetings6.softphone.com SIP/2.0
  281.    Via: SIP/2.0/TLS 192.0.0.4:53696;branch=z9hG4bK-524287-1---b65f79db56feac6e;rport
  282.    Max-Forwards: 70
  283.    Contact: <sip:guest_JamieStracha_3486d97@192.0.0.4:53696;ob;transport=tls>;+sip.instance="<urn:uuid:3a361d20-76c8-5514-b4b9-8b1f7b473efd>"
  284.    To: <sip:5000@meetings6.softphone.com>
  285.    From: <sip:guest_JamieStracha_3486d97@meetings6.softphone.com>;tag=4fddd918
  286.    Call-ID: 151911_rel67NDUwNDIwMTIzNGFiYTY0ZmNjZjc0ZDQzMzVhZTFjM2Y
  287.    CSeq: 2 INVITE
  288.    Allow: INVITE, ACK, CANCEL, BYE, REFER, INFO, NOTIFY, OPTIONS, UPDATE, PRACK, SUBSCRIBE, MESSAGE
  289.    Content-Type: application/sdp
  290.    Proxy-Authorization: Digest username="guest_JamieStracha_3486d97",realm="meetings6.softphone.com",nonce="e7e3fa5c-9b45-4fad-81c4-bb254cbc70c8",uri="sip:5000@meetings6.softphone.com",response="f3e8f35bda60c1e2a5180854bd4f863c",cnonce="b52d75b8e318813db90d219296b1719e",nc=00000001,qop=auth,algorithm=MD5
  291.    Supported: outbound, path, replaces, 100rel
  292.    User-Agent: Bria Stretto Android 5.2.2 build 103528
  293.    Content-Length: 1389
  294.    
  295.    v=0
  296.    o=- 13865856205 1 IN IP4 192.0.0.4
  297.    s=Cpc session
  298.    c=IN IP4 192.0.0.4
  299.    t=0 0
  300.    m=audio 53200 RTP/SAVP 120 121 18 0 101
  301.    a=rtpmap:120 opus/48000/2
  302.    a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  303.    a=rtpmap:121 SILK/8000
  304.    a=rtpmap:18 G729/8000
  305.    a=fmtp:18 annexb=no
  306.    a=rtpmap:101 telephone-event/8000
  307.    a=fmtp:101 0-15
  308.    a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==
  309.    a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==
  310.    a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY
  311.    a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:CMT57vV8srZCT73qSK4n/b78Dz/hqRHydQzt+E/m
  312.    a=sendrecv
  313.    m=video 64246 RTP/SAVP 125 126 120
  314.    a=rtpmap:125 H264/90000
  315.    a=fmtp:125 profile-level-id=42801f;packetization-mode=0
  316.    a=rtpmap:126 H264/90000
  317.    a=fmtp:126 profile-level-id=42801f;packetization-mode=1
  318.    a=rtpmap:120 VP8/90000
  319.    a=rtcp-fb:* nack
  320.    a=rtcp-fb:* nack pli
  321.    a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==
  322.    a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==
  323.    a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY
  324.    a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:CMT57vV8srZCT73qSK4n/b78Dz/hqRHydQzt+E/m
  325.    a=sendrecv
  326.    ------------------------------------------------------------------------
  327. tport.c:3034 tport_deliver() tport_deliver(0x7f95dc02e120): msg 0x7f95dc034280 (2433 bytes) from tls/24.114.108.163:57304/sips next=(nil)
  328. nta.c:2880 agent_recv_request() nta: received INVITE sip:5000@meetings6.softphone.com SIP/2.0 (CSeq 2)
  329. nta.c:3174 agent_check_request_via() nta: Via check: received=24.114.108.163
  330. nta.c:3085 agent_recv_request() nta: INVITE (2) going to a default leg
  331. nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
  332. nua_stack.c:899 nh_create() nua: nh_create: entering
  333. nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
  334. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  335. soa.c:280 soa_clone() soa_clone(static::0x7f95dc001930, 0x7f95dc001130, 0x7f95dc021300) called
  336. soa.c:403 soa_set_params() soa_set_params(static::0x7f95dc0216b0, ...) called
  337. nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7f95dc022e80)
  338. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f95dc0216b0) called
  339. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f95dc0216b0, (nil), 0x7f95dc033a74, 1389) called
  340. nua_dialog.c:338 nua_dialog_usage_add() nua(0x7f95dc021300): adding session usage
  341. tport.c:3268 tport_tsend() tport_tsend(0x7f95dc02e120) XXXXX tpn = TLS/24.114.108.163:57304
  342. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc036320 133 (133)
  343. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc0337aa 77 (77)
  344. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc033782 40 (40)
  345. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc0337f7 82 (82)
  346. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc0363a5 79 (79)
  347. tport.c:3605 tport_vsend() tport_vsend(0x7f95dc02e120): 411 bytes of 411 to tls/24.114.108.163:57304
  348. tport.c:3503 tport_send_msg() tport_vsend returned 411
  349. send 411 bytes to tls/[24.114.108.163]:57304 at 10:51:24.241459:
  350.    ------------------------------------------------------------------------
  351.    SIP/2.0 100 Trying
  352.    Via: SIP/2.0/TLS 192.0.0.4:53696;branch=z9hG4bK-524287-1---b65f79db56feac6e;rport=57304;received=24.114.108.163
  353.    From: <sip:guest_JamieStracha_3486d97@meetings6.softphone.com>;tag=4fddd918
  354.    To: <sip:5000@meetings6.softphone.com>
  355.    Call-ID: 151911_rel67NDUwNDIwMTIzNGFiYTY0ZmNjZjc0ZDQzMzVhZTFjM2Y
  356.    CSeq: 2 INVITE
  357.    User-Agent: FreeSWITCH-mod_sofia/1.6.20-37-987c9b9~64bit
  358.    Content-Length: 0
  359.    
  360.    ------------------------------------------------------------------------
  361. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  362. nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (2)
  363. nua_stack.c:271 nua_stack_event() nua(0x7f95dc021300): event i_invite 100 Trying
  364. nua_session.c:4139 signal_call_state_change() nua(0x7f95dc021300): call state changed: init -> received, received offer
  365. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f95dc0216b0, [0x7f961a8828b8], [0x7f961a8828c0], [(nil)]) called
  366. nua_stack.c:271 nua_stack_event() nua(0x7f95dc021300): event i_state 100 Trying
  367. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  368. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  369. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
  370. 2018-03-22 10:51:24.238876 [DEBUG] sofia.c:2442 Re-attaching to session 6fa735f3-f396-4893-bce4-9777442cce3b
  371. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  372. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  373. 2018-03-22 10:51:24.238876 [DEBUG] sofia.c:9873 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com receiving invite from 24.114.108.163:57304 version: 1.6.20 -37-987c9b9 64bit
  374. 2018-03-22 10:51:24.238876 [DEBUG] sofia.c:10044 IP 24.114.108.163 Rejected by acl "domains". Falling back to Digest auth.
  375. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  376. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  377. 2018-03-22 10:51:24.248861 [DEBUG] sofia.c:7084 Channel sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com entering state [received][100]
  378. 2018-03-22 10:51:24.248861 [DEBUG] sofia.c:7094 Remote SDP:
  379. v=0
  380. o=- 13865856205 1 IN IP4 192.0.0.4
  381. s=Cpc session
  382. c=IN IP4 192.0.0.4
  383. t=0 0
  384. m=audio 53200 RTP/SAVP 120 121 18 0 101
  385. a=rtpmap:120 opus/48000/2
  386. a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  387. a=rtpmap:121 SILK/8000
  388. a=rtpmap:18 G729/8000
  389. a=fmtp:18 annexb=no
  390. a=rtpmap:101 telephone-event/8000
  391. a=fmtp:101 0-15
  392. a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==
  393. a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==
  394. a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY
  395. a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:CMT57vV8srZCT73qSK4n/b78Dz/hqRHydQzt+E/m
  396. m=video 64246 RTP/SAVP 125 126 120
  397. a=rtpmap:125 H264/90000
  398. a=fmtp:125 profile-level-id=42801f;packetization-mode=0
  399. a=rtpmap:126 H264/90000
  400. a=fmtp:126 profile-level-id=42801f;packetization-mode=1
  401. a=rtpmap:120 VP8/90000
  402. a=rtcp-fb:* nack
  403. a=rtcp-fb:* nack pli
  404. a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==
  405. a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==
  406. a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY
  407. a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:CMT57vV8srZCT73qSK4n/b78Dz/hqRHydQzt+E/m
  408.  
  409. 2018-03-22 10:51:24.248861 [DEBUG] sofia.c:7486 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State Change CS_NEW -> CS_INIT
  410. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  411. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_INIT (Cur 1 Tot 3)
  412. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State INIT
  413. 2018-03-22 10:51:24.248861 [DEBUG] mod_sofia.c:90 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com SOFIA INIT
  414. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:40 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard INIT
  415. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State Change CS_INIT -> CS_ROUTING
  416. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State INIT going to sleep
  417. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_ROUTING (Cur 1 Tot 3)
  418. 2018-03-22 10:51:24.248861 [DEBUG] switch_channel.c:2249 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Callstate Change DOWN -> RINGING
  419. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State ROUTING
  420. 2018-03-22 10:51:24.248861 [DEBUG] mod_sofia.c:143 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com SOFIA ROUTING
  421. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:236 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard ROUTING
  422. 2018-03-22 10:51:24.248861 [INFO] mod_dialplan_xml.c:637 Processing guest_JamieStracha_3486d97 <guest_JamieStracha_3486d97>->5000 in context public
  423. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [public->unloop] continue=false
  424. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  425. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  426. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [public->outside_call] continue=true
  427. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Absolute Condition [outside_call]
  428. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Action set(outside_call=true)
  429. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  430. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [public->call_debug] continue=true
  431. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  432. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [public->public_extensions] continue=false
  433. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (PASS) [public_extensions] destination_number(5000) =~ /^(5000)$/ break=on-false
  434. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Action transfer(5000 XML default)
  435. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State Change CS_ROUTING -> CS_EXECUTE
  436. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State ROUTING going to sleep
  437. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_EXECUTE (Cur 1 Tot 3)
  438. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State EXECUTE
  439. 2018-03-22 10:51:24.248861 [DEBUG] mod_sofia.c:198 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com SOFIA EXECUTE
  440. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:328 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard EXECUTE
  441. EXECUTE sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com set(outside_call=true)
  442. 2018-03-22 10:51:24.248861 [DEBUG] mod_dptools.c:1548 SET sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com [outside_call]=[true]
  443. EXECUTE sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com export(RFC2822_DATE=Thu, 22 Mar 2018 10:51:24 -0400)
  444. 2018-03-22 10:51:24.248861 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 22 Mar 2018 10:51:24 -0400]
  445. EXECUTE sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com transfer(5000 XML default)
  446. 2018-03-22 10:51:24.248861 [DEBUG] switch_ivr.c:2165 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State Change CS_EXECUTE -> CS_ROUTING
  447. 2018-03-22 10:51:24.248861 [NOTICE] switch_ivr.c:2172 Transfer sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com to XML[5000@default]
  448. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State EXECUTE going to sleep
  449. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_ROUTING (Cur 1 Tot 3)
  450. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State ROUTING
  451. 2018-03-22 10:51:24.248861 [DEBUG] mod_sofia.c:143 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com SOFIA ROUTING
  452. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:236 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard ROUTING
  453. 2018-03-22 10:51:24.248861 [INFO] mod_dialplan_xml.c:637 Processing guest_JamieStracha_3486d97 <guest_JamieStracha_3486d97>->5000 in context default
  454. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [default->unloop] continue=false
  455. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  456. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  457. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [default->conference_lobby] continue=false
  458. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (PASS) [conference_lobby] destination_number(5000) =~ /^(5000(?:#\d{4})?)$/ break=on-false
  459. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Action answer()
  460. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Action park()
  461. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State Change CS_ROUTING -> CS_EXECUTE
  462. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State ROUTING going to sleep
  463. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_EXECUTE (Cur 1 Tot 3)
  464. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State EXECUTE
  465. 2018-03-22 10:51:24.248861 [DEBUG] mod_sofia.c:198 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com SOFIA EXECUTE
  466. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_state_machine.c:328 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard EXECUTE
  467. EXECUTE sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com answer()
  468. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_256_GCM_8] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  469. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_128_GCM_8] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  470. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  471. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  472. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  473. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  474. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  475. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  476. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_NULL_AUTH] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  477. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1460 Unsupported Crypto [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  478. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_256_GCM_8] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  479. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_128_GCM_8] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  480. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  481. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  482. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  483. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  484. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  485. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  486. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_NULL_AUTH] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  487. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1460 Unsupported Crypto [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  488. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_256_GCM_8] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  489. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_128_GCM_8] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  490. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  491. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  492. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  493. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1400 Found suite AES_CM_128_HMAC_SHA1_80
  494. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1466 Set Remote Key [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  495. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1128 Set Local audio crypto Key [3 AES_CM_128_HMAC_SHA1_80 inline:Xb2QITxAoJtCfiEtdLY5Y98JUJjOGk76EpSr0CVC]
  496. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:120:48000:20:0:1]/[opus:116:48000:20:0:1]
  497. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
  498. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:120:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
  499. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:120:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
  500. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:121:8000:20:0:1]/[opus:116:48000:20:0:1]
  501. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:121:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  502. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:121:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  503. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  504. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  505. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  506. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  507. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  508. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  509. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  510. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  511. 2018-03-22 10:51:24.248861 [DEBUG] mod_opus.c:598 Opus encoder: set bitrate based on maxaveragebitrate value found in SDP or local config [64000bps]
  512. 2018-03-22 10:51:24.248861 [DEBUG] mod_opus.c:598 Opus encoder: set bitrate based on maxaveragebitrate value found in SDP or local config [64000bps]
  513. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com opus/48000 20 ms 960 samples 0 bits 1 channels
  514. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_codec.c:111 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Original read codec set to opus:116
  515. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4767 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Set 2833 dtmf send payload to 101 recv payload to 101
  516. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_256_GCM_8] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  517. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_128_GCM_8] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  518. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  519. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  520. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  521. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  522. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  523. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  524. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_NULL_AUTH] in [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  525. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1460 Unsupported Crypto [1 AES_256_CM_HMAC_SHA1_80 inline:jQfU0+5pc+3H0wdHC2SO3tjqbY0otX2ARGJTykCisrPveQLm3WtocnCzJYA3HA==]
  526. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_256_GCM_8] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  527. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_128_GCM_8] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  528. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  529. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  530. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  531. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  532. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  533. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  534. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_NULL_AUTH] in [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  535. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1460 Unsupported Crypto [2 AES_256_CM_HMAC_SHA1_32 inline:nRN9B+uJ35YmMNAW4bEmSqX+W9akezqeBZv0puRz4qmAVrkBoPC46oa25WMHtQ==]
  536. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_256_GCM_8] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  537. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AEAD_AES_128_GCM_8] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  538. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  539. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  540. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1395 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  541. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1400 Found suite AES_CM_128_HMAC_SHA1_80
  542. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1466 Set Remote Key [3 AES_CM_128_HMAC_SHA1_80 inline:/oCnYp9d/G2zAjauf/wM4elvNHr/aHznoCJMmPcY]
  543. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:1128 Set Local video crypto Key [3 AES_CM_128_HMAC_SHA1_80 inline:YtUdphzRH+ykAwQOsX7UQ+K+OHzjXE0Ti6pnAAKs]
  544. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4950 Video Codec Compare [H264:125]/[H264:99]
  545. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4982 Video Codec Compare [H264:125] +++ is saved as a match
  546. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4950 Video Codec Compare [H264:126]/[H264:99]
  547. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4982 Video Codec Compare [H264:126] +++ is saved as a match
  548. 2018-03-22 10:51:24.248861 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:120]/[H264:99]
  549. 2018-03-22 10:51:24.248861 [NOTICE] avcodec.c:1022 codec: id=28 H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
  550. 2018-03-22 10:51:24.258855 [NOTICE] avcodec.c:1022 codec: id=28 H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
  551. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com H264/90000 0 ms
  552. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com] 216.93.246.114 port 31714 -> 192.0.0.4 port 53200 codec: 120 ms: 20
  553. 2018-03-22 10:51:24.258855 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 960 bytes per 20ms
  554. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:7096 Activating RTCP PORT 53201
  555. 2018-03-22 10:51:24.258855 [DEBUG] switch_rtp.c:4533 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 53201
  556. 2018-03-22 10:51:24.258855 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 192.0.0.4:53201 2
  557. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:7180 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Set 2833 dtmf send payload to 101
  558. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:7187 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Set 2833 dtmf receive payload to 101
  559. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:7210 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Set rtp dtmf delay to 40
  560. 2018-03-22 10:51:24.258855 [INFO] switch_rtp.c:3941 Activating audio Secure RTP SEND
  561. 2018-03-22 10:51:24.258855 [INFO] switch_rtp.c:3919 Activating audio Secure RTP RECV
  562. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  563. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  564. 2018-03-22 10:51:24.258855 [DEBUG] switch_rtp.c:4152 Starting video timer.
  565. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:7399 VIDEO RTP [sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com] 216.93.246.114:18614->192.0.0.4:64246 codec: 125 ms: 0 [SUCCESS]
  566. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:6003 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Starting Video thread
  567. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:5869 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Video thread started. Echo is off
  568. 2018-03-22 10:51:24.258855 [INFO] switch_core_media.c:7479 Activating VIDEO RTCP PORT 64247 interval 1000 mux -1
  569. 2018-03-22 10:51:24.258855 [DEBUG] switch_rtp.c:4533 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 64247
  570. 2018-03-22 10:51:24.258855 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 192.0.0.4:64247 2
  571. 2018-03-22 10:51:24.258855 [INFO] switch_rtp.c:3941 Activating video Secure RTP SEND
  572. 2018-03-22 10:51:24.258855 [INFO] switch_rtp.c:3919 Activating video Secure RTP RECV
  573. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  574. 2018-03-22 10:51:24.258855 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com!
  575. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  576. 2018-03-22 10:51:24.258855 [DEBUG] switch_channel.c:3474 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Callstate Change RINGING -> EARLY
  577. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com.
  578. 2018-03-22 10:51:24.258855 [DEBUG] switch_core_media.c:7257 Video params are unchanged for sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com.
  579. 2018-03-22 10:51:24.258855 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com:
  580. v=0
  581. o=FreeSWITCH 1521698570 1521698571 IN IP4 216.93.246.114
  582. s=FreeSWITCH
  583. c=IN IP4 216.93.246.114
  584. t=0 0
  585. m=audio 31714 RTP/SAVP 120 101
  586. a=rtpmap:120 opus/48000/2
  587. a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  588. a=rtpmap:101 telephone-event/8000
  589. a=fmtp:101 0-16
  590. a=ptime:20
  591. a=sendrecv
  592. a=rtcp:31715 IN IP4 216.93.246.114
  593. a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:Xb2QITxAoJtCfiEtdLY5Y98JUJjOGk76EpSr0CVC
  594. m=video 18614 RTP/SAVP 125
  595. b=AS:2048
  596. a=rtpmap:125 H264/90000
  597. a=fmtp:125 profile-level-id=42801f;packetization-mode=0
  598. a=rtcp:18615 IN IP4 216.93.246.114
  599. a=rtcp-fb:125 nack
  600. a=rtcp-fb:125 nack pli
  601. a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:YtUdphzRH+ykAwQOsX7UQ+K+OHzjXE0Ti6pnAAKs
  602. m=video 18614 RTP/SAVP 125
  603. b=AS:2048
  604. a=rtpmap:125 H264/90000
  605. a=fmtp:125 profile-level-id=42801f;packetization-mode=0
  606. a=rtcp:18615 IN IP4 216.93.246.114
  607. a=rtcp-fb:125 nack
  608. a=rtcp-fb:125 nack pli
  609.  
  610. nua.c:879 nua_respond() nua: nua_respond: entering
  611. nua_stack.c:529 nua_signal() nua(0x7f95dc021300): sent signal r_respond
  612. nua_stack.c:573 nua_stack_signal() nua(0x7f95dc021300): recv signal r_respond 200 OK
  613. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  614. soa.c:403 soa_set_params() soa_set_params(static::0x7f95dc0216b0, ...) called
  615. soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7f95dc0216b0, (nil), 0x7f9560011bdd, -1) called
  616. soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x7f95dc0216b0, (nil), 0x7f9560011bdd, -1) called
  617. 2018-03-22 10:51:24.268777 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com] has been answered
  618. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
  619. soa.c:1515 soa_generate_answer() soa_generate_answer(static::0x7f95dc0216b0) called
  620. soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7f95dc0216b0, soa_generate_answer): called
  621. soa_static.c:1189 offer_answer_step() soa_static(0x7f95dc0216b0, soa_generate_answer): generating local description
  622. soa_static.c:1230 offer_answer_step() soa_static(0x7f95dc0216b0, soa_generate_answer): upgrade with remote description
  623. soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7f961a880b00, 0x7f95dc036d20, ""): called
  624. soa_static.c:1446 offer_answer_step() soa_static(0x7f95dc0216b0, soa_generate_answer): storing local description
  625. soa.c:1730 soa_activate() soa_activate(static::0x7f95dc0216b0, (nil)) called
  626. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f95dc0216b0, [(nil)], [0x7f961a882c38], [0x7f961a882c34]) called
  627. tport.c:3268 tport_tsend() tport_tsend(0x7f95dc02e120) XXXXX tpn = TLS/24.114.108.163:57304
  628. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc039b10 129 (129)
  629. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc0337aa 77 (77)
  630. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc039b91 58 (58)
  631. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc0337f7 82 (82)
  632. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc039bcb 633 (633)
  633. tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 0x7f95dc034b10 0x7f95dc03e270 699 (699)
  634. tport.c:3605 tport_vsend() tport_vsend(0x7f95dc02e120): 1678 bytes of 1678 to tls/24.114.108.163:57304
  635. tport.c:3503 tport_send_msg() tport_vsend returned 1678
  636. send 1678 bytes to tls/[24.114.108.163]:57304 at 10:51:24.269152:
  637.    ------------------------------------------------------------------------
  638.    SIP/2.0 200 OK
  639.    Via: SIP/2.0/TLS 192.0.0.4:53696;branch=z9hG4bK-524287-1---b65f79db56feac6e;rport=57304;received=24.114.108.163
  640.    From: <sip:guest_JamieStracha_3486d97@meetings6.softphone.com>;tag=4fddd918
  641.    To: <sip:5000@meetings6.softphone.com>;tag=jQ00KKeU0Nyjg
  642.    Call-ID: 151911_rel67NDUwNDIwMTIzNGFiYTY0ZmNjZjc0ZDQzMzVhZTFjM2Y
  643.    CSeq: 2 INVITE
  644.    Contact: <sip:5000@216.93.246.114:5061;transport=tls>
  645.    User-Agent: FreeSWITCH-mod_sofia/1.6.20-37-987c9b9~64bit
  646.    Accept: application/sdp
  647.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  648.    Supported: timer, path, replaces
  649.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  650.    Content-Type: application/sdp
  651.    Content-Disposition: session
  652.    Content-Length: 699
  653.    Remote-Party-ID: "5000" <sip:5000@meetings6.softphone.com>;party=calling;privacy=off;screen=no
  654.    
  655.    v=0
  656.    o=FreeSWITCH 1521698570 1521698571 IN IP4 216.93.246.114
  657.    s=FreeSWITCH
  658.    c=IN IP4 216.93.246.114
  659.    t=0 0
  660.    m=audio 31714 RTP/SAVP 120 101
  661.    a=rtpmap:120 opus/48000/2
  662.    a=fmtp:120 useinbandfec=1; usedtx=1; maxaveragebitrate=64000
  663.    a=rtpmap:101 telephone-event/8000
  664.    a=fmtp:101 0-16
  665.    a=ptime:20
  666.    a=rtcp:31715 IN IP4 216.93.246.114
  667.    a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:Xb2QITxAoJtCfiEtdLY5Y98JUJjOGk76EpSr0CVC
  668.    m=video 18614 RTP/SAVP 125
  669.    b=AS:2048
  670.    a=rtpmap:125 H264/90000
  671.    a=fmtp:125 profile-level-id=42801f;packetization-mode=0
  672.    a=rtcp:18615 IN IP4 216.93.246.114
  673.    a=rtcp-fb:125 nack
  674.    a=rtcp-fb:125 nack pli
  675.    a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:YtUdphzRH+ykAwQOsX7UQ+K+OHzjXE0Ti6pnAAKs
  676.    ------------------------------------------------------------------------
  677. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  678. nta.c:6791 incoming_reply() nta: sent 200 OK for INVITE (2)
  679. nta.c:1348 set_timeout() nta: timer shortened to 500 ms
  680. nua_session.c:4139 signal_call_state_change() nua(0x7f95dc021300): call state changed: received -> completed, sent answer
  681. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f95dc0216b0, [0x7f961a882ce8], [0x7f961a882cf0], [(nil)]) called
  682. soa.c:616 soa_get_params() soa_get_params(static::0x7f95dc0216b0, ...) called
  683. nua_stack.c:271 nua_stack_event() nua(0x7f95dc021300): event i_state 200 OK
  684. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  685. 2018-03-22 10:51:24.268777 [DEBUG] switch_channel.c:3773 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Callstate Change EARLY -> ACTIVE
  686. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  687. 2018-03-22 10:51:24.268777 [DEBUG] sofia.c:7084 Channel sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com entering state [completed][200]
  688. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  689. EXECUTE sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com park()
  690. 2018-03-22 10:51:24.268777 [DEBUG] switch_ivr.c:980 Codec Activated L16@48000hz 1 channels 20ms
  691. 2018-03-22 10:51:24.288800 [DEBUG] switch_ivr.c:2165 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State Change CS_EXECUTE -> CS_ROUTING
  692. 2018-03-22 10:51:24.288800 [NOTICE] switch_ivr.c:2172 Transfer sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com to XML[bridge-1010-collab-1080@default]
  693. 2018-03-22 10:51:24.298837 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State EXECUTE going to sleep
  694. 2018-03-22 10:51:24.298837 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_ROUTING (Cur 1 Tot 3)
  695. 2018-03-22 10:51:24.298837 [DEBUG] switch_channel.c:2249 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Callstate Change ACTIVE -> RINGING
  696. 2018-03-22 10:51:24.298837 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State ROUTING
  697. 2018-03-22 10:51:24.298837 [DEBUG] mod_sofia.c:143 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com SOFIA ROUTING
  698. 2018-03-22 10:51:24.298837 [DEBUG] switch_core_state_machine.c:236 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard ROUTING
  699. 2018-03-22 10:51:24.298837 [INFO] mod_dialplan_xml.c:637 Processing guest_JamieStracha_3486d97 <guest_JamieStracha_3486d97>->bridge-1010-collab-1080 in context default
  700. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [default->unloop] continue=false
  701. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  702. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  703. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [default->conference_lobby] continue=false
  704. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (FAIL) [conference_lobby] destination_number(bridge-1010-collab-1080) =~ /^(5000(?:#\d{4})?)$/ break=on-false
  705. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [default->lobby_transfer] continue=false
  706. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (FAIL) [lobby_transfer] destination_number(bridge-1010-collab-1080) =~ /^xfer-(.*)$/ break=on-false
  707. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com parsing [default->stretto_conference_physical_bridge] continue=false
  708. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Regex (PASS) [stretto_conference_physical_bridge] destination_number(bridge-1010-collab-1080) =~ /^(bridge-\d{4})-(.*)?$/ break=on-false
  709. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Action answer()
  710. Dialplan: sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Action conference(bridge-1010@collab-1080)
  711. 2018-03-22 10:51:24.298837 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State Change CS_ROUTING -> CS_EXECUTE
  712. 2018-03-22 10:51:24.298837 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State ROUTING going to sleep
  713. 2018-03-22 10:51:24.298837 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_EXECUTE (Cur 1 Tot 3)
  714. 2018-03-22 10:51:24.298837 [DEBUG] switch_channel.c:2251 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Callstate Change RINGING -> ACTIVE
  715. 2018-03-22 10:51:24.298837 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State EXECUTE
  716. 2018-03-22 10:51:24.298837 [DEBUG] mod_sofia.c:198 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com SOFIA EXECUTE
  717. 2018-03-22 10:51:24.298837 [DEBUG] switch_core_state_machine.c:328 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard EXECUTE
  718. EXECUTE sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com answer()
  719. EXECUTE sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com conference(bridge-1010@collab-1080)
  720. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:275 Adding layout group ribbon
  721. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon1 to layout group ribbon
  722. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon2 to layout group ribbon
  723. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon3 to layout group ribbon
  724. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon4 to layout group ribbon
  725. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon5 to layout group ribbon
  726. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon6 to layout group ribbon
  727. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:275 Adding layout group focus
  728. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up_top_left+5 to layout group focus
  729. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up_top_left+7 to layout group focus
  730. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up_top_left+9 to layout group focus
  731. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:275 Adding layout group grid
  732. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid
  733. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 2x1 to layout group grid
  734. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1x1+2x1 to layout group grid
  735. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid
  736. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid
  737. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 4x4 to layout group grid
  738. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 5x5 to layout group grid
  739. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 6x6 to layout group grid
  740. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 8x8 to layout group grid
  741. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:275 Adding layout group townhall
  742. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group townhall
  743. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:275 Adding layout group 1up-ribbon-progression
  744. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon1 to layout group 1up-ribbon-progression
  745. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon2 to layout group 1up-ribbon-progression
  746. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon3 to layout group 1up-ribbon-progression
  747. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon4 to layout group 1up-ribbon-progression
  748. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon5 to layout group 1up-ribbon-progression
  749. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up-ribbon6 to layout group 1up-ribbon-progression
  750. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:275 Adding layout group grid-zoom
  751. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid-zoom
  752. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 2x1-zoom to layout group grid-zoom
  753. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 3x1-zoom to layout group grid-zoom
  754. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid-zoom
  755. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 5-grid-zoom to layout group grid-zoom
  756. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 3x2-zoom to layout group grid-zoom
  757. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 7-grid-zoom to layout group grid-zoom
  758. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 4x2-zoom to layout group grid-zoom
  759. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid-zoom
  760. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:275 Adding layout group 1up_top_left_plus
  761. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up_top_left+5 to layout group 1up_top_left_plus
  762. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up_top_left+7 to layout group 1up_top_left_plus
  763. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 1up_top_left+9 to layout group 1up_top_left_plus
  764. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:275 Adding layout group 3up_plus
  765. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 3up+4 to layout group 3up_plus
  766. 2018-03-22 10:51:24.298837 [DEBUG] conference_video.c:284 Adding node 3up+9 to layout group 3up_plus
  767. 2018-03-22 10:51:24.298837 [DEBUG] mod_conference.c:3084 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie
  768. 2018-03-22 10:51:24.308822 [DEBUG] conference_video.c:1166 Canvas position 1 applied layout 1up_top_left+5
  769. 2018-03-22 10:51:24.308822 [DEBUG] conference_video.c:1256 Layout set to 1up_top_left+5
  770. 2018-03-22 10:51:24.308822 [DEBUG] conference_video.c:1216 Canvas attached to position 0
  771. 2018-03-22 10:51:24.308822 [DEBUG] conference_member.c:1693 Raw Codec Activation Success L16@48000hz 1 channel 20ms
  772. 2018-03-22 10:51:24.308822 [DEBUG] conference_member.c:1740 Raw Codec Activation Success L16@48000hz 2 channel 20ms
  773. 2018-03-22 10:51:24.308822 [DEBUG] mod_conference.c:220 Setup timer success interval: 20  samples: 960
  774. 2018-03-22 10:51:24.308822 [DEBUG] switch_core_codec.c:223 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Push codec L16:100
  775. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '0' to 'mute'
  776. 2018-03-22 10:51:24.368833 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
  777. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560855d70
  778. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '*' to 'deaf mute'
  779. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560855da0
  780. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '9' to 'energy up'
  781. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560855dd0
  782. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '8' to 'energy equ'
  783. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560855e00
  784. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '7' to 'energy dn'
  785. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560855e30
  786. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '3' to 'vol talk up'
  787. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560855e60
  788. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '2' to 'vol talk zero'
  789. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560899f28
  790. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '1' to 'vol talk dn'
  791. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560899f58
  792. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '6' to 'vol listen up'
  793. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560899f88
  794. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '5' to 'vol listen zero'
  795. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560899fb8
  796. 2018-03-22 10:51:24.368833 [DEBUG] conference_member.c:128 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com binding '4' to 'vol listen dn'
  797. 2018-03-22 10:51:24.368833 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f96195a2dd0 data: 0x7f9560899fe8
  798. 2018-03-22 10:51:24.368833 [DEBUG] switch_core_file.c:342 File /stretto/current/mediaprompts/MEDIA/crickets.wav sample rate 8000 doesn't match requested rate 48000
  799. 2018-03-22 10:51:24.378778 [WARNING] switch_core_file.c:360 File has 1 channels, muxing to 2 channels will occur.
  800. 2018-03-22 10:51:24.378778 [DEBUG] conference_loop.c:1142 Setup timer soft success interval: 20  samples: 960 from codec opus
  801. 2018-03-22 10:51:24.378778 [INFO] avcodec.c:1083 initializing encoder 352x288
  802. 2018-03-22 10:51:24.378778 [DEBUG] avcodec.c:832 NVENC HW CODEC NOT PRESENT
  803. using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2
  804. profile Constrained Baseline, level 3.1
  805. tport.c:2784 tport_wakeup() tport_wakeup(0x7f95dc02e120): events IN
  806. tport.c:2875 tport_recv_event() tport_recv_event(0x7f95dc02e120)
  807. tport_type_tls.c:434 tport_tls_recv() tport_tls_recv(0x7f95dc02e120): tls_read() returned 585
  808. tport.c:3216 tport_recv_iovec() tport_recv_iovec(0x7f95dc02e120) msg 0x7f95dc035c80 from (tls/24.114.108.163:57304) has 585 bytes, veclen = 1
  809. recv 585 bytes from tls/[24.114.108.163]:57304 at 10:51:24.414698:
  810.    ------------------------------------------------------------------------
  811.    ACK sip:5000@216.93.246.114:5061;transport=tls SIP/2.0
  812.    Via: SIP/2.0/TLS 192.0.0.4:53696;branch=z9hG4bK-524287-1---c9ea5506370346c0;rport
  813.    Max-Forwards: 70
  814.    Contact: <sip:guest_JamieStracha_3486d97@192.0.0.4:53696;ob;transport=tls>;+sip.instance="<urn:uuid:3a361d20-76c8-5514-b4b9-8b1f7b473efd>"
  815.    To: <sip:5000@meetings6.softphone.com>;tag=jQ00KKeU0Nyjg
  816.    From: <sip:guest_JamieStracha_3486d97@meetings6.softphone.com>;tag=4fddd918
  817.    Call-ID: 151911_rel67NDUwNDIwMTIzNGFiYTY0ZmNjZjc0ZDQzMzVhZTFjM2Y
  818.    CSeq: 2 ACK
  819.    User-Agent: Bria Stretto Android 5.2.2 build 103528
  820.    Content-Length: 0
  821.    
  822.    ------------------------------------------------------------------------
  823. tport.c:3034 tport_deliver() tport_deliver(0x7f95dc02e120): msg 0x7f95dc035c80 (585 bytes) from tls/24.114.108.163:57304/sips next=(nil)
  824. nta.c:2880 agent_recv_request() nta: received ACK sip:5000@216.93.246.114:5061;transport=tls SIP/2.0 (CSeq 2)
  825. nta.c:3174 agent_check_request_via() nta: Via check: received=24.114.108.163
  826. nta.c:3019 agent_recv_request() nta: ACK (2) is going to INVITE (2)
  827. nua_session.c:2569 process_ack_or_cancel() nua: process_ack_or_cancel: entering
  828. soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7f95dc0216b0) called
  829. nua_stack.c:271 nua_stack_event() nua(0x7f95dc021300): event i_ack 200 OK
  830. nua_session.c:4139 signal_call_state_change() nua(0x7f95dc021300): call state changed: completed -> ready
  831. nua_stack.c:271 nua_stack_event() nua(0x7f95dc021300): event i_state 200 OK
  832. nua_stack.c:271 nua_stack_event() nua(0x7f95dc021300): event i_active 200 Call active
  833. nta.c:5744 incoming_free() nta: incoming_free(0x7f95dc020620)
  834. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  835. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  836. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  837. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  838. 2018-03-22 10:51:24.408777 [DEBUG] avcodec.c:1091 picture size changed from 352x288 to 1920x1080, reinitializing encoder
  839. frame I:1     Avg QP: 0.00  size:  1151
  840. mb I  I16..4: 99.0%  0.0%  1.0%
  841. coded y,uvDC,uvAC intra: 0.3% 0.0% 0.0%
  842. i16 v,h,dc,p: 79%  0% 21%  0%
  843. i4 v,h,dc,ddl,ddr,vr,hd,vl,hu:  0%  0% 100%  0%  0%  0%  0%  0%  0%
  844. i8c dc,h,v,p: 100%  0%  0%  0%
  845. kb/s:828.72
  846. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  847. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  848. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  849. 2018-03-22 10:51:24.418854 [DEBUG] sofia.c:7084 Channel sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com entering state [ready][200]
  850. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  851. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  852. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  853. 2018-03-22 10:51:24.418854 [DEBUG] switch_ivr.c:623 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Command Execute playback(tone_stream://%(100,3000,600))
  854. using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2
  855. profile Constrained Baseline, level 3.1
  856. EXECUTE sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com playback(tone_stream://%(100,3000,600))
  857. 2018-03-22 10:51:24.468773 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@48000hz 1 channels 20ms
  858. 2018-03-22 10:51:24.468773 [INFO] switch_rtp.c:6737 Auto Changing audio RTCP port from 192.0.0.4:53201 to 24.114.108.163:57331
  859. 2018-03-22 10:51:24.468773 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 24.114.108.163:57331 2
  860. 2018-03-22 10:51:24.508869 [INFO] switch_rtp.c:6737 Auto Changing video RTCP port from 192.0.0.4:64247 to 24.114.108.163:57290
  861. 2018-03-22 10:51:24.508869 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 24.114.108.163:57290 2
  862. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc02e120): reset timer
  863. nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7f961a882c60)
  864. nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 0/0 term, 1/1 free
  865. nta.c:1289 agent_timer() nta: timer not set
  866. 2018-03-22 10:51:24.838870 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: audio ssrc[1045987720] base_seq[32317]
  867. 2018-03-22 10:51:25.038768 [INFO] switch_rtp.c:7268 Auto Changing audio port from 192.0.0.4:53200 to 24.114.108.163:57313
  868. 2018-03-22 10:51:27.538870 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://%(100,3000,600)
  869. 2018-03-22 10:51:27.578900 [DEBUG] switch_ivr.c:623 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Command Execute playback(/stretto/current/mediaprompts//CONFERENCE/en_US/onlyparticipant.wav)
  870. EXECUTE sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com playback(/stretto/current/mediaprompts//CONFERENCE/en_US/onlyparticipant.wav)
  871. 2018-03-22 10:51:27.588779 [DEBUG] switch_core_file.c:342 File /stretto/current/mediaprompts//CONFERENCE/en_US/onlyparticipant.wav sample rate 8000 doesn't match requested rate 48000
  872. 2018-03-22 10:51:27.588779 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@48000hz 1 channels 20ms
  873. 2018-03-22 10:51:29.518876 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: video ssrc[2603796608] base_seq[15540]
  874. freeswitch@bstnma-freeswitch6> uuid_kill
  875.  
  876. [6fa735f3-f396-4893-bce4-9777442cce3b] 
  877.  
  878.  
  879. 2018-03-22 10:51:30.398779 [DEBUG] switch_ivr_play_say.c:1942 done playing file /stretto/current/mediaprompts//CONFERENCE/en_US/onlyparticipant.wav
  880. freeswitch@bstnma-freeswitch6> uuid_kill 6fa735f3-f396-4893-bce4-9777442cce3b
  881. +OK
  882.  
  883. 2018-03-22 10:51:31.178858 [NOTICE] switch_ivr.c:4269 Hangup sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com [CS_EXECUTE] [NORMAL_CLEARING]
  884. 2018-03-22 10:51:31.178858 [INFO] conference_loop.c:1471 Channel leaving conference, cause: NORMAL_CLEARING
  885. 2018-03-22 10:51:31.178858 [DEBUG] mod_conference.c:2247 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com skip receive message [UNBRIDGE] (channel is hungup already)
  886. 2018-03-22 10:51:31.178858 [DEBUG] switch_core_media.c:5970 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Video thread ended
  887. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_media.c:9780 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com skip receive message [HARD_MUTE] (channel is hungup already)
  888. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_codec.c:248 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Restore previous codec opus:116.
  889. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_session.c:2815 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  890. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State EXECUTE going to sleep
  891. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_HANGUP (Cur 1 Tot 3)
  892. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Callstate Change ACTIVE -> HANGUP
  893. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State HANGUP
  894. 2018-03-22 10:51:31.188781 [DEBUG] mod_sofia.c:438 Channel sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com hanging up, cause: NORMAL_CLEARING
  895. 2018-03-22 10:51:31.188781 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com
  896. nua.c:645 nua_bye() nua: nua_bye: entering
  897. nua_stack.c:529 nua_signal() nua(0x7f95dc021300): sent signal r_bye
  898. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_state_machine.c:60 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard HANGUP, cause: NORMAL_CLEARING
  899. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State HANGUP going to sleep
  900. nua_stack.c:569 nua_stack_signal() nua(0x7f95dc021300): recv signal r_bye
  901. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  902. soa.c:403 soa_set_params() soa_set_params(static::0x7f95dc0216b0, ...) called
  903. soa.c:1784 soa_terminate() soa_terminate(static::0x7f95dc0216b0) called
  904. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f95dc0216b0) called
  905. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State Change CS_HANGUP -> CS_REPORTING
  906. nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
  907. tport.c:3268 tport_tsend() tport_tsend(0x7f95dc004f40) XXXXX tpn = tls/192.0.0.4:53696
  908. tport.c:4057 tport_resolve() tport_resolve addrinfo = 192.0.0.4:53696
  909. tport.c:4691 tport_by_addrinfo() tport_by_addrinfo(0x7f95dc004f40): not found by name tls/192.0.0.4:53696
  910. tport.c:862 tport_alloc_secondary() tport_alloc_secondary(0x7f95dc004f40): new secondary tport 0x7f95dc030270
  911. tport_type_tcp.c:203 tport_tcp_init_secondary() tport_tcp_init_secondary(0x7f95dc030270): Setting TCP_KEEPIDLE to 30
  912. tport_type_tcp.c:209 tport_tcp_init_secondary() tport_tcp_init_secondary(0x7f95dc030270): Setting TCP_KEEPINTVL to 30
  913. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_REPORTING (Cur 1 Tot 3)
  914. 2018-03-22 10:51:31.188781 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State REPORTING
  915. tport_type_tls.c:683 tport_tls_connect() tport_tls_connect(0x7f95dc030270): connecting to tls/192.0.0.4:53696/sips
  916. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc030270): reset timer
  917. 2018-03-22 10:51:31.198773 [DEBUG] switch_core_state_machine.c:174 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard REPORTING, cause: NORMAL_CLEARING
  918. tport.c:3793 tport_queue() tport_queue(0x7f95dc030270): queueing 0x7f95dc03e9d0 for tls/192.0.0.4:53696
  919. 2018-03-22 10:51:31.198773 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State REPORTING going to sleep
  920. nta.c:8304 outgoing_send() nta: sent BYE (120529081) to tls/192.0.0.4:53696
  921. tport.c:4171 tport_pend() tport_pend(0x7f95dc030270): pending 0x7f95dc03e9d0 for tls/192.0.0.4:53696 (already 0)
  922. nta.c:1350 set_timeout() nta: timer set to 32000 ms
  923. 2018-03-22 10:51:31.198773 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State Change CS_REPORTING -> CS_DESTROY
  924. 2018-03-22 10:51:31.198773 [DEBUG] switch_core_session.c:1665 Session 3 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Locked, Waiting on external entities
  925. 2018-03-22 10:51:31.198773 [NOTICE] switch_core_session.c:1683 Session 3 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Ended
  926. 2018-03-22 10:51:31.198773 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com [CS_DESTROY]
  927. 2018-03-22 10:51:31.198773 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) Running State Change CS_DESTROY (Cur 0 Tot 3)
  928. 2018-03-22 10:51:31.198773 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State DESTROY
  929. 2018-03-22 10:51:31.198773 [DEBUG] mod_sofia.c:343 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com SOFIA DESTROY
  930. 2018-03-22 10:51:31.198773 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[214] PLC[0] FEC[0]
  931. 2018-03-22 10:51:31.198773 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0]
  932. 2018-03-22 10:51:31.198773 [DEBUG] mod_opus.c:711 Opus decoder stats: Frames[0] PLC[0] FEC[0]
  933. 2018-03-22 10:51:31.198773 [DEBUG] mod_opus.c:726 Opus encoder stats: Frames[342] Bytes encoded[31418] Encoded length ms[6840] Average encoded bitrate bps[41890]
  934. frame I:10    Avg QP: 4.22  size: 17969
  935. frame P:195   Avg QP: 1.01  size:   243
  936. mb I  I16..4: 97.5%  0.0%  2.5%
  937. mb P  I16..4:  0.0%  0.0%  0.0%  P16..4:  0.2%  0.0%  0.0%  0.0%  0.0%    skip:99.8%
  938. coded y,uvDC,uvAC intra: 1.3% 1.1% 0.8% inter: 0.1% 0.0% 0.0%
  939. i16 v,h,dc,p: 79%  2% 20%  0%
  940. i4 v,h,dc,ddl,ddr,vr,hd,vl,hu: 28% 10% 52%  2%  2%  1%  1%  1%  2%
  941. i8c dc,h,v,p: 99%  0%  0%  0%
  942. kb/s:797.83
  943. 2018-03-22 10:51:31.198773 [DEBUG] switch_core_state_machine.c:181 sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com Standard DESTROY
  944. 2018-03-22 10:51:31.198773 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/guest_JamieStracha_3486d97@meetings6.softphone.com) State DESTROY going to sleep
  945. 2018-03-22 10:51:31.208800 [DEBUG] mod_conference.c:788 Write Lock ON
  946. 2018-03-22 10:51:31.208800 [DEBUG] mod_conference.c:791 Write Lock OFF
  947. tport_tls.c:956 tls_connect() tls_connect(0x7f95dc030270): events CONNECTING ERROR HANGUP
  948. tport.c:4233 tport_release() tport_release(0x7f95dc030270): 0x7f95dc03e9d0 by 0x7f95dc022950 with (nil)
  949. nta.c:8496 outgoing_print_tport_error() nta: BYE (120529081): Network is unreachable (101) with tls/[192.0.0.4]:53696
  950. nua_stack.c:271 nua_stack_event() nua(0x7f95dc021300): event r_bye 503 Service Unavailable
  951. nua_session.c:4139 signal_call_state_change() nua(0x7f95dc021300): call state changed: terminating -> terminated
  952. nua_stack.c:271 nua_stack_event() nua(0x7f95dc021300): event i_state 503 to BYE
  953. nua_stack.c:271 nua_stack_event() nua(0x7f95dc021300): event i_terminated 503 to BYE
  954. nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7f95dc021300): removing session usage
  955. soa.c:356 soa_destroy() soa_destroy(static::0x7f95dc0216b0) called
  956. nta.c:4470 nta_leg_destroy() nta_leg_destroy(0x7f95dc022e80)
  957. nua_session.c:351 nua_session_usage_destroy() nua: terminated session 0x7f95dc021300
  958. nta.c:8722 outgoing_free() nta: outgoing_free(0x7f95dc022950)
  959. tport.c:2307 tport_set_secondary_timer() tport(0x7f95dc030270): reset timer
  960. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  961. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  962. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  963. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  964. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  965. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  966. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  967. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
  968. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  969. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  970. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  971. nua_stack.c:529 nua_signal() nua(0x7f95dc021300): sent signal r_destroy
  972. tport.c:2101 tport_close() tport_close(0x7f95dc030270): tls/192.0.0.4:53696/sips
  973. tport_tls.c:157 tls_log_errors() tls_free: 140e0114:SSL routines:SSL_shutdown:uninitialized
  974. nua_stack.c:569 nua_stack_signal() nua(0x7f95dc021300): recv signal r_destroy
  975. nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
  976. freeswitch@bstnma-freeswitch6> /exit
  977.  

Reply to "RFC1918 setup"