From awalin, 7 Years ago, written in Plain Text.
- view diff
Embed
  1. freeswitch@voyage>
  2. 2017-03-02 14:07:58.347716 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1002@rayteldes.co.uk:5060 [46df0e42-707f-4487-89aa-b369665fb0fc]
  3. 2017-03-02 14:07:58.347716 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@rayteldes.co.uk:5060) Running State Change CS_NEW (Cur 1 Tot 51)
  4. 2017-03-02 14:07:58.347716 [DEBUG] sofia.c:10016 sofia/internal/1002@rayteldes.co.uk:5060 receiving invite from 192.168.0.105:41106 version: 1.9.0 git 91d62b7 2017-02-24 22:49:43Z 64bit
  5. 2017-03-02 14:07:58.347716 [DEBUG] sofia.c:10187 IP 192.168.0.105 Rejected by acl "domains". Falling back to Digest auth.
  6. 2017-03-02 14:07:58.347716 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1002@rayteldes.co.uk:5060) State NEW
  7. 2017-03-02 14:07:58.347716 [DEBUG] sofia.c:2405 detaching session 46df0e42-707f-4487-89aa-b369665fb0fc
  8. 2017-03-02 14:07:58.347716 [DEBUG] sofia.c:2514 Re-attaching to session 46df0e42-707f-4487-89aa-b369665fb0fc
  9. 2017-03-02 14:07:58.367710 [DEBUG] sofia.c:10016 sofia/internal/1002@rayteldes.co.uk:5060 receiving invite from 192.168.0.105:41106 version: 1.9.0 git 91d62b7 2017-02-24 22:49:43Z 64bit
  10. 2017-03-02 14:07:58.367710 [DEBUG] sofia.c:10187 IP 192.168.0.105 Rejected by acl "domains". Falling back to Digest auth.
  11. 2017-03-02 14:07:58.377707 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@rayteldes.co.uk:5060 entering state [received][100]
  12. 2017-03-02 14:07:58.377707 [DEBUG] sofia.c:7251 Remote SDP:
  13. v=0
  14. o=Zoiper 0 0 IN IP4 192.168.0.105
  15. s=Zoiper
  16. c=IN IP4 192.168.0.105
  17. t=0 0
  18. m=audio 43282 RTP/AVP 3 0 8 101
  19. a=rtpmap:3 GSM/8000
  20. a=rtpmap:0 PCMU/8000
  21. a=rtpmap:8 PCMA/8000
  22. a=rtpmap:101 telephone-event/8000
  23. a=fmtp:101 0-16
  24.  
  25. 2017-03-02 14:07:58.377707 [DEBUG] sofia.c:7643 (sofia/internal/1002@rayteldes.co.uk:5060) State Change CS_NEW -> CS_INIT
  26. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@rayteldes.co.uk:5060) Running State Change CS_INIT (Cur 1 Tot 51)
  27. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@rayteldes.co.uk:5060) State INIT
  28. 2017-03-02 14:07:58.377707 [DEBUG] mod_sofia.c:93 sofia/internal/1002@rayteldes.co.uk:5060 SOFIA INIT
  29. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1002@rayteldes.co.uk:5060 Standard INIT
  30. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1002@rayteldes.co.uk:5060) State Change CS_INIT -> CS_ROUTING
  31. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@rayteldes.co.uk:5060) State INIT going to sleep
  32. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@rayteldes.co.uk:5060) Running State Change CS_ROUTING (Cur 1 Tot 51)
  33. 2017-03-02 14:07:58.377707 [DEBUG] switch_channel.c:2249 (sofia/internal/1002@rayteldes.co.uk:5060) Callstate Change DOWN -> RINGING
  34. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@rayteldes.co.uk:5060) State ROUTING
  35. 2017-03-02 14:07:58.377707 [DEBUG] mod_sofia.c:154 sofia/internal/1002@rayteldes.co.uk:5060 SOFIA ROUTING
  36. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1002@rayteldes.co.uk:5060 Standard ROUTING
  37. 2017-03-02 14:07:58.377707 [INFO] mod_dialplan_xml.c:637 Processing 1002 <1002>->1008 in context default
  38. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->unloop] continue=false
  39. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  40. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  41. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->tod_example] continue=true
  42. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Date/Time Match (PASS) [tod_example] break=on-false
  43. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action set(open=true)
  44. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->holiday_example] continue=true
  45. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  46. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->global-intercept] continue=false
  47. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [global-intercept] destination_number(1008) =~ /^886$/ break=on-false
  48. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->group-intercept] continue=false
  49. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [group-intercept] destination_number(1008) =~ /^\*8$/ break=on-false
  50. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->intercept-ext] continue=false
  51. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [intercept-ext] destination_number(1008) =~ /^\*\*(\d+)$/ break=on-false
  52. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->redial] continue=false
  53. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [redial] destination_number(1008) =~ /^(redial|870)$/ break=on-false
  54. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->global] continue=true
  55. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  56. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [global] ${default_password}(12345) =~ /^1234$/ break=never
  57. Dialplan: sofia/internal/1002@rayteldes.co.uk: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
  58. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  59. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  60. o=Zoiper 0 0 IN IP4 192.168.0.105
  61. s=Zoiper
  62. c=IN IP4 192.168.0.105
  63. t=0 0
  64. m=audio 43282 RTP/AVP 3 0 8 101
  65. a=rtpmap:3 GSM/8000
  66. a=rtpmap:0 PCMU/8000
  67. a=rtpmap:8 PCMA/8000
  68. a=rtpmap:101 telephone-event/8000
  69. a=fmtp:101 0-16
  70. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  71. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Absolute Condition [global]
  72. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  73. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  74. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  75. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  76. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->snom-demo-2] continue=false
  77. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [snom-demo-2] destination_number(1008) =~ /^9001$/ break=on-false
  78. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->snom-demo-1] continue=false
  79. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [snom-demo-1] destination_number(1008) =~ /^9000$/ break=on-false
  80. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->eavesdrop] continue=false
  81. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [eavesdrop] destination_number(1008) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  82. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->eavesdrop] continue=false
  83. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [eavesdrop] destination_number(1008) =~ /^779$/ break=on-false
  84. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->call_return] continue=false
  85. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [call_return] destination_number(1008) =~ /^\*69$|^869$|^lcr$/ break=on-false
  86. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->del-group] continue=false
  87. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [del-group] destination_number(1008) =~ /^80(\d{2})$/ break=on-false
  88. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->add-group] continue=false
  89. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [add-group] destination_number(1008) =~ /^81(\d{2})$/ break=on-false
  90. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->call-group-simo] continue=false
  91. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [call-group-simo] destination_number(1008) =~ /^82(\d{2})$/ break=on-false
  92. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->call-group-order] continue=false
  93. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [call-group-order] destination_number(1008) =~ /^83(\d{2})$/ break=on-false
  94. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->extension-intercom] continue=false
  95. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (FAIL) [extension-intercom] destination_number(1008) =~ /^8(10[01][0-9])$/ break=on-false
  96. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 parsing [default->Local_Extension] continue=false
  97. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Regex (PASS) [Local_Extension] destination_number(1008) =~ /^(10[01][0-9])$/ break=on-false
  98. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action export(dialed_extension=1008)
  99. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action bind_meta_app(1 b s execute_extension::dx XML features)
  100. Dialplan: sofia/internal/1002@rayteldes.co.uk: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)
  101. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action bind_meta_app(3 b s execute_extension::cf XML features)
  102. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
  103. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action set(ringback=${us-ring})
  104. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action set(transfer_ringback=local_stream://moh)
  105. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action set(call_timeout=30)
  106. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action set(hangup_after_bridge=true)
  107. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action set(continue_on_fail=true)
  108. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  109. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  110. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
  111. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})
  112. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})
  113. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
  114. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action bridge(user/${dialed_extension}@${domain_name})
  115. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action answer()
  116. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action sleep(1000)
  117. Dialplan: sofia/internal/1002@rayteldes.co.uk:5060 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
  118. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1002@rayteldes.co.uk:5060) State Change CS_ROUTING -> CS_EXECUTE
  119. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@rayteldes.co.uk:5060) State ROUTING going to sleep
  120. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@rayteldes.co.uk:5060) Running State Change CS_EXECUTE (Cur 1 Tot 51)
  121. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1002@rayteldes.co.uk:5060) State EXECUTE
  122. 2017-03-02 14:07:58.377707 [DEBUG] mod_sofia.c:209 sofia/internal/1002@rayteldes.co.uk:5060 SOFIA EXECUTE
  123. 2017-03-02 14:07:58.377707 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1002@rayteldes.co.uk:5060 Standard EXECUTE
  124. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 set(open=true)
  125. 2017-03-02 14:07:58.377707 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@rayteldes.co.uk:5060 [open]=[true]
  126. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 hash(insert/192.168.0.104-spymap/1002/46df0e42-707f-4487-89aa-b369665fb0fc)
  127. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 hash(insert/192.168.0.104-last_dial/1002/1008)
  128. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 hash(insert/192.168.0.104-last_dial/global/46df0e42-707f-4487-89aa-b369665fb0fc)
  129. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 export(RFC2822_DATE=Thu, 02 Mar 2017 14:07:58 +0000)
  130. 2017-03-02 14:07:58.387692 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 02 Mar 2017 14:07:58 +0000]
  131. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 export(dialed_extension=1008)
  132. 2017-03-02 14:07:58.387692 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1008]
  133. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 bind_meta_app(1 b s execute_extension::dx XML features)
  134. 2017-03-02 14:07:58.387692 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *1 execute_extension::dx XML features
  135. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/1002.2017-03-02-14-07-58.wav)
  136. 2017-03-02 14:07:58.387692 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/1002.2017-03-02-14-07-58.wav
  137. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 bind_meta_app(3 b s execute_extension::cf XML features)
  138. 2017-03-02 14:07:58.387692 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *3 execute_extension::cf XML features
  139. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 bind_meta_app(4 b s execute_extension::att_xfer XML features)
  140. 2017-03-02 14:07:58.387692 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *4 execute_extension::att_xfer XML features
  141. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 set(ringback=%(2000,4000,440,480))
  142. 2017-03-02 14:07:58.387692 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@rayteldes.co.uk:5060 [ringback]=[%(2000,4000,440,480)]
  143. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 set(transfer_ringback=local_stream://moh)
  144. 2017-03-02 14:07:58.397691 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@rayteldes.co.uk:5060 [transfer_ringback]=[local_stream://moh]
  145. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 set(call_timeout=30)
  146. 2017-03-02 14:07:58.397691 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@rayteldes.co.uk:5060 [call_timeout]=[30]
  147. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 set(hangup_after_bridge=true)
  148. 2017-03-02 14:07:58.397691 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@rayteldes.co.uk:5060 [hangup_after_bridge]=[true]
  149. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 set(continue_on_fail=true)
  150. 2017-03-02 14:07:58.397691 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@rayteldes.co.uk:5060 [continue_on_fail]=[true]
  151. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 hash(insert/192.168.0.104-call_return/1008/1002)
  152. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 hash(insert/192.168.0.104-last_dial_ext/1008/46df0e42-707f-4487-89aa-b369665fb0fc)
  153. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 set(called_party_callgroup=techsupport)
  154. 2017-03-02 14:07:58.397691 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@rayteldes.co.uk:5060 [called_party_callgroup]=[techsupport]
  155. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 hash(insert/192.168.0.104-last_dial_ext/techsupport/46df0e42-707f-4487-89aa-b369665fb0fc)
  156. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 hash(insert/192.168.0.104-last_dial_ext/global/46df0e42-707f-4487-89aa-b369665fb0fc)
  157. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 hash(insert/192.168.0.104-last_dial/techsupport/46df0e42-707f-4487-89aa-b369665fb0fc)
  158. EXECUTE sofia/internal/1002@rayteldes.co.uk:5060 bridge(user/1008@192.168.0.104)
  159. 2017-03-02 14:07:58.407695 [DEBUG] switch_channel.c:1250 sofia/internal/1002@rayteldes.co.uk:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 02 Mar 2017 14:07:58 +0000] to event
  160. 2017-03-02 14:07:58.407695 [DEBUG] switch_channel.c:1250 sofia/internal/1002@rayteldes.co.uk:5060 EXPORTING[export_vars] [dialed_extension]=[1008] to event
  161. 2017-03-02 14:07:58.407695 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables
  162. 2017-03-02 14:07:58.407695 [DEBUG] switch_channel.c:1250 sofia/internal/1002@rayteldes.co.uk:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 02 Mar 2017 14:07:58 +0000] to event
  163. 2017-03-02 14:07:58.407695 [DEBUG] switch_channel.c:1250 sofia/internal/1002@rayteldes.co.uk:5060 EXPORTING[export_vars] [dialed_extension]=[1008] to event
  164. 2017-03-02 14:07:58.407695 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables
  165. 2017-03-02 14:07:58.417690 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1008@df7jal23ls0d.invalid [34306794-fa56-4c2f-9531-16774a2ff629]
  166. 2017-03-02 14:07:58.417690 [DEBUG] mod_sofia.c:5002 (sofia/internal/1008@df7jal23ls0d.invalid) State Change CS_NEW -> CS_INIT
  167. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1008@df7jal23ls0d.invalid) Running State Change CS_INIT (Cur 2 Tot 52)
  168. 2017-03-02 14:07:58.417690 [NOTICE] switch_ivr_originate.c:2846 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  169. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1008@df7jal23ls0d.invalid) State INIT
  170. 2017-03-02 14:07:58.417690 [DEBUG] mod_sofia.c:93 sofia/internal/1008@df7jal23ls0d.invalid SOFIA INIT
  171. 2017-03-02 14:07:58.417690 [DEBUG] sofia_glue.c:1267 sips:1008@192.168.0.128:49658;rtcweb-breaker=yes;transport=wss Setting proxy route to sofia/internal/1008@df7jal23ls0d.invalid
  172. 2017-03-02 14:07:58.417690 [DEBUG] sofia_glue.c:1296 sofia/internal/1008@df7jal23ls0d.invalid sending invite version: 1.9.0 git 91d62b7 2017-02-24 22:49:43Z 64bit
  173. Local SDP:
  174. v=0
  175. o=FreeSWITCH 1488439710 1488439711 IN IP4 192.168.0.104
  176. s=FreeSWITCH
  177. c=IN IP4 192.168.0.104
  178. t=0 0
  179. a=msid-semantic: WMS ZaoeSWJzBGCWEmfMXzci6Yuq5JYxT1dI
  180. m=audio 23968 RTP/SAVPF 0 8 101
  181. a=rtpmap:0 PCMU/8000
  182. a=rtpmap:8 PCMA/8000
  183. a=rtpmap:101 telephone-event/8000
  184. a=fingerprint:sha-256 18:87:44:40:A4:B1:1B:D2:30:EC:DD:94:D7:73:09:EA:90:12:88:69:9F:15:0A:1B:A9:1A:C7:FD:07:48:82:72
  185. a=setup:actpass
  186. a=rtcp-mux
  187. a=rtcp:23968 IN IP4 192.168.0.104
  188. a=ssrc:1019464494 cname:2sNKenaGUCtoOy1B
  189. a=ssrc:1019464494 msid:ZaoeSWJzBGCWEmfMXzci6Yuq5JYxT1dI a0
  190. a=ssrc:1019464494 mslabel:ZaoeSWJzBGCWEmfMXzci6Yuq5JYxT1dI
  191. a=ssrc:1019464494 label:ZaoeSWJzBGCWEmfMXzci6Yuq5JYxT1dIa0
  192. a=ice-ufrag:Y4KVS4cOD0bnRfWJ
  193. a=ice-pwd:xzjBMj8xLrLqQCRExspfmrTQ
  194. a=candidate:6766039656 1 udp 659136 192.168.0.104 23968 typ host generation 0
  195. a=candidate:6766039656 2 udp 659136 192.168.0.104 23968 typ host generation 0
  196. a=ptime:20
  197. a=sendrecv
  198.  
  199. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1008@df7jal23ls0d.invalid Standard INIT
  200. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1008@df7jal23ls0d.invalid) State Change CS_INIT -> CS_ROUTING
  201. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1008@df7jal23ls0d.invalid) State INIT going to sleep
  202. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1008@df7jal23ls0d.invalid) Running State Change CS_ROUTING (Cur 2 Tot 52)
  203. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1008@df7jal23ls0d.invalid) State ROUTING
  204. 2017-03-02 14:07:58.417690 [DEBUG] mod_sofia.c:154 sofia/internal/1008@df7jal23ls0d.invalid SOFIA ROUTING
  205. 2017-03-02 14:07:58.417690 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1008@df7jal23ls0d.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  206. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1008@df7jal23ls0d.invalid) State ROUTING going to sleep
  207. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1008@df7jal23ls0d.invalid) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 52)
  208. 2017-03-02 14:07:58.417690 [DEBUG] sofia.c:7241 Channel sofia/internal/1008@df7jal23ls0d.invalid entering state [calling][0]
  209. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1008@df7jal23ls0d.invalid) State CONSUME_MEDIA
  210. 2017-03-02 14:07:58.417690 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1008@df7jal23ls0d.invalid) State CONSUME_MEDIA going to sleep
  211. freeswitch@voyage>
  212.