From awalin, 7 Years ago, written in Plain Text.
- go back
Embed
Viewing differences between and Untitled
  1. [This app Best viewed at 160x60 or more..]
  2. +OK log level  [7]
  3. 2017-03-03 12:24:43.743990 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1002@192.168.0.104:5060 [aacc1dfb-735b-42ad-b912-00a62af5dac6]
  4. 2017-03-03 12:24:43.743990 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.0.104:5060) Running State Change CS_NEW (Cur 1 Tot 73)
  5. 2017-03-03 12:24:43.743990 [DEBUG] sofia.c:10016 sofia/internal/1002@192.168.0.104:5060 receiving invite from 192.168.0.105:37527 version: 1.9.0 git 91d62b7 2017-02-24 22:49:43Z 64bit
  6. 2017-03-03 12:24:43.743990 [DEBUG] sofia.c:10187 IP 192.168.0.105 Rejected by acl "domains". Falling back to Digest auth.
  7. 2017-03-03 12:24:43.753985 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1002@192.168.0.104:5060) State NEW
  8. 2017-03-03 12:24:43.753985 [DEBUG] sofia.c:2405 detaching session aacc1dfb-735b-42ad-b912-00a62af5dac6
  9. 2017-03-03 12:24:43.753985 [DEBUG] sofia.c:2514 Re-attaching to session aacc1dfb-735b-42ad-b912-00a62af5dac6
  10. 2017-03-03 12:24:43.773997 [DEBUG] sofia.c:10016 sofia/internal/1002@192.168.0.104:5060 receiving invite from 192.168.0.105:37527 version: 1.9.0 git 91d62b7 2017-02-24 22:49:43Z 64bit
  11. 2017-03-03 12:24:43.773997 [DEBUG] sofia.c:10187 IP 192.168.0.105 Rejected by acl "domains". Falling back to Digest auth.
  12. 2017-03-03 12:24:43.773997 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [received][100]
  13. 2017-03-03 12:24:43.773997 [DEBUG] sofia.c:7251 Remote SDP:
  14. v=0
  15. o=Zoiper 0 0 IN IP4 192.168.0.105
  16. s=Zoiper
  17. c=IN IP4 192.168.0.105
  18. t=0 0
  19. m=audio 38388 RTP/AVP 3 0 8 101
  20. a=rtpmap:3 GSM/8000
  21. a=rtpmap:0 PCMU/8000
  22. a=rtpmap:8 PCMA/8000
  23. a=rtpmap:101 telephone-event/8000
  24. a=fmtp:101 0-16
  25.  
  26. 2017-03-03 12:24:43.773997 [DEBUG] sofia.c:7643 (sofia/internal/1002@192.168.0.104:5060) State Change CS_NEW -> CS_INIT
  27. 2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.0.104:5060) Running State Change CS_INIT (Cur 1 Tot 73)
  28. 2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@192.168.0.104:5060) State INIT
  29. 2017-03-03 12:24:43.773997 [DEBUG] mod_sofia.c:93 sofia/internal/1002@192.168.0.104:5060 SOFIA INIT
  30. 2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1002@192.168.0.104:5060 Standard INIT
  31. 2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1002@192.168.0.104:5060) State Change CS_INIT -> CS_ROUTING
  32. 2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@192.168.0.104:5060) State INIT going to sleep
  33. 2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.0.104:5060) Running State Change CS_ROUTING (Cur 1 Tot 73)
  34. 2017-03-03 12:24:43.773997 [DEBUG] switch_channel.c:2249 (sofia/internal/1002@192.168.0.104:5060) Callstate Change DOWN -> RINGING
  35. 2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@192.168.0.104:5060) State ROUTING
  36. 2017-03-03 12:24:43.773997 [DEBUG] mod_sofia.c:154 sofia/internal/1002@192.168.0.104:5060 SOFIA ROUTING
  37. 2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1002@192.168.0.104:5060 Standard ROUTING
  38. 2017-03-03 12:24:43.773997 [INFO] mod_dialplan_xml.c:637 Processing 1002 <1002>->1010 in context default
  39. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->unloop] continue=false
  40. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  41. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  42. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->tod_example] continue=true
  43. Dialplan: sofia/internal/1002@192.168.0.104:5060 Date/Time Match (PASS) [tod_example] break=on-false
  44. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(open=true)
  45. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->holiday_example] continue=true
  46. Dialplan: sofia/internal/1002@192.168.0.104:5060 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  47. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->global-intercept] continue=false
  48. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [global-intercept] destination_number(1010) =~ /^886$/ break=on-false
  49. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->group-intercept] continue=false
  50. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [group-intercept] destination_number(1010) =~ /^\*8$/ break=on-false
  51. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->intercept-ext] continue=false
  52. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [intercept-ext] destination_number(1010) =~ /^\*\*(\d+)$/ break=on-false
  53. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->redial] continue=false
  54. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [redial] destination_number(1010) =~ /^(redial|870)$/ break=on-false
  55. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->global] continue=true
  56. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  57. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [global] ${default_password}(12345) =~ /^1234$/ break=never
  58. Dialplan: sofia/internal/1002@192.168.0.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
  59. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  60. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  61. o=Zoiper 0 0 IN IP4 192.168.0.105
  62. s=Zoiper
  63. c=IN IP4 192.168.0.105
  64. t=0 0
  65. m=audio 38388 RTP/AVP 3 0 8 101
  66. a=rtpmap:3 GSM/8000
  67. a=rtpmap:0 PCMU/8000
  68. a=rtpmap:8 PCMA/8000
  69. a=rtpmap:101 telephone-event/8000
  70. a=fmtp:101 0-16
  71. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  72. Dialplan: sofia/internal/1002@192.168.0.104:5060 Absolute Condition [global]
  73. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  74. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  75. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  76. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  77. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->snom-demo-2] continue=false
  78. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [snom-demo-2] destination_number(1010) =~ /^9001$/ break=on-false
  79. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->snom-demo-1] continue=false
  80. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [snom-demo-1] destination_number(1010) =~ /^9000$/ break=on-false
  81. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->eavesdrop] continue=false
  82. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [eavesdrop] destination_number(1010) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  83. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->eavesdrop] continue=false
  84. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [eavesdrop] destination_number(1010) =~ /^779$/ break=on-false
  85. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->call_return] continue=false
  86. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [call_return] destination_number(1010) =~ /^\*69$|^869$|^lcr$/ break=on-false
  87. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->del-group] continue=false
  88. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [del-group] destination_number(1010) =~ /^80(\d{2})$/ break=on-false
  89. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->add-group] continue=false
  90. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [add-group] destination_number(1010) =~ /^81(\d{2})$/ break=on-false
  91. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->call-group-simo] continue=false
  92. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [call-group-simo] destination_number(1010) =~ /^82(\d{2})$/ break=on-false
  93. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->call-group-order] continue=false
  94. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [call-group-order] destination_number(1010) =~ /^83(\d{2})$/ break=on-false
  95. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->extension-intercom] continue=false
  96. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [extension-intercom] destination_number(1010) =~ /^8(10[01][0-9])$/ break=on-false
  97. Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->Local_Extension] continue=false
  98. Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (PASS) [Local_Extension] destination_number(1010) =~ /^(10[01][0-9])$/ break=on-false
  99. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action export(dialed_extension=1010)
  100. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bind_meta_app(1 b s execute_extension::dx XML features)
  101. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)
  102. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bind_meta_app(3 b s execute_extension::cf XML features)
  103. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
  104. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(ringback=${us-ring})
  105. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(transfer_ringback=local_stream://moh)
  106. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(call_timeout=30)
  107. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(hangup_after_bridge=true)
  108. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(continue_on_fail=true)
  109. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  110. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  111. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
  112. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})
  113. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})
  114. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
  115. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bridge(user/${dialed_extension}@${domain_name})
  116. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action answer()
  117. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action sleep(1000)
  118. Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
  119. 2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1002@192.168.0.104:5060) State Change CS_ROUTING -> CS_EXECUTE
  120. 2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@192.168.0.104:5060) State ROUTING going to sleep
  121. 2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.0.104:5060) Running State Change CS_EXECUTE (Cur 1 Tot 73)
  122. 2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1002@192.168.0.104:5060) State EXECUTE
  123. 2017-03-03 12:24:43.783979 [DEBUG] mod_sofia.c:209 sofia/internal/1002@192.168.0.104:5060 SOFIA EXECUTE
  124. 2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1002@192.168.0.104:5060 Standard EXECUTE
  125. EXECUTE sofia/internal/1002@192.168.0.104:5060 set(open=true)
  126. 2017-03-03 12:24:43.783979 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [open]=[true]
  127. EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-spymap/1002/aacc1dfb-735b-42ad-b912-00a62af5dac6)
  128. EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial/1002/1010)
  129. EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial/global/aacc1dfb-735b-42ad-b912-00a62af5dac6)
  130. EXECUTE sofia/internal/1002@192.168.0.104:5060 export(RFC2822_DATE=Fri, 03 Mar 2017 12:24:43 +0000)
  131. 2017-03-03 12:24:43.783979 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 03 Mar 2017 12:24:43 +0000]
  132. EXECUTE sofia/internal/1002@192.168.0.104:5060 export(dialed_extension=1010)
  133. 2017-03-03 12:24:43.783979 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1010]
  134. EXECUTE sofia/internal/1002@192.168.0.104:5060 bind_meta_app(1 b s execute_extension::dx XML features)
  135. 2017-03-03 12:24:43.794091 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *1 execute_extension::dx XML features
  136. EXECUTE sofia/internal/1002@192.168.0.104:5060 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/1002.2017-03-03-12-24-43.wav)
  137. 2017-03-03 12:24:43.794091 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/1002.2017-03-03-12-24-43.wav
  138. EXECUTE sofia/internal/1002@192.168.0.104:5060 bind_meta_app(3 b s execute_extension::cf XML features)
  139. 2017-03-03 12:24:43.794091 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *3 execute_extension::cf XML features
  140. EXECUTE sofia/internal/1002@192.168.0.104:5060 bind_meta_app(4 b s execute_extension::att_xfer XML features)
  141. 2017-03-03 12:24:43.794091 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *4 execute_extension::att_xfer XML features
  142. EXECUTE sofia/internal/1002@192.168.0.104:5060 set(ringback=%(2000,4000,440,480))
  143. 2017-03-03 12:24:43.794091 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [ringback]=[%(2000,4000,440,480)]
  144. EXECUTE sofia/internal/1002@192.168.0.104:5060 set(transfer_ringback=local_stream://moh)
  145. 2017-03-03 12:24:43.794091 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [transfer_ringback]=[local_stream://moh]
  146. EXECUTE sofia/internal/1002@192.168.0.104:5060 set(call_timeout=30)
  147. 2017-03-03 12:24:43.794091 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [call_timeout]=[30]
  148. EXECUTE sofia/internal/1002@192.168.0.104:5060 set(hangup_after_bridge=true)
  149. 2017-03-03 12:24:43.794091 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [hangup_after_bridge]=[true]
  150. EXECUTE sofia/internal/1002@192.168.0.104:5060 set(continue_on_fail=true)
  151. 2017-03-03 12:24:43.803984 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [continue_on_fail]=[true]
  152. EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-call_return/1010/1002)
  153. EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial_ext/1010/aacc1dfb-735b-42ad-b912-00a62af5dac6)
  154. EXECUTE sofia/internal/1002@192.168.0.104:5060 set(called_party_callgroup=techsupport)
  155. 2017-03-03 12:24:43.803984 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [called_party_callgroup]=[techsupport]
  156. EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial_ext/techsupport/aacc1dfb-735b-42ad-b912-00a62af5dac6)
  157. EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial_ext/global/aacc1dfb-735b-42ad-b912-00a62af5dac6)
  158. EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial/techsupport/aacc1dfb-735b-42ad-b912-00a62af5dac6)
  159. EXECUTE sofia/internal/1002@192.168.0.104:5060 bridge(user/1010@192.168.0.104)
  160. 2017-03-03 12:24:43.813983 [DEBUG] switch_channel.c:1250 sofia/internal/1002@192.168.0.104:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 03 Mar 2017 12:24:43 +0000] to event
  161. 2017-03-03 12:24:43.813983 [DEBUG] switch_channel.c:1250 sofia/internal/1002@192.168.0.104:5060 EXPORTING[export_vars] [dialed_extension]=[1010] to event
  162. 2017-03-03 12:24:43.813983 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables
  163. 2017-03-03 12:24:43.813983 [DEBUG] switch_channel.c:1250 sofia/internal/1002@192.168.0.104:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 03 Mar 2017 12:24:43 +0000] to event
  164. 2017-03-03 12:24:43.813983 [DEBUG] switch_channel.c:1250 sofia/internal/1002@192.168.0.104:5060 EXPORTING[export_vars] [dialed_extension]=[1010] to event
  165. 2017-03-03 12:24:43.813983 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables
  166. 2017-03-03 12:24:43.813983 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1010@192.168.0.128:36056 [d2238c44-67b5-4e18-b4a9-dd100906de39]
  167. 2017-03-03 12:24:43.813983 [DEBUG] mod_sofia.c:5002 (sofia/internal/1010@192.168.0.128:36056) State Change CS_NEW -> CS_INIT
  168. 2017-03-03 12:24:43.813983 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1010@192.168.0.128:36056) Running State Change CS_INIT (Cur 2 Tot 74)
  169. 2017-03-03 12:24:43.813983 [NOTICE] switch_ivr_originate.c:2846 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  170. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1010@192.168.0.128:36056) State INIT
  171. 2017-03-03 12:24:43.823989 [DEBUG] mod_sofia.c:93 sofia/internal/1010@192.168.0.128:36056 SOFIA INIT
  172. 2017-03-03 12:24:43.823989 [DEBUG] sofia_glue.c:1296 sofia/internal/1010@192.168.0.128:36056 sending invite version: 1.9.0 git 91d62b7 2017-02-24 22:49:43Z 64bit
  173. Local SDP:
  174. v=0
  175. o=FreeSWITCH 1488525837 1488525838 IN IP4 192.168.0.104
  176. s=FreeSWITCH
  177. c=IN IP4 192.168.0.104
  178. t=0 0
  179. m=audio 18046 RTP/AVP 0 8 101
  180. a=rtpmap:0 PCMU/8000
  181. a=rtpmap:8 PCMA/8000
  182. a=rtpmap:101 telephone-event/8000
  183. a=fmtp:101 0-16
  184. a=ptime:20
  185. a=sendrecv
  186.  
  187. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1010@192.168.0.128:36056 Standard INIT
  188. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1010@192.168.0.128:36056) State Change CS_INIT -> CS_ROUTING
  189. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1010@192.168.0.128:36056) State INIT going to sleep
  190. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1010@192.168.0.128:36056) Running State Change CS_ROUTING (Cur 2 Tot 74)
  191. 2017-03-03 12:24:43.823989 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [calling][0]
  192. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1010@192.168.0.128:36056) State ROUTING
  193. 2017-03-03 12:24:43.823989 [DEBUG] mod_sofia.c:154 sofia/internal/1010@192.168.0.128:36056 SOFIA ROUTING
  194. 2017-03-03 12:24:43.823989 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1010@192.168.0.128:36056) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  195. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1010@192.168.0.128:36056) State ROUTING going to sleep
  196. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1010@192.168.0.128:36056) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 74)
  197. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1010@192.168.0.128:36056) State CONSUME_MEDIA
  198. 2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1010@192.168.0.128:36056) State CONSUME_MEDIA going to sleep
  199. 2017-03-03 12:24:43.943979 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [proceeding][180]
  200. 2017-03-03 12:24:43.943979 [NOTICE] sofia.c:7349 Ring-Ready sofia/internal/1010@192.168.0.128:36056!
  201. 2017-03-03 12:24:43.943979 [DEBUG] switch_channel.c:3353 (sofia/internal/1010@192.168.0.128:36056) Callstate Change DOWN -> RINGING
  202. 2017-03-03 12:24:43.963985 [INFO] switch_ivr_originate.c:1220 Sending early media
  203. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
  204. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
  205. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  206. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  207. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  208. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  209. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  210. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  211. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  212. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  213. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  214. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  215. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  216. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  217. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:4974 Set telephone-event payload to 101@8000
  218. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:3414 Set Codec sofia/internal/1002@192.168.0.104:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  219. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_codec.c:111 sofia/internal/1002@192.168.0.104:5060 Original read codec set to PCMU:0
  220. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5317 Set telephone-event payload to 101@8000
  221. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5376 sofia/internal/1002@192.168.0.104:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  222. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:7978 AUDIO RTP [sofia/internal/1002@192.168.0.104:5060] 192.168.0.104 port 20582 -> 192.168.0.105 port 38388 codec: 0 ms: 20
  223. 2017-03-03 12:24:43.963985 [DEBUG] switch_rtp.c:3888 Starting timer [soft] 160 bytes per 20ms
  224. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:8286 sofia/internal/1002@192.168.0.104:5060 Set 2833 dtmf send payload to 101
  225. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:8293 sofia/internal/1002@192.168.0.104:5060 Set 2833 dtmf receive payload to 101
  226. 2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:8316 sofia/internal/1002@192.168.0.104:5060 Set rtp dtmf delay to 40
  227. 2017-03-03 12:24:43.963985 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1002@192.168.0.104:5060!
  228. 2017-03-03 12:24:43.974018 [DEBUG] switch_channel.c:3481 (sofia/internal/1002@192.168.0.104:5060) Callstate Change RINGING -> EARLY
  229. 2017-03-03 12:24:43.974018 [DEBUG] switch_core_media.c:7961 Audio params are unchanged for sofia/internal/1002@192.168.0.104:5060.
  230. 2017-03-03 12:24:43.974018 [DEBUG] mod_sofia.c:2502 Ring SDP:
  231. v=0
  232. o=FreeSWITCH 1488523301 1488523302 IN IP4 192.168.0.104
  233. s=FreeSWITCH
  234. c=IN IP4 192.168.0.104
  235. t=0 0
  236. m=audio 20582 RTP/AVP 0 101
  237. a=rtpmap:0 PCMU/8000
  238. a=rtpmap:101 telephone-event/8000
  239. a=fmtp:101 0-16
  240. a=ptime:20
  241. a=sendrecv
  242.  
  243. 2017-03-03 12:24:43.974018 [DEBUG] switch_ivr_originate.c:1278 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  244. 2017-03-03 12:24:43.974018 [DEBUG] switch_core_codec.c:223 sofia/internal/1002@192.168.0.104:5060 Push codec L16:100
  245. 2017-03-03 12:24:43.974018 [DEBUG] switch_ivr_originate.c:1347 Play Ringback Tone [%(2000,4000,440,480)]
  246. 2017-03-03 12:24:43.994007 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [early][183]
  247. 2017-03-03 12:24:44.323992 [DEBUG] switch_rtp.c:7020 Correct audio ip/port confirmed.
  248. 2017-03-03 12:24:48.563996 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [completing][200]
  249. 2017-03-03 12:24:48.563996 [DEBUG] sofia.c:7251 Remote SDP:
  250. v=0
  251. o=Zoiper 0 1 IN IP4 192.168.0.128
  252. s=Zoiper
  253. c=IN IP4 192.168.0.128
  254. t=0 0
  255. m=audio 33540 RTP/AVP 0 3 8 101
  256. a=rtpmap:0 PCMU/8000
  257. a=rtpmap:3 GSM/8000
  258. a=rtpmap:8 PCMA/8000
  259. a=rtpmap:101 telephone-event/8000
  260. a=fmtp:101 0-16
  261.  
  262. 2017-03-03 12:24:48.563996 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [ready][200]
  263. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  264. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  265. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  266. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  267. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  268. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  269. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  270. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  271. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:4974 Set telephone-event payload to 101@8000
  272. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:3414 Set Codec sofia/internal/1010@192.168.0.128:36056 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  273. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_codec.c:111 sofia/internal/1010@192.168.0.128:36056 Original read codec set to PCMU:0
  274. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5317 Set telephone-event payload to 101@8000
  275. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5376 sofia/internal/1010@192.168.0.128:36056 Set 2833 dtmf send payload to 101 recv payload to 101
  276. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:7978 AUDIO RTP [sofia/internal/1010@192.168.0.128:36056] 192.168.0.104 port 18046 -> 192.168.0.128 port 33540 codec: 0 ms: 20
  277. 2017-03-03 12:24:48.563996 [DEBUG] switch_rtp.c:3888 Starting timer [soft] 160 bytes per 20ms
  278. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:8286 sofia/internal/1010@192.168.0.128:36056 Set 2833 dtmf send payload to 101
  279. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:8293 sofia/internal/1010@192.168.0.128:36056 Set 2833 dtmf receive payload to 101
  280. 2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:8316 sofia/internal/1010@192.168.0.128:36056 Set rtp dtmf delay to 40
  281. 2017-03-03 12:24:48.563996 [NOTICE] sofia.c:8365 Channel [sofia/internal/1010@192.168.0.128:36056] has been answered
  282. 2017-03-03 12:24:48.573982 [DEBUG] switch_channel.c:3780 (sofia/internal/1010@192.168.0.128:36056) Callstate Change RINGING -> ACTIVE
  283. 2017-03-03 12:24:48.583987 [DEBUG] switch_core_codec.c:248 sofia/internal/1002@192.168.0.104:5060 Restore previous codec PCMU:0.
  284. 2017-03-03 12:24:48.583987 [DEBUG] switch_core_media.c:7961 Audio params are unchanged for sofia/internal/1002@192.168.0.104:5060.
  285. 2017-03-03 12:24:48.583987 [DEBUG] mod_sofia.c:881 Local SDP sofia/internal/1002@192.168.0.104:5060:
  286. v=0
  287. o=FreeSWITCH 1488523301 1488523303 IN IP4 192.168.0.104
  288. s=FreeSWITCH
  289. c=IN IP4 192.168.0.104
  290. t=0 0
  291. m=audio 20582 RTP/AVP 0 101
  292. a=rtpmap:0 PCMU/8000
  293. a=rtpmap:101 telephone-event/8000
  294. a=fmtp:101 0-16
  295. a=ptime:20
  296. a=sendrecv
  297.  
  298. 2017-03-03 12:24:48.583987 [NOTICE] switch_ivr_originate.c:3633 Channel [sofia/internal/1002@192.168.0.104:5060] has been answered
  299. 2017-03-03 12:24:48.593983 [DEBUG] switch_channel.c:3780 (sofia/internal/1002@192.168.0.104:5060) Callstate Change EARLY -> ACTIVE
  300. 2017-03-03 12:24:48.593983 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [completed][200]
  301. 2017-03-03 12:24:48.593983 [DEBUG] switch_ivr_originate.c:3691 Originate Resulted in Success: [sofia/internal/1010@192.168.0.128:36056]
  302. 2017-03-03 12:24:48.593983 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [ready][200]
  303. 2017-03-03 12:24:48.593983 [DEBUG] switch_ivr_originate.c:3691 Originate Resulted in Success: [sofia/internal/1010@192.168.0.128:36056]
  304. 2017-03-03 12:24:48.603984 [DEBUG] switch_ivr_bridge.c:1696 (sofia/internal/1010@192.168.0.128:36056) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  305. 2017-03-03 12:24:48.603984 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1010@192.168.0.128:36056) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 74)
  306. 2017-03-03 12:24:48.603984 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1010@192.168.0.128:36056) State EXCHANGE_MEDIA
  307. 2017-03-03 12:24:48.603984 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA
  308. 2017-03-03 12:24:48.823991 [DEBUG] switch_rtp.c:7020 Correct audio ip/port confirmed.
  309. 2017-03-03 12:24:48.823991 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [received][100]
  310. 2017-03-03 12:24:48.823991 [DEBUG] sofia.c:7251 Remote SDP:
  311. v=0
  312. o=Zoiper 0 1 IN IP4 192.168.0.105
  313. s=Zoiper
  314. c=IN IP4 192.168.0.105
  315. t=0 0
  316. m=audio 38388 RTP/AVP 0 3 8 101
  317. a=rtpmap:0 PCMU/8000
  318. a=rtpmap:3 GSM/8000
  319. a=rtpmap:8 PCMA/8000
  320. a=rtpmap:101 telephone-event/8000
  321. a=fmtp:101 0-16
  322. m=video 38414 RTP/AVP 116 115
  323. a=rtpmap:116 VP8/90000
  324. a=rtpmap:115 H263-1998/90000
  325.  
  326. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  327. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  328. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  329. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  330. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  331. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
  332. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
  333. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  334. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  335. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  336. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  337. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  338. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  339. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  340. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:4974 Set telephone-event payload to 101@8000
  341. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5317 Set telephone-event payload to 101@8000
  342. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5376 sofia/internal/1002@192.168.0.104:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  343. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5659 Video Codec Compare [VP8:116]/[VP8:99]
  344. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5691 Video Codec Compare [VP8:116] +++ is saved as a match
  345. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5659 Video Codec Compare [H263-1998:115]/[VP8:99]
  346. 2017-03-03 12:24:48.823991 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  347. 2017-03-03 12:24:48.823991 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  348. 2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:3224 Set VIDEO Codec sofia/internal/1002@192.168.0.104:5060 VP8/90000 0 ms
  349. 2017-03-03 12:24:48.833990 [DEBUG] switch_core_media.c:7961 Audio params are unchanged for sofia/internal/1002@192.168.0.104:5060.
  350. 2017-03-03 12:24:48.833990 [DEBUG] switch_rtp.c:3899 Starting video timer.
  351. 2017-03-03 12:24:48.833990 [DEBUG] switch_core_media.c:8816 VIDEO RTP [sofia/internal/1002@192.168.0.104:5060] 192.168.0.104:27574->192.168.0.105:38414 codec: 116 ms: 0 [SUCCESS]
  352. 2017-03-03 12:24:48.833990 [DEBUG] switch_core_media.c:6977 sofia/internal/1002@192.168.0.104:5060 Starting Video thread
  353. 2017-03-03 12:24:48.833990 [DEBUG] sofia.c:8144 Processing updated SDP
  354. 2017-03-03 12:24:48.833990 [DEBUG] switch_core_media.c:6843 sofia/internal/1002@192.168.0.104:5060 Video thread started. Echo is off
  355. 2017-03-03 12:24:48.843988 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [completed][200]
  356. 2017-03-03 12:24:48.843988 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [ready][200]
  357. 2017-03-03 12:24:48.853987 [NOTICE] switch_vpx.c:402 VPX reset encoder picture from 320x240 to 352x288 1024 BW
  358. 2017-03-03 12:24:49.163995 [DEBUG] switch_rtp.c:7020 Correct video ip/port confirmed.
  359. 2017-03-03 12:24:49.244020 [DEBUG] switch_rtp.c:7020 Correct audio ip/port confirmed.
  360. 2017-03-03 12:24:57.683985 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [received][100]
  361. 2017-03-03 12:24:57.683985 [DEBUG] sofia.c:7251 Remote SDP:
  362. v=0
  363. o=Zoiper 0 2 IN IP4 192.168.0.128
  364. s=Zoiper
  365. c=IN IP4 192.168.0.128
  366. t=0 0
  367. m=audio 33540 RTP/AVP 0 3 8 101
  368. a=rtpmap:0 PCMU/8000
  369. a=rtpmap:3 GSM/8000
  370. a=rtpmap:8 PCMA/8000
  371. a=rtpmap:101 telephone-event/8000
  372. a=fmtp:101 0-16
  373. m=video 33552 RTP/AVP 116 115
  374. a=rtpmap:116 VP8/90000
  375. a=rtpmap:115 H263-1998/90000
  376.  
  377. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  378. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  379. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  380. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  381. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  382. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  383. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
  384. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
  385. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  386. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  387. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  388. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  389. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  390. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  391. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:4974 Set telephone-event payload to 101@8000
  392. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5317 Set telephone-event payload to 101@8000
  393. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5376 sofia/internal/1010@192.168.0.128:36056 Set 2833 dtmf send payload to 101 recv payload to 101
  394. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5659 Video Codec Compare [VP8:116]/[VP8:99]
  395. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5691 Video Codec Compare [VP8:116] +++ is saved as a match
  396. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5659 Video Codec Compare [H263-1998:115]/[VP8:99]
  397. 2017-03-03 12:24:57.683985 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  398. 2017-03-03 12:24:57.683985 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
  399. 2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:3224 Set VIDEO Codec sofia/internal/1010@192.168.0.128:36056 VP8/90000 0 ms
  400. 2017-03-03 12:24:57.694000 [DEBUG] switch_core_media.c:7961 Audio params are unchanged for sofia/internal/1010@192.168.0.128:36056.
  401. 2017-03-03 12:24:57.694000 [DEBUG] switch_rtp.c:3899 Starting video timer.
  402. 2017-03-03 12:24:57.694000 [DEBUG] switch_core_media.c:8816 VIDEO RTP [sofia/internal/1010@192.168.0.128:36056] 192.168.0.104:26338->192.168.0.128:33552 codec: 116 ms: 0 [SUCCESS]
  403. 2017-03-03 12:24:57.694000 [DEBUG] switch_core_media.c:6977 sofia/internal/1010@192.168.0.128:36056 Starting Video thread
  404. 2017-03-03 12:24:57.694000 [DEBUG] sofia.c:8144 Processing updated SDP
  405. 2017-03-03 12:24:57.694000 [DEBUG] switch_core_media.c:6843 sofia/internal/1010@192.168.0.128:36056 Video thread started. Echo is off
  406. 2017-03-03 12:24:57.704004 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [completed][200]
  407. 2017-03-03 12:24:57.704004 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [ready][200]
  408. 2017-03-03 12:24:58.143997 [DEBUG] switch_rtp.c:7020 Correct video ip/port confirmed.
  409.