From Jose Lopes, 4 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: cornflowerblue; font-weight: bold;"> 2017-04-28 08:58:05.101113 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /var/log/freeswitch/cdr-csv/1002.csv
  2. 2017-04-28 08:58:05.101113 [NOTICE] mod_logfile.c:192 New log started.
  3. recv 419 bytes from udp/[192.168.1.104]:5060 at 08:59:41.830596:
  4.    ------------------------------------------------------------------------
  5.    INVITE sip:1002@172.17.0.3:5080 SIP/2.0
  6.    Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK-1988-1-0;nosdp=true;out-of-dialog=true
  7.    From: sipp <sip:sipp@192.168.1.104:5060>;tag=1
  8.    To: <sip:1002@172.17.0.3:5080>
  9.    Call-ID: 1-1988@192.168.1.104
  10.    CSeq: 1 INVITE
  11.    Contact: <sip:sipp@192.168.1.104:5060;transport=udp>
  12.    Max-Forwards: 70
  13.    Subject: Performance Test
  14.    Session-Expires: 1800
  15.    Min-SE: 900
  16.    Content-Length:     0
  17.    
  18.    ------------------------------------------------------------------------
  19. send 317 bytes to udp/[192.168.1.104]:5060 at 08:59:41.830888:
  20.    ------------------------------------------------------------------------
  21.    SIP/2.0 100 Trying
  22.    Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK-1988-1-0;nosdp=true;out-of-dialog=true
  23.    From: sipp <sip:sipp@192.168.1.104:5060>;tag=1
  24.    To: <sip:1002@172.17.0.3:5080>
  25.    Call-ID: 1-1988@192.168.1.104
  26.    CSeq: 1 INVITE
  27.    User-Agent: FreeSWITCH-mod_sofia/1.6.17-34-0fc0946~64bit
  28.    Content-Length: 0
  29.    
  30.    ------------------------------------------------------------------------
  31. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [NOTICE] switch_channel.c:1104 New Channel sofia/external/sipp@192.168.1.104:5060 [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  32. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:584 (sofia/external/sipp@192.168.1.104:5060) Running State Change CS_NEW (Cur 1 Tot 9)
  33. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] sofia.c:9834 sofia/external/sipp@192.168.1.104:5060 receiving invite from 192.168.1.104:5060 version: 1.6.17 -34-0fc0946 64bit
  34. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] sofia.c:7048 Channel sofia/external/sipp@192.168.1.104:5060 entering state [received][100]
  35. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] sofia.c:7568 (sofia/external/sipp@192.168.1.104:5060) State Change CS_NEW -> CS_INIT
  36. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:603 (sofia/external/sipp@192.168.1.104:5060) State NEW
  37. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:584 (sofia/external/sipp@192.168.1.104:5060) Running State Change CS_INIT (Cur 1 Tot 9)
  38. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:627 (sofia/external/sipp@192.168.1.104:5060) State INIT
  39. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] mod_sofia.c:90 sofia/external/sipp@192.168.1.104:5060 SOFIA INIT
  40. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:40 sofia/external/sipp@192.168.1.104:5060 Standard INIT
  41. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:48 (sofia/external/sipp@192.168.1.104:5060) State Change CS_INIT -> CS_ROUTING
  42. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:627 (sofia/external/sipp@192.168.1.104:5060) State INIT going to sleep
  43. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:584 (sofia/external/sipp@192.168.1.104:5060) Running State Change CS_ROUTING (Cur 1 Tot 9)
  44. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_channel.c:2249 (sofia/external/sipp@192.168.1.104:5060) Callstate Change DOWN -> RINGING
  45. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:643 (sofia/external/sipp@192.168.1.104:5060) State ROUTING
  46. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] mod_sofia.c:143 sofia/external/sipp@192.168.1.104:5060 SOFIA ROUTING
  47. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:236 sofia/external/sipp@192.168.1.104:5060 Standard ROUTING
  48. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [INFO] mod_dialplan_xml.c:637 Processing sipp <sipp>->1002 in context public
  49. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [public->unloop] continue=false
  50. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  51. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  52. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [public->outside_call] continue=true
  53. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Absolute Condition [outside_call]
  54. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action set(outside_call=true)
  55. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  56. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [public->call_debug] continue=true
  57. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (PASS) [call_debug] ${call_debug}(true) =~ /^true$/ break=never
  58. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action info()
  59. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [public->public_extensions] continue=false
  60. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (PASS) [public_extensions] destination_number(1002) =~ /^(10[01][0-9])$/ break=on-false
  61. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action transfer(1002 XML default)
  62. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:286 (sofia/external/sipp@192.168.1.104:5060) State Change CS_ROUTING -> CS_EXECUTE
  63. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:643 (sofia/external/sipp@192.168.1.104:5060) State ROUTING going to sleep
  64. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:584 (sofia/external/sipp@192.168.1.104:5060) Running State Change CS_EXECUTE (Cur 1 Tot 9)
  65. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:650 (sofia/external/sipp@192.168.1.104:5060) State EXECUTE
  66. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] mod_sofia.c:198 sofia/external/sipp@192.168.1.104:5060 SOFIA EXECUTE
  67. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:328 sofia/external/sipp@192.168.1.104:5060 Standard EXECUTE
  68. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 set(outside_call=true)
  69. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] mod_dptools.c:1530 SET sofia/external/sipp@192.168.1.104:5060 [outside_call]=[true]
  70. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 export(RFC2822_DATE=Fri, 28 Apr 2017 08:59:41 +0000)
  71. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 28 Apr 2017 08:59:41 +0000]
  72. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 info()
  73. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [INFO] mod_dptools.c:1743 CHANNEL_DATA:
  74. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-State: [CS_EXECUTE]
  75. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-Call-State: [RINGING]
  76. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-State-Number: [4]
  77. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-Name: [sofia/external/sipp@192.168.1.104:5060]
  78. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Unique-ID: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  79. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Call-Direction: [inbound]
  80. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Presence-Call-Direction: [inbound]
  81. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-HIT-Dialplan: [true]
  82. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-Call-UUID: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  83. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Answer-State: [ringing]
  84. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Direction: [inbound]
  85. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Logical-Direction: [inbound]
  86. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Username: [sipp]
  87. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Dialplan: [XML]
  88. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Caller-ID-Name: [sipp]
  89. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Caller-ID-Number: [sipp]
  90. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Orig-Caller-ID-Name: [sipp]
  91. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Orig-Caller-ID-Number: [sipp]
  92. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Network-Addr: [192.168.1.104]
  93. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-ANI: [sipp]
  94. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Destination-Number: [1002]
  95. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Unique-ID: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  96. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Source: [mod_sofia]
  97. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Context: [public]
  98. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Name: [sofia/external/sipp@192.168.1.104:5060]
  99. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Profile-Index: [1]
  100. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Profile-Created-Time: [1493369981831115]
  101. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Created-Time: [1493369981831115]
  102. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Answered-Time: [0]
  103. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Progress-Time: [0]
  104. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Progress-Media-Time: [0]
  105. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Hangup-Time: [0]
  106. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Transfer-Time: [0]
  107. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Resurrect-Time: [0]
  108. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Bridged-Time: [0]
  109. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Last-Hold: [0]
  110. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Hold-Accum: [0]
  111. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Screen-Bit: [true]
  112. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Privacy-Hide-Name: [false]
  113. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Privacy-Hide-Number: [false]
  114. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_direction: [inbound]
  115. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_uuid: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  116. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_session_id: [9]
  117. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_user: [sipp]
  118. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_port: [5060]
  119. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_uri: [sipp@192.168.1.104:5060]
  120. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_host: [192.168.1.104]
  121. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_video_media_flow: [sendrecv]
  122. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_audio_media_flow: [sendrecv]
  123. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_channel_name: [sofia/external/sipp@192.168.1.104:5060]
  124. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_call_id: [1-1988@192.168.1.104]
  125. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_local_network_addr: [172.17.0.3]
  126. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_network_ip: [192.168.1.104]
  127. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_network_port: [5060]
  128. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_received_ip: [192.168.1.104]
  129. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_received_port: [5060]
  130. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_via_protocol: [udp]
  131. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_user_stripped: [sipp]
  132. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_tag: [1]
  133. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sofia_profile_name: [external]
  134. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_recovery_profile_name: [external]
  135. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_full_via: [SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK-1988-1-0;nosdp=true;out-of-dialog=true]
  136. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_display: [sipp]
  137. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_full_from: [sipp <sip:sipp@192.168.1.104:5060>;tag=1]
  138. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_full_to: [<sip:1002@172.17.0.3:5080>]
  139. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_req_user: [1002]
  140. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_req_port: [5080]
  141. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_req_uri: [1002@172.17.0.3:5080]
  142. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_req_host: [172.17.0.3]
  143. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_to_user: [1002]
  144. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_to_port: [5080]
  145. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_to_uri: [1002@172.17.0.3:5080]
  146. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_to_host: [172.17.0.3]
  147. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_params: [transport=udp]
  148. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_user: [sipp]
  149. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_port: [5060]
  150. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_uri: [sipp@192.168.1.104:5060]
  151. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_host: [192.168.1.104]
  152. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_rtp_use_codec_string: [OPUS,G722,PCMU,PCMA,VP8]
  153. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_subject: [Performance Test]
  154. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_via_host: [192.168.1.104]
  155. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_via_port: [5060]
  156. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_max_forwards: [70]
  157. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_endpoint_disposition: [RECEIVED_NOSDP]
  158. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_DP_MATCH: [ARRAY::1002|:1002]
  159. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_call_uuid: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  160. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_outside_call: [true]
  161. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_RFC2822_DATE: [Fri, 28 Apr 2017 08:59:41 +0000]
  162. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_export_vars: [RFC2822_DATE]
  163. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_current_application: [info]
  164. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2
  165. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2
  166. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 transfer(1002 XML default)
  167. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_ivr.c:2165 (sofia/external/sipp@192.168.1.104:5060) State Change CS_EXECUTE -> CS_ROUTING
  168. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/sipp@192.168.1.104:5060 to XML[1002@default]
  169. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:650 (sofia/external/sipp@192.168.1.104:5060) State EXECUTE going to sleep
  170. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:584 (sofia/external/sipp@192.168.1.104:5060) Running State Change CS_ROUTING (Cur 1 Tot 9)
  171. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:643 (sofia/external/sipp@192.168.1.104:5060) State ROUTING
  172. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] mod_sofia.c:143 sofia/external/sipp@192.168.1.104:5060 SOFIA ROUTING
  173. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:236 sofia/external/sipp@192.168.1.104:5060 Standard ROUTING
  174. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [INFO] mod_dialplan_xml.c:637 Processing sipp <sipp>->1002 in context default
  175. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->unloop] continue=false
  176. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  177. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  178. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->tod_example] continue=true
  179. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Date/TimeMatch (FAIL) [tod_example] break=on-false
  180. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->holiday_example] continue=true
  181. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  182. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->global-intercept] continue=false
  183. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [global-intercept] destination_number(1002) =~ /^886$/ break=on-false
  184. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->group-intercept] continue=false
  185. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [group-intercept] destination_number(1002) =~ /^\*8$/ break=on-false
  186. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->intercept-ext] continue=false
  187. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [intercept-ext] destination_number(1002) =~ /^\*\*(\d+)$/ break=on-false
  188. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->redial] continue=false
  189. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [redial] destination_number(1002) =~ /^(redial|870)$/ break=on-false
  190. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->global] continue=true
  191. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (PASS) [global] ${call_debug}(true) =~ /^true$/ break=never
  192. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action info()
  193. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [global] ${default_password}(12345) =~ /^1234$/ break=never
  194. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  195. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [global] ${endpoint_disposition}(RECEIVED_NOSDP) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  196. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->snom-demo-2] continue=false
  197. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [snom-demo-2] destination_number(1002) =~ /^9001$/ break=on-false
  198. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->snom-demo-1] continue=false
  199. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [snom-demo-1] destination_number(1002) =~ /^9000$/ break=on-false
  200. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->eavesdrop] continue=false
  201. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [eavesdrop] destination_number(1002) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  202. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->eavesdrop] continue=false
  203. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [eavesdrop] destination_number(1002) =~ /^779$/ break=on-false
  204. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->call_return] continue=false
  205. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [call_return] destination_number(1002) =~ /^\*69$|^869$|^lcr$/ break=on-false
  206. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->del-group] continue=false
  207. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [del-group] destination_number(1002) =~ /^80(\d{2})$/ break=on-false
  208. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->add-group] continue=false
  209. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [add-group] destination_number(1002) =~ /^81(\d{2})$/ break=on-false
  210. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->call-group-simo] continue=false
  211. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [call-group-simo] destination_number(1002) =~ /^82(\d{2})$/ break=on-false
  212. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->call-group-order] continue=false
  213. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [call-group-order] destination_number(1002) =~ /^83(\d{2})$/ break=on-false
  214. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->extension-intercom] continue=false
  215. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (FAIL) [extension-intercom] destination_number(1002) =~ /^8(10[01][0-9])$/ break=on-false
  216. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 parsing [default->Local_Extension] continue=false
  217. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Regex (PASS) [Local_Extension] destination_number(1002) =~ /^(10[01][0-9])$/ break=on-false
  218. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action export(dialed_extension=1002)
  219. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action bind_meta_app(1 b s execute_extension::dx XML features)
  220. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action bind_meta_app(2 b s record_session::/var/lib/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)
  221. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action bind_meta_app(3 b s execute_extension::cf XML features)
  222. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
  223. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action set(call_timeout=30)
  224. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action set(hangup_after_bridge=true)
  225. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action set(continue_on_fail=true)
  226. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  227. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  228. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
  229. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})
  230. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})
  231. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
  232. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action set(3pcc_always_gen_sdp=true)
  233. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action bridge(user/${dialed_extension}@${domain_name})
  234. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action answer()
  235. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action sleep(1000)
  236. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Dialplan: sofia/external/sipp@192.168.1.104:5060 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
  237. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:286 (sofia/external/sipp@192.168.1.104:5060) State Change CS_ROUTING -> CS_EXECUTE
  238. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:643 (sofia/external/sipp@192.168.1.104:5060) State ROUTING going to sleep
  239. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:584 (sofia/external/sipp@192.168.1.104:5060) Running State Change CS_EXECUTE (Cur 1 Tot 9)
  240. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:650 (sofia/external/sipp@192.168.1.104:5060) State EXECUTE
  241. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] mod_sofia.c:198 sofia/external/sipp@192.168.1.104:5060 SOFIA EXECUTE
  242. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_core_state_machine.c:328 sofia/external/sipp@192.168.1.104:5060 Standard EXECUTE
  243. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 info()
  244. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [INFO] mod_dptools.c:1743 CHANNEL_DATA:
  245. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-State: [CS_EXECUTE]
  246. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-Call-State: [RINGING]
  247. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-State-Number: [4]
  248. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-Name: [sofia/external/sipp@192.168.1.104:5060]
  249. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Unique-ID: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  250. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Call-Direction: [inbound]
  251. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Presence-Call-Direction: [inbound]
  252. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-HIT-Dialplan: [true]
  253. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Channel-Call-UUID: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  254. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Answer-State: [ringing]
  255. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Direction: [inbound]
  256. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Logical-Direction: [inbound]
  257. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Username: [sipp]
  258. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Dialplan: [XML]
  259. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Caller-ID-Name: [sipp]
  260. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Caller-ID-Number: [sipp]
  261. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Orig-Caller-ID-Name: [sipp]
  262. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Orig-Caller-ID-Number: [sipp]
  263. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Network-Addr: [192.168.1.104]
  264. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-ANI: [sipp]
  265. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Destination-Number: [1002]
  266. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Unique-ID: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  267. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Source: [mod_sofia]
  268. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Transfer-Source: [1493369981:858cf5c7-117b-4df9-972a-f4afdfecf711:bl_xfer:1002/default/XML]
  269. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Context: [default]
  270. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-RDNIS: [1002]
  271. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Name: [sofia/external/sipp@192.168.1.104:5060]
  272. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Profile-Index: [2]
  273. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Profile-Created-Time: [1493369981831115]
  274. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Created-Time: [1493369981831115]
  275. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Answered-Time: [0]
  276. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Progress-Time: [0]
  277. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Progress-Media-Time: [0]
  278. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Hangup-Time: [0]
  279. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Transfer-Time: [0]
  280. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Resurrect-Time: [0]
  281. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Bridged-Time: [0]
  282. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Last-Hold: [0]
  283. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Channel-Hold-Accum: [0]
  284. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Screen-Bit: [true]
  285. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Privacy-Hide-Name: [false]
  286. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 Caller-Privacy-Hide-Number: [false]
  287. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_direction: [inbound]
  288. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_uuid: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  289. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_session_id: [9]
  290. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_user: [sipp]
  291. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_port: [5060]
  292. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_uri: [sipp@192.168.1.104:5060]
  293. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_host: [192.168.1.104]
  294. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_video_media_flow: [sendrecv]
  295. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_audio_media_flow: [sendrecv]
  296. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_channel_name: [sofia/external/sipp@192.168.1.104:5060]
  297. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_call_id: [1-1988@192.168.1.104]
  298. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_local_network_addr: [172.17.0.3]
  299. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_network_ip: [192.168.1.104]
  300. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_network_port: [5060]
  301. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_received_ip: [192.168.1.104]
  302. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_received_port: [5060]
  303. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_via_protocol: [udp]
  304. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_user_stripped: [sipp]
  305. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_tag: [1]
  306. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sofia_profile_name: [external]
  307. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_recovery_profile_name: [external]
  308. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_full_via: [SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK-1988-1-0;nosdp=true;out-of-dialog=true]
  309. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_from_display: [sipp]
  310. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_full_from: [sipp <sip:sipp@192.168.1.104:5060>;tag=1]
  311. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_full_to: [<sip:1002@172.17.0.3:5080>]
  312. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_req_user: [1002]
  313. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_req_port: [5080]
  314. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_req_uri: [1002@172.17.0.3:5080]
  315. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_req_host: [172.17.0.3]
  316. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_to_user: [1002]
  317. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_to_port: [5080]
  318. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_to_uri: [1002@172.17.0.3:5080]
  319. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_to_host: [172.17.0.3]
  320. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_params: [transport=udp]
  321. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_user: [sipp]
  322. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_port: [5060]
  323. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_uri: [sipp@192.168.1.104:5060]
  324. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_contact_host: [192.168.1.104]
  325. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_rtp_use_codec_string: [OPUS,G722,PCMU,PCMA,VP8]
  326. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_subject: [Performance Test]
  327. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_via_host: [192.168.1.104]
  328. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_sip_via_port: [5060]
  329. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_endpoint_disposition: [RECEIVED_NOSDP]
  330. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_outside_call: [true]
  331. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_RFC2822_DATE: [Fri, 28 Apr 2017 08:59:41 +0000]
  332. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_export_vars: [RFC2822_DATE]
  333. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_max_forwards: [69]
  334. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_transfer_history: [1493369981:858cf5c7-117b-4df9-972a-f4afdfecf711:bl_xfer:1002/default/XML]
  335. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_transfer_source: [1493369981:858cf5c7-117b-4df9-972a-f4afdfecf711:bl_xfer:1002/default/XML]
  336. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_DP_MATCH: [ARRAY::1002|:1002]
  337. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_call_uuid: [c514aafe-c9de-45b1-897d-0ddd5fa3f4b2]
  338. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 variable_current_application: [info]
  339. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2
  340. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2
  341. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 export(dialed_extension=1002)
  342. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1002]
  343. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 bind_meta_app(1 b s execute_extension::dx XML features)
  344. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [INFO] switch_ivr_async.c:4171 Bound B-Leg: *1 execute_extension::dx XML features
  345. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 bind_meta_app(2 b s record_session::/var/lib/freeswitch/recordings/sipp.2017-04-28-08-59-41.wav)
  346. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [INFO] switch_ivr_async.c:4171 Bound B-Leg: *2 record_session::/var/lib/freeswitch/recordings/sipp.2017-04-28-08-59-41.wav
  347. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 bind_meta_app(3 b s execute_extension::cf XML features)
  348. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [INFO] switch_ivr_async.c:4171 Bound B-Leg: *3 execute_extension::cf XML features
  349. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 bind_meta_app(4 b s execute_extension::att_xfer XML features)
  350. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [INFO] switch_ivr_async.c:4171 Bound B-Leg: *4 execute_extension::att_xfer XML features
  351. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 set(call_timeout=30)
  352. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] mod_dptools.c:1530 SET sofia/external/sipp@192.168.1.104:5060 [call_timeout]=[30]
  353. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 set(hangup_after_bridge=true)
  354. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] mod_dptools.c:1530 SET sofia/external/sipp@192.168.1.104:5060 [hangup_after_bridge]=[true]
  355. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 set(continue_on_fail=true)
  356. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.831115 [DEBUG] mod_dptools.c:1530 SET sofia/external/sipp@192.168.1.104:5060 [continue_on_fail]=[true]
  357. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 hash(insert/172.17.0.3-call_return/1002/sipp)
  358. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 hash(insert/172.17.0.3-last_dial_ext/1002/c514aafe-c9de-45b1-897d-0ddd5fa3f4b2)
  359. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 set(called_party_callgroup=techsupport)
  360. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.841115 [DEBUG] mod_dptools.c:1530 SET sofia/external/sipp@192.168.1.104:5060 [called_party_callgroup]=[techsupport]
  361. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 hash(insert/172.17.0.3-last_dial_ext/techsupport/c514aafe-c9de-45b1-897d-0ddd5fa3f4b2)
  362. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 hash(insert/172.17.0.3-last_dial_ext/global/c514aafe-c9de-45b1-897d-0ddd5fa3f4b2)
  363. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 hash(insert/172.17.0.3-last_dial/techsupport/c514aafe-c9de-45b1-897d-0ddd5fa3f4b2)
  364. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 set(3pcc_always_gen_sdp=true)
  365. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.841115 [DEBUG] mod_dptools.c:1530 SET sofia/external/sipp@192.168.1.104:5060 [3pcc_always_gen_sdp]=[true]
  366. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 EXECUTE sofia/external/sipp@192.168.1.104:5060 bridge(user/1002@172.17.0.3)
  367. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.841115 [DEBUG] switch_channel.c:1250 sofia/external/sipp@192.168.1.104:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 28 Apr 2017 08:59:41 +0000] to event
  368. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.841115 [DEBUG] switch_channel.c:1250 sofia/external/sipp@192.168.1.104:5060 EXPORTING[export_vars] [dialed_extension]=[1002] to event
  369. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.841115 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  370. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.841115 [DEBUG] switch_channel.c:1250 sofia/external/sipp@192.168.1.104:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 28 Apr 2017 08:59:41 +0000] to event
  371. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.841115 [DEBUG] switch_channel.c:1250 sofia/external/sipp@192.168.1.104:5060 EXPORTING[export_vars] [dialed_extension]=[1002] to event
  372. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.841115 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  373. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1002@172.17.0.1:15060 [a39555b8-b95f-479f-b480-4601fd615f24]
  374. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] mod_sofia.c:4818 (sofia/internal/1002@172.17.0.1:15060) State Change CS_NEW -> CS_INIT
  375. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@172.17.0.1:15060) Running State Change CS_INIT (Cur 2 Tot 10)
  376. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:41.841115 [NOTICE] switch_ivr_originate.c:2845 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  377. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@172.17.0.1:15060) State INIT
  378. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] mod_sofia.c:90 sofia/internal/1002@172.17.0.1:15060 SOFIA INIT
  379. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] sofia_glue.c:1295 sofia/internal/1002@172.17.0.1:15060 sending invite version: 1.6.17 -34-0fc0946 64bit
  380. a39555b8-b95f-479f-b480-4601fd615f24 Local SDP:
  381. a39555b8-b95f-479f-b480-4601fd615f24 v=0
  382. a39555b8-b95f-479f-b480-4601fd615f24 o=FreeSWITCH 1493337485 1493337486 IN IP4 172.17.0.3
  383. a39555b8-b95f-479f-b480-4601fd615f24 s=FreeSWITCH
  384. a39555b8-b95f-479f-b480-4601fd615f24 c=IN IP4 172.17.0.3
  385. a39555b8-b95f-479f-b480-4601fd615f24 t=0 0
  386. a39555b8-b95f-479f-b480-4601fd615f24 m=audio 32496 RTP/AVP 102 9 0 8 104 101
  387. a39555b8-b95f-479f-b480-4601fd615f24 a=rtpmap:102 opus/48000/2
  388. a39555b8-b95f-479f-b480-4601fd615f24 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  389. a39555b8-b95f-479f-b480-4601fd615f24 a=rtpmap:9 G722/8000
  390. a39555b8-b95f-479f-b480-4601fd615f24 a=rtpmap:0 PCMU/8000
  391. a39555b8-b95f-479f-b480-4601fd615f24 a=rtpmap:8 PCMA/8000
  392. a39555b8-b95f-479f-b480-4601fd615f24 a=rtpmap:104 telephone-event/48000
  393. a39555b8-b95f-479f-b480-4601fd615f24 a=fmtp:104 0-16
  394. a39555b8-b95f-479f-b480-4601fd615f24 a=rtpmap:101 telephone-event/8000
  395. a39555b8-b95f-479f-b480-4601fd615f24 a=fmtp:101 0-16
  396. a39555b8-b95f-479f-b480-4601fd615f24 a=ptime:20
  397. a39555b8-b95f-479f-b480-4601fd615f24 a=sendrecv
  398. a39555b8-b95f-479f-b480-4601fd615f24 m=video 18064 RTP/AVP 103
  399. a39555b8-b95f-479f-b480-4601fd615f24 b=AS:1024
  400. a39555b8-b95f-479f-b480-4601fd615f24 a=rtpmap:103 VP8/90000
  401. a39555b8-b95f-479f-b480-4601fd615f24 a=rtcp-fb:103 ccm fir
  402. a39555b8-b95f-479f-b480-4601fd615f24 a=rtcp-fb:103 ccm tmmbr
  403. a39555b8-b95f-479f-b480-4601fd615f24 a=rtcp-fb:103 nack
  404. a39555b8-b95f-479f-b480-4601fd615f24 a=rtcp-fb:103 nack pli
  405. a39555b8-b95f-479f-b480-4601fd615f24
  406. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1002@172.17.0.1:15060 Standard INIT
  407. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1002@172.17.0.1:15060) State Change CS_INIT -> CS_ROUTING
  408. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@172.17.0.1:15060) State INIT going to sleep
  409. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@172.17.0.1:15060) Running State Change CS_ROUTING (Cur 2 Tot 10)
  410. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@172.17.0.1:15060) State ROUTING
  411. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] mod_sofia.c:143 sofia/internal/1002@172.17.0.1:15060 SOFIA ROUTING
  412. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1002@172.17.0.1:15060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  413. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@172.17.0.1:15060) State ROUTING going to sleep
  414. send 1549 bytes to udp/[172.17.0.1]:15060 at 08:59:41.845256:
  415.    ------------------------------------------------------------------------
  416.    INVITE sip:1002@172.17.0.1:15060;transport=udp SIP/2.0
  417.    Via: SIP/2.0/UDP 172.17.0.3;rport;branch=z9hG4bKKtB7Dgr6DtZaN
  418.    Max-Forwards: 68
  419.    From: "sipp" <sip:sipp@172.17.0.3>;tag=Q2gmU46vre09H
  420.    To: <sip:1002@172.17.0.1:15060;transport=udp>
  421.    Call-ID: dbbbf1c8-a693-1235-0e9b-0242ac110003
  422.    CSeq: 106348926 INVITE
  423.    Contact: <sip:mod_sofia@172.17.0.3:5060>
  424.    User-Agent: FreeSWITCH-mod_sofia/1.6.17-34-0fc0946~64bit
  425.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  426.    Supported: timer, path, replaces
  427.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  428.    Content-Type: application/sdp
  429.   Content-Disposition: sessiona39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@172.17.0.1:15060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 10)
  430.  
  431.    Content-Length: 617
  432.    X-FS-Support: update_display,send_info
  433.    Remote-Party-ID: "sipp" <sip:sipp@172.17.0.3>;party=calling;screen=yes;privacy=off
  434.    
  435.    v=0
  436.    o=FreeSWITCH 1493337485 1493337486 IN IP4 172.17.0.3
  437.    s=FreeSWITCH
  438.    c=IN IP4 172.17.0.3
  439.    t=0 0
  440.    m=audio 32496 RTP/AVP 102 9 0 8 104 101
  441.    a=rtpmap:102 opus/48000/2
  442.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  443.    a=rtpmap:9 G722/8000
  444.    a=rtpmap:0 PCMU/8000
  445.    a=rtpmap:8 PCMA/8000
  446.    a=rtpmap:104 telephone-event/48000
  447.    a=fmtp:104 0-16
  448.    a=rtpmap:101 telephone-event/8000
  449.    a=fmtp:101 0-16
  450.    a=ptime:20
  451.    m=video 18064 RTP/AVP 103
  452.    b=AS:1024
  453.    a=rtpmap:103 VP8/90000
  454.    a=rtcp-fb:103 ccm fir
  455.    a=rtcp-fb:103 ccm tmmbr
  456.    a=rtcp-fb:103 nack
  457.    a=rtcp-fb:103 nack pli
  458.    ------------------------------------------------------------------------
  459. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] sofia.c:7048 Channel sofia/internal/1002@172.17.0.1:15060 entering state [calling][0]
  460. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1002@172.17.0.1:15060) State CONSUME_MEDIA
  461. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.841115 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1002@172.17.0.1:15060) State CONSUME_MEDIA going to sleep
  462. recv 257 bytes from udp/[172.17.0.1]:15060 at 08:59:41.866749:
  463.    ------------------------------------------------------------------------
  464.    SIP/2.0 100 Trying
  465.    Via: SIP/2.0/UDP 172.17.0.3;rport;branch=z9hG4bKKtB7Dgr6DtZaN
  466.    From: "sipp" <sip:sipp@172.17.0.3>;tag=Q2gmU46vre09H
  467.    To: <sip:1002@172.17.0.1:15060;transport=udp>
  468.    Call-ID: dbbbf1c8-a693-1235-0e9b-0242ac110003
  469.    CSeq: 106348926 INVITE
  470.    
  471.    ------------------------------------------------------------------------
  472. recv 348 bytes from udp/[172.17.0.1]:15060 at 08:59:41.883124:
  473.    ------------------------------------------------------------------------
  474.    SIP/2.0 180 Ringing
  475.    Via: SIP/2.0/UDP 172.17.0.3;rport;branch=z9hG4bKKtB7Dgr6DtZaN
  476.    From: "sipp" <sip:sipp@172.17.0.3>;tag=Q2gmU46vre09H
  477.    To: <sip:1002@172.17.0.1:15060;transport=udp>;tag=p4j0hb7
  478.    Call-ID: dbbbf1c8-a693-1235-0e9b-0242ac110003
  479.    CSeq: 106348926 INVITE
  480.    User-Agent: Linphone/3.11.1 (belle-sip/1.6.1)
  481.    Supported: replaces, outbound
  482.    
  483.    ------------------------------------------------------------------------
  484. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:41.881123 [DEBUG] sofia.c:7048 Channel sofia/internal/1002@172.17.0.1:15060 entering state [proceeding][180]
  485. recv 905 bytes from udp/[172.17.0.1]:15060 at 08:59:50.246067:
  486.    ------------------------------------------------------------------------
  487.    SIP/2.0 200 Ok
  488.    Via: SIP/2.0/UDP 172.17.0.3;rport;branch=z9hG4bKKtB7Dgr6DtZaN
  489.    From: "sipp" <sip:sipp@172.17.0.3>;tag=Q2gmU46vre09H
  490.    To: <sip:1002@172.17.0.1:15060;transport=udp>;tag=p4j0hb7
  491.    Call-ID: dbbbf1c8-a693-1235-0e9b-0242ac110003
  492.    CSeq: 106348926 INVITE
  493.    User-Agent: Linphone/3.11.1 (belle-sip/1.6.1)
  494.    Supported: replaces, outbound
  495.   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
  496.    Contact: <sip:1002@172.17.0.1:15060;transport=udp>;+sip.instance="<urn:uuid:7762182d-541d-4148-a234-ca1f236a6606>"
  497.    Content-Type: application/sdp
  498.    Content-Length: 303
  499.    
  500.    v=0
  501.    o=1002 701 51 IN IP4 172.17.0.1
  502.    s=Talk
  503.    c=IN IP4 172.17.0.1
  504.    t=0 0
  505.    m=audio 7078 RTP/AVP 0 8 101
  506.    a=rtpmap:101 telephone-event/8000
  507.    m=video 9076 RTP/AVP 103
  508.    a=rtpmap:103 VP8/90000
  509.    a=rtcp-fb:* trr-int 5000
  510.    a=rtcp-fb:* nack
  511.    a=rtcp-fb:* ccm tmmbr
  512.    a=rtcp-fb:103 nack pli
  513.    a=rtcp-fb:103 ccm fir
  514.    ------------------------------------------------------------------------
  515. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.241114 [DEBUG] sofia.c:7048 Channel sofia/internal/1002@172.17.0.1:15060 entering state [completing][200]
  516. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.241114 [DEBUG] sofia.c:7058 Remote SDP:
  517. a39555b8-b95f-479f-b480-4601fd615f24 v=0
  518. a39555b8-b95f-479f-b480-4601fd615f24 o=1002 701 51 IN IP4 172.17.0.1
  519. a39555b8-b95f-479f-b480-4601fd615f24 s=Talk
  520. a39555b8-b95f-479f-b480-4601fd615f24 c=IN IP4 172.17.0.1
  521. a39555b8-b95f-479f-b480-4601fd615f24 t=0 0
  522. a39555b8-b95f-479f-b480-4601fd615f24 m=audio 7078 RTP/AVP 0 8 101
  523. a39555b8-b95f-479f-b480-4601fd615f24 a=rtpmap:101 telephone-event/8000
  524. a39555b8-b95f-479f-b480-4601fd615f24 m=video 9076 RTP/AVP 103
  525. a39555b8-b95f-479f-b480-4601fd615f24 a=rtpmap:103 VP8/90000
  526. a39555b8-b95f-479f-b480-4601fd615f24 a=rtcp-fb:* trr-int 5000
  527. a39555b8-b95f-479f-b480-4601fd615f24 a=rtcp-fb:* nack
  528. a39555b8-b95f-479f-b480-4601fd615f24 a=rtcp-fb:* ccm tmmbr
  529. a39555b8-b95f-479f-b480-4601fd615f24 a=rtcp-fb:103 nack pli
  530. a39555b8-b95f-479f-b480-4601fd615f24 a=rtcp-fb:103 ccm fir
  531. a39555b8-b95f-479f-b480-4601fd615f24
  532. send 378 bytes to udp/[172.17.0.1]:15060 at 08:59:50.249818:
  533.    ------------------------------------------------------------------------
  534.    ACK sip:1002@172.17.0.1:15060;transport=udp SIP/2.0
  535.    Via: SIP/2.0/UDP 172.17.0.3;rport;branch=z9hG4bKm34ZFB99a3NXg
  536.    Max-Forwards: 70
  537.    From: "sipp" <sip:sipp@172.17.0.3>;tag=Q2gmU46vre09H
  538.    To: <sip:1002@172.17.0.1:15060;transport=udp>;tag=p4j0hb7
  539.    Call-ID: dbbbf1c8-a693-1235-0e9b-0242ac110003
  540.    CSeq: 106348926 ACK
  541.    Contact: <sip:mod_sofia@172.17.0.3:5060>
  542.    Content-Length: 0
  543.    
  544.    ------------------------------------------------------------------------
  545. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] sofia.c:7048 Channel sofia/internal/1002@172.17.0.1:15060 entering state [ready][200]
  546. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  547. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  548. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  549. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  550. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  551. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  552. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  553. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  554. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  555. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  556. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 101@8000
  557. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/1002@172.17.0.1:15060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  558. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_codec.c:111 sofia/internal/1002@172.17.0.1:15060 Original read codec set to PCMU:0
  559. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 101@8000
  560. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4754 sofia/internal/1002@172.17.0.1:15060 Set 2833 dtmf send payload to 101 recv payload to 101
  561. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4937 Video Codec Compare [VP8:103]/[VP8:99]
  562. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:4969 Video Codec Compare [VP8:103] +++ is saved as a match
  563. 2017-04-28 08:59:50.251119 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  564. 2017-04-28 08:59:50.251119 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  565. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:2866 Set VIDEO Codec sofia/internal/1002@172.17.0.1:15060 VP8/90000 0 ms
  566. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:6865 AUDIO RTP [sofia/internal/1002@172.17.0.1:15060] 172.17.0.3 port 32496 -> 172.17.0.1 port 7078 codec: 0 ms: 20
  567. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms
  568. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:7166 sofia/internal/1002@172.17.0.1:15060 Set 2833 dtmf send payload to 101
  569. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:7173 sofia/internal/1002@172.17.0.1:15060 Set 2833 dtmf receive payload to 101
  570. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:7196 sofia/internal/1002@172.17.0.1:15060 Set rtp dtmf delay to 40
  571. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_rtp.c:4107 Starting video timer.
  572. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:7385 VIDEO RTP [sofia/internal/1002@172.17.0.1:15060] 172.17.0.3:18064->172.17.0.1:9076 codec: 103 ms: 0 [SUCCESS]
  573. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:5990 sofia/internal/1002@172.17.0.1:15060 Starting Video thread
  574. 2017-04-28 08:59:50.251119 [DEBUG] switch_core_media.c:5856 sofia/internal/1002@172.17.0.1:15060 Video thread started. Echo is off
  575. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [INFO] switch_core_media.c:7465 Activating VIDEO RTCP PORT 9077 interval 1000 mux -1
  576. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_rtp.c:4488 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 9077
  577. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_rtp.c:2560 Setting RTCP remote addr to 172.17.0.1:9077 2
  578. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [NOTICE] sofia.c:8182 Channel [sofia/internal/1002@172.17.0.1:15060] has been answered
  579. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.251119 [DEBUG] switch_channel.c:3772 (sofia/internal/1002@172.17.0.1:15060) Callstate Change DOWN -> ACTIVE
  580. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.263332 [DEBUG] mod_sofia.c:790 3PCC-PROXY, Sent a 200 OK, waiting for ACK
  581. send 1284 bytes to udp/[192.168.1.104]:5060 at 08:59:50.269280:
  582.    ------------------------------------------------------------------------
  583.    SIP/2.0 200 OK
  584.    Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK-1988-1-0;nosdp=true;out-of-dialog=true
  585.    From: sipp <sip:sipp@192.168.1.104:5060>;tag=1
  586.    To: <sip:1002@172.17.0.3:5080>;tag=ND882pjeBXeQH
  587.    Call-ID: 1-1988@192.168.1.104
  588.    CSeq: 1 INVITE
  589.    Contact: <sip:mod_sofia@172.17.0.3:5080>
  590.    User-Agent: FreeSWITCH-mod_sofia/1.6.17-34-0fc0946~64bit
  591.    Accept: application/sdp
  592.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  593.    Supported: timer, path, replaces
  594.    Allow-Events: talk, hold, conference, refer
  595.    Session-Expires: 1800;refresher=uas
  596.    Content-Type: application/sdp
  597.    Content-Disposition: session
  598.    Content-Length: 617
  599.    
  600.    v=0
  601.    o=FreeSWITCH 1493349136 1493349137 IN IP4 172.17.0.3
  602.    s=FreeSWITCH
  603.    c=IN IP4 172.17.0.3
  604.    t=0 0
  605.    m=audio 20854 RTP/AVP 102 9 0 8 103 101
  606.    a=rtpmap:102 opus/48000/2
  607.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  608.    a=rtpmap:9 G722/8000
  609.    a=rtpmap:0 PCMU/8000
  610.    a=rtpmap:8 PCMA/8000
  611.    a=rtpmap:103 telephone-event/48000
  612.    a=fmtp:103 0-16
  613.    a=rtpmap:101 telephone-event/8000
  614.    a=fmtp:101 0-16
  615.    a=ptime:20
  616.    m=video 19190 RTP/AVP 103
  617.    b=AS:1024
  618.    a=rtpmap:103 VP8/90000
  619.    a=rtcp-fb:103 ccm fir
  620.    a=rtcp-fb:103 ccm tmmbr
  621.    a=rtcp-fb:103 nack
  622.    a=rtcp-fb:103 nack pli
  623.    ------------------------------------------------------------------------
  624. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.263332 [DEBUG] sofia.c:7048 Channel sofia/external/sipp@192.168.1.104:5060 entering state [completed][200]
  625. recv 572 bytes from udp/[192.168.1.104]:5060 at 08:59:50.274616:
  626.    ------------------------------------------------------------------------
  627.    ACK sip:mod_sofia@172.17.0.3:5080 SIP/2.0
  628.    Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK-1988-1-4
  629.    From: sipp <sip:sipp@192.168.1.104:5060>;tag=1
  630.    To: <sip:1002@172.17.0.3:5080>;tag=ND882pjeBXeQH
  631.    Call-ID: 1-1988@192.168.1.104
  632.    CSeq: 1 ACK
  633.    Contact: <sip:sipp@192.168.1.104:5060;transport=udp>
  634.    Max-Forwards: 70
  635.    Subject: Performance Test
  636.    Content-Type: application/sdp
  637.    Content-Length:   171
  638.    
  639.    v=0
  640.    o=ipjib 1492600869292 1492600869292 IN IP4 192.168.1.104
  641.    s=wms
  642.    c=IN IP4 192.168.1.104
  643.    t=0 0
  644.    m=audio 6000 RTP/AVP 0
  645.    a=rtpmap:0 PCMU/8000
  646.    a=ptime:20
  647.    a=sendrecv
  648.    ------------------------------------------------------------------------
  649. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] sofia.c:7048 Channel sofia/external/sipp@192.168.1.104:5060 entering state [ready][200]
  650. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] sofia.c:7058 Remote SDP:
  651. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 v=0
  652. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 o=ipjib 1492600869292 1492600869292 IN IP4 192.168.1.104
  653. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 s=wms
  654. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 c=IN IP4 192.168.1.104
  655. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 t=0 0
  656. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 m=audio 6000 RTP/AVP 0
  657. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 a=rtpmap:0 PCMU/8000
  658. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 a=ptime:20
  659. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2
  660. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  661. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  662. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  663. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  664. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  665. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:3056 Set Codec sofia/external/sipp@192.168.1.104:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  666. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_codec.c:111 sofia/external/sipp@192.168.1.104:5060 Original read codec set to PCMU:0
  667. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:4725 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  668. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:4754 sofia/external/sipp@192.168.1.104:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  669. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:6865 AUDIO RTP [sofia/external/sipp@192.168.1.104:5060] 172.17.0.3 port 20854 -> 192.168.1.104 port 6000 codec: 0 ms: 20
  670. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms
  671. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:7166 sofia/external/sipp@192.168.1.104:5060 Set 2833 dtmf send payload to 101
  672. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:7173 sofia/external/sipp@192.168.1.104:5060 Set 2833 dtmf receive payload to 101
  673. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_core_media.c:7196 sofia/external/sipp@192.168.1.104:5060 Set rtp dtmf delay to 40
  674. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [NOTICE] sofia.c:8182 Channel [sofia/external/sipp@192.168.1.104:5060] has been answered
  675. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_channel.c:3772 (sofia/external/sipp@192.168.1.104:5060) Callstate Change RINGING -> ACTIVE
  676. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] sofia.c:8191 3PCC-PROXY, Got my ACK
  677. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] mod_sofia.c:808 3PCC-PROXY, Done waiting for ACK
  678. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_ivr_originate.c:3690 Originate Resulted in Success: [sofia/internal/1002@172.17.0.1:15060]
  679. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.271103 [DEBUG] switch_ivr_originate.c:3690 Originate Resulted in Success: [sofia/internal/1002@172.17.0.1:15060]
  680. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.281108 [DEBUG] switch_ivr_bridge.c:1601 (sofia/internal/1002@172.17.0.1:15060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  681. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.281108 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@172.17.0.1:15060) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 10)
  682. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.281108 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1002@172.17.0.1:15060) State EXCHANGE_MEDIA
  683. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.281108 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  684. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.331105 [DEBUG] switch_rtp.c:7229 Correct audio ip/port confirmed.
  685. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.361105 [DEBUG] switch_rtp.c:6679 Correct video RTCP ip/port confirmed.
  686. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:50.371107 [NOTICE] switch_vpx.c:402 VPX reset encoder picture from 320x240 to 352x288 1024 BW
  687. recv 627 bytes from udp/[192.168.1.104]:5060 at 08:59:50.776894:
  688.    ------------------------------------------------------------------------
  689.    INVITE sip:mod_sofia@172.17.0.3:5080 SIP/2.0
  690.    Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK-1988-1-6
  691.    From: sipp <sip:sipp@192.168.1.104:5060>;tag=1
  692.    To: <sip:1002@172.17.0.3:5080>;tag=ND882pjeBXeQH
  693.    Call-ID: 1-1988@192.168.1.104
  694.    CSeq: 2 INVITE
  695.    Contact: <sip:sipp@192.168.1.104:5060;transport=udp>
  696.    Max-Forwards: 70
  697.    Subject: Performance Test
  698.    Session-Expires: 1800
  699.    Min-SE: 900
  700.    Content-Length:   215
  701.    
  702.    v=0
  703.    o=ipjib 1492600869292 1492600869294 IN IP4 192.168.1.104
  704.    s=Basic Session
  705.    c=IN IP4 192.168.1.104
  706.    t=0 0
  707.    m=audio 6000 RTP/AVP 0 8 18 99
  708.    a=rtpmap:99 telephone-event/8000
  709.    a=fmtp:99 0-15
  710.    a=fmtp:18 annexb=yes
  711.    ------------------------------------------------------------------------
  712. send 911 bytes to udp/[192.168.1.104]:5060 at 08:59:50.777648:
  713.    ------------------------------------------------------------------------
  714.    SIP/2.0 200 OK
  715.    Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK-1988-1-6
  716.    From: sipp <sip:sipp@192.168.1.104:5060>;tag=1
  717.    To: <sip:1002@172.17.0.3:5080>;tag=ND882pjeBXeQH
  718.    Call-ID: 1-1988@192.168.1.104
  719.    CSeq: 2 INVITE
  720.    Contact: <sip:mod_sofia@172.17.0.3:5080>
  721.    User-Agent: FreeSWITCH-mod_sofia/1.6.17-34-0fc0946~64bit
  722.    Accept: application/sdp
  723.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  724.    Supported: timer, path, replaces
  725.    Session-Expires: 1800;refresher=uas
  726.    Content-Type: application/sdp
  727.    Content-Disposition: session
  728.    Content-Length: 319
  729.    
  730.    v=0
  731.    o=FreeSWITCH 1493349136 1493349138 IN IP4 172.17.0.3
  732.    s=FreeSWITCH
  733.    c=IN IP4 172.17.0.3
  734.    t=0 0
  735.    m=audio 20854 RTP/AVP 0 8 103 101
  736.    a=rtpmap:0 PCMU/8000
  737.    a=rtpmap:8 PCMA/8000
  738.    a=rtpmap:103 telephone-event/48000
  739.    a=fmtp:103 0-16
  740.    a=rtpmap:101 telephone-event/8000
  741.    a=fmtp:101 0-16
  742.    a=ptime:20
  743.    m=video 0 RTP/AVP 19
  744.    ------------------------------------------------------------------------
  745. recv 406 bytes from udp/[192.168.1.104]:5060 at 08:59:50.778254:
  746.    ------------------------------------------------------------------------
  747.    ACK sip:mod_sofia@172.17.0.3:5080 SIP/2.0
  748.    Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK-1988-1-10
  749.    From: sipp <sip:sipp@192.168.1.104:5060>;tag=1
  750.    To: sut <sip:1002@172.17.0.3:5080>;tag=ND882pjeBXeQH
  751.    Call-ID: 1-1988@192.168.1.104
  752.    CSeq: 2 ACK
  753.    Contact: <sip:sipp@192.168.1.104:5060;transport=udp>
  754.    Max-Forwards: 70
  755.    Subject: Performance Test
  756.    Content-Type: application/sdp
  757.    Content-Length:     0
  758.    
  759.    ------------------------------------------------------------------------
  760. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] sofia.c:7048 Channel sofia/external/sipp@192.168.1.104:5060 entering state [completed][200]
  761. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] sofia.c:7058 Remote SDP:
  762. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 v=0
  763. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 o=ipjib 1492600869292 1492600869294 IN IP4 192.168.1.104
  764. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 s=Basic Session
  765. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 c=IN IP4 192.168.1.104
  766. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 t=0 0
  767. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 m=audio 6000 RTP/AVP 0 8 18 99
  768. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 a=fmtp:18 annexb=yes
  769. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 a=rtpmap:99 telephone-event/8000
  770. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 a=fmtp:99 0-15
  771. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2
  772. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  773. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  774. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  775. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  776. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  777. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  778. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  779. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  780. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  781. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  782. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  783. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  784. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  785. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  786. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 99@8000
  787. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 99@8000
  788. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:4754 sofia/external/sipp@192.168.1.104:5060 Set 2833 dtmf send payload to 99 recv payload to 99
  789. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] switch_core_media.c:6848 Audio params are unchanged for sofia/external/sipp@192.168.1.104:5060.
  790. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] sofia.c:7963 Processing updated SDP
  791. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:50.791167 [DEBUG] sofia.c:7048 Channel sofia/external/sipp@192.168.1.104:5060 entering state [ready][200]
  792. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:51.031123 [DEBUG] switch_rtp.c:1885 rtcp_stats_init: video ssrc[985159765] base_seq[0]
  793. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:51.081126 [DEBUG] switch_rtp.c:7229 Correct video ip/port confirmed.
  794. recv 331 bytes from udp/[172.17.0.1]:15060 at 08:59:56.693337:
  795.    ------------------------------------------------------------------------
  796.    BYE sip:mod_sofia@172.17.0.3:5060 SIP/2.0
  797.    Via: SIP/2.0/UDP 172.17.0.1:15060;branch=z9hG4bK.VTfhChwLa;rport
  798.    From: <sip:1002@172.17.0.1>;tag=p4j0hb7
  799.    To: "sipp" <sip:sipp@172.17.0.3>;tag=Q2gmU46vre09H
  800.    CSeq: 111 BYE
  801.    Call-ID: dbbbf1c8-a693-1235-0e9b-0242ac110003
  802.    Max-Forwards: 70
  803.    User-Agent: Linphone/3.11.1 (belle-sip/1.6.1)
  804.    
  805.    ------------------------------------------------------------------------
  806. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [NOTICE] sofia.c:1012 Hangup sofia/internal/1002@172.17.0.1:15060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  807. send 466 bytes to udp/[172.17.0.1]:15060 at 08:59:56.713853:
  808.    ------------------------------------------------------------------------
  809.    SIP/2.0 200 OK
  810.    Via: SIP/2.0/UDP 172.17.0.1:15060;branch=z9hG4bK.VTfhChwLa;rport=15060
  811.    From: <sip:1002@172.17.0.1>;tag=p4j0hb7
  812.    To: "sipp" <sip:sipp@172.17.0.3>;tag=Q2gmU46vre09H
  813.    Call-ID: dbbbf1c8-a693-1235-0e9b-0242ac110003
  814.    CSeq: 111 BYE
  815.    User-Agent: FreeSWITCH-mod_sofia/1.6.17-34-0fc0946~64bit
  816.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  817.    Supported: timer, path, replaces
  818.    Content-Length: 0
  819.    
  820.    ------------------------------------------------------------------------
  821. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/1002@172.17.0.1:15060]
  822. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_media.c:11605 sofia/internal/1002@172.17.0.1:15060 skip receive message [VIDEO_REFRESH_REQ] (channel is hungup already)
  823. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1002@172.17.0.1:15060) State EXCHANGE_MEDIA going to sleep
  824. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@172.17.0.1:15060) Running State Change CS_HANGUP (Cur 2 Tot 10)
  825. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1002@172.17.0.1:15060) Callstate Change ACTIVE -> HANGUP
  826. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1002@172.17.0.1:15060) State HANGUP
  827. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1002@172.17.0.1:15060 hanging up, cause: NORMAL_CLEARING
  828. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1002@172.17.0.1:15060 Standard HANGUP, cause: NORMAL_CLEARING
  829. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1002@172.17.0.1:15060) State HANGUP going to sleep
  830. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1002@172.17.0.1:15060) State Change CS_HANGUP -> CS_REPORTING
  831. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@172.17.0.1:15060) Running State Change CS_REPORTING (Cur 2 Tot 10)
  832. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1002@172.17.0.1:15060) State REPORTING
  833. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1002@172.17.0.1:15060 Standard REPORTING, cause: NORMAL_CLEARING
  834. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1002@172.17.0.1:15060) State REPORTING going to sleep
  835. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1002@172.17.0.1:15060) State Change CS_REPORTING -> CS_DESTROY
  836. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_session.c:1664 Session 10 (sofia/internal/1002@172.17.0.1:15060) Locked, Waiting on external entities
  837. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/external/sipp@192.168.1.104:5060]
  838. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [NOTICE] switch_ivr_bridge.c:1751 Hangup sofia/external/sipp@192.168.1.104:5060 [CS_EXECUTE] [NORMAL_CLEARING]
  839. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_session.c:2814 sofia/external/sipp@192.168.1.104:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  840. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:650 (sofia/external/sipp@192.168.1.104:5060) State EXECUTE going to sleep
  841. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:584 (sofia/external/sipp@192.168.1.104:5060) Running State Change CS_HANGUP (Cur 2 Tot 10)
  842. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:850 (sofia/external/sipp@192.168.1.104:5060) Callstate Change ACTIVE -> HANGUP
  843. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:852 (sofia/external/sipp@192.168.1.104:5060) State HANGUP
  844. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] mod_sofia.c:432 sofia/external/sipp@192.168.1.104:5060 Overriding SIP cause 480 with 200 from the other leg
  845. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] mod_sofia.c:438 Channel sofia/external/sipp@192.168.1.104:5060 hanging up, cause: NORMAL_CLEARING
  846. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/sipp@192.168.1.104:5060
  847. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:60 sofia/external/sipp@192.168.1.104:5060 Standard HANGUP, cause: NORMAL_CLEARING
  848. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:852 (sofia/external/sipp@192.168.1.104:5060) State HANGUP going to sleep
  849. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:619 (sofia/external/sipp@192.168.1.104:5060) State Change CS_HANGUP -> CS_REPORTING
  850. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:584 (sofia/external/sipp@192.168.1.104:5060) Running State Change CS_REPORTING (Cur 2 Tot 10)
  851. send 541 bytes to udp/[192.168.1.104]:5060 at 08:59:56.718214:
  852.    ------------------------------------------------------------------------
  853.    BYE sip:sipp@192.168.1.104:5060;transport=udp SIP/2.0
  854.    Via: SIP/2.0/UDP 172.17.0.3:5080;rport;branch=z9hG4bKN5XtmKjQrrS3p
  855.    Max-Forwards: 70
  856.    From: <sip:1002@172.17.0.3:5080>;tag=ND882pjeBXeQH
  857.    To: sipp <sip:sipp@192.168.1.104:5060>;tag=1
  858.    Call-ID: 1-1988@192.168.1.104
  859.    CSeq: 106348934 BYE
  860.    User-Agent: FreeSWITCH-mod_sofia/1.6.17-34-0fc0946~64bit
  861.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFYc514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:938 (sofia/external/sipp@192.168.1.104:5060) State REPORTING
  862.  
  863.    Supported: timer, path, replaces
  864.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  865.    Content-Length: 0
  866.    
  867.    ------------------------------------------------------------------------
  868. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:174 sofia/external/sipp@192.168.1.104:5060 Standard REPORTING, cause: NORMAL_CLEARING
  869. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:938 (sofia/external/sipp@192.168.1.104:5060) State REPORTING going to sleep
  870. recv 255 bytes from udp/[192.168.1.104]:5060 at 08:59:56.718368:
  871.    ------------------------------------------------------------------------
  872.    SIP/2.0 200 OK
  873.    Via: SIP/2.0/UDP 172.17.0.3:5080;rport;branch=z9hG4bKN5XtmKjQrrS3p
  874.    From: <sip:1002@172.17.0.3:5080>;tag=ND882pjeBXeQH
  875.    To: sipp <sip:sipp@192.168.1.104:5060>;tag=1
  876.    Call-ID: 1-1988@192.168.1.104
  877.    CSeq: 106348934 BYE
  878.    Content-Length: 0
  879.    
  880.    ------------------------------------------------------------------------
  881. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:610 (sofia/external/sipp@192.168.1.104:5060) State Change CS_REPORTING -> CS_DESTROY
  882. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_session.c:1664 Session 9 (sofia/external/sipp@192.168.1.104:5060) Locked, Waiting on external entities
  883. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [NOTICE] switch_core_session.c:1682 Session 9 (sofia/external/sipp@192.168.1.104:5060) Ended
  884. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [NOTICE] switch_core_session.c:1686 Close Channel sofia/external/sipp@192.168.1.104:5060 [CS_DESTROY]
  885. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:741 (sofia/external/sipp@192.168.1.104:5060) Running State Change CS_DESTROY (Cur 1 Tot 10)
  886. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:751 (sofia/external/sipp@192.168.1.104:5060) State DESTROY
  887. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] mod_sofia.c:343 sofia/external/sipp@192.168.1.104:5060 SOFIA DESTROY
  888. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:181 sofia/external/sipp@192.168.1.104:5060 Standard DESTROY
  889. c514aafe-c9de-45b1-897d-0ddd5fa3f4b2 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:751 (sofia/external/sipp@192.168.1.104:5060) State DESTROY going to sleep
  890. 2017-04-28 08:59:56.711226 [DEBUG] switch_core_media.c:5957 sofia/internal/1002@172.17.0.1:15060 Video thread ended
  891. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [NOTICE] switch_core_session.c:1682 Session 10 (sofia/internal/1002@172.17.0.1:15060) Ended
  892. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/1002@172.17.0.1:15060 [CS_DESTROY]
  893. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1002@172.17.0.1:15060) Running State Change CS_DESTROY (Cur 0 Tot 10)
  894. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1002@172.17.0.1:15060) State DESTROY
  895. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] mod_sofia.c:343 sofia/internal/1002@172.17.0.1:15060 SOFIA DESTROY
  896. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1002@172.17.0.1:15060 Standard DESTROY
  897. a39555b8-b95f-479f-b480-4601fd615f24 2017-04-28 08:59:56.711226 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1002@172.17.0.1:15060) State DESTROY going to sleep
  898. 2017-04-28 09:00:04.351110 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /var/log/freeswitch/cdr-csv/Master.csv
  899.  
captcha