API tools faq
paste
Advertisement
josefu

limit bridge one member

josefu
Jun 4th, 2012
310
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
FreeSWITCH 30.94 KB | None | 0 0
raw download clone embed print report
  1. span class="re2"> 2012-06-04 12:09:32.861048 [DEBUG] sofia.c:7880 IP 128.23.0.2 Rejected by acl "domains". Falling back to Digest auth.
  2. 2012-06-04 12:09:32.910952 [DEBUG] sofia.c:7880 IP 128.23.0.2 Rejected by acl "domains". Falling back to Digest auth.
  3. 2012-06-04 12:09:32.980959 [NOTICE] switch_channel.c:926 New Channel sofia/internal/171@128.23.0.1 [4be2e410-bc67-452a-8406-edf85996a661]
  4. 2012-06-04 12:09:32.980959 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_NEW
  5. 2012-06-04 12:09:32.980959 [DEBUG] switch_core_state_machine.c:403 (sofia/internal/171@128.23.0.1) State NEW
  6. 2012-06-04 12:09:33.010954 [DEBUG] sofia.c:5804 Channel sofia/internal/171@128.23.0.1 entering state [received][100]
  7. 2012-06-04 12:09:33.010954 [DEBUG] sofia.c:5815 Remote SDP:
  8. v=0
  9. o=twinkle 1898994539 1305489274 IN IP4 128.23.0.2
  10. s=-
  11. c=IN IP4 128.23.0.2
  12. t=0 0
  13. m=audio 8002 RTP/AVP 98 97 8 0 3 101
  14. a=rtpmap:98 speex/16000
  15. a=rtpmap:97 speex/8000
  16. a=rtpmap:8 PCMA/8000
  17. a=rtpmap:0 PCMU/8000
  18. a=rtpmap:3 GSM/8000
  19. a=rtpmap:101 telephone-event/8000
  20. a=fmtp:101 0-15
  21. a=ptime:20
  22.  
  23. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:3923 Looking for zrtp-hash
  24. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:3901 Deciding whether to pass zrtp-hash between legs
  25. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:3903 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  26. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:98:16000:20:0]/[PCMA:8:8000:20:64000]
  27. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:98:16000:20:0]/[PCMU:0:8000:20:64000]
  28. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:98:16000:20:0]/[GSM:3:8000:20:13200]
  29. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:97:8000:20:0]/[PCMA:8:8000:20:64000]
  30. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:97:8000:20:0]/[PCMU:0:8000:20:64000]
  31. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:97:8000:20:0]/[GSM:3:8000:20:13200]
  32. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
  33. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:3013 Set Codec sofia/internal/171@128.23.0.1 PCMA/8000 20 ms 160 samples 64000 bits
  34. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_codec.c:111 sofia/internal/171@128.23.0.1 Original read codec set to PCMA:8
  35. 2012-06-04 12:09:33.010954 [DEBUG] sofia_glue.c:5136 Set 2833 dtmf send/recv payload to 101
  36. 2012-06-04 12:09:33.010954 [DEBUG] sofia.c:6043 (sofia/internal/171@128.23.0.1) State Change CS_NEW -> CS_INIT
  37. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  38. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_INIT
  39. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/171@128.23.0.1) State INIT
  40. 2012-06-04 12:09:33.010954 [DEBUG] mod_sofia.c:85 sofia/internal/171@128.23.0.1 SOFIA INIT
  41. 2012-06-04 12:09:33.010954 [DEBUG] mod_sofia.c:125 (sofia/internal/171@128.23.0.1) State Change CS_INIT -> CS_ROUTING
  42. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  43. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/171@128.23.0.1) State INIT going to sleep
  44. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_ROUTING
  45. 2012-06-04 12:09:33.010954 [DEBUG] switch_channel.c:1919 (sofia/internal/171@128.23.0.1) Callstate Change DOWN -> RINGING
  46. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/171@128.23.0.1) State ROUTING
  47. 2012-06-04 12:09:33.010954 [DEBUG] mod_sofia.c:148 sofia/internal/171@128.23.0.1 SOFIA ROUTING
  48. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:104 sofia/internal/171@128.23.0.1 Standard ROUTING
  49. 2012-06-04 12:09:33.010954 [INFO] mod_dialplan_xml.c:485 Processing 171 <171>->7950 in context default
  50. Dialplan: sofia/internal/171@128.23.0.1 parsing [default->unloop] continue=false
  51. Dialplan: sofia/internal/171@128.23.0.1 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  52. Dialplan: sofia/internal/171@128.23.0.1 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  53. Dialplan: sofia/internal/171@128.23.0.1 parsing [default->limit_exeeded] continue=false
  54. Dialplan: sofia/internal/171@128.23.0.1 Regex (FAIL) [limit_exeeded] destination_number(7950) =~ /^limit_exceeded$/ break=on-false
  55. Dialplan: sofia/internal/171@128.23.0.1 parsing [default->default_extension] continue=false
  56. Dialplan: sofia/internal/171@128.23.0.1 Absolute Condition [default_extension]
  57. Dialplan: sofia/internal/171@128.23.0.1 Action lua(main.lua)
  58. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:154 (sofia/internal/171@128.23.0.1) State Change CS_ROUTING -> CS_EXECUTE
  59. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  60. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/171@128.23.0.1) State ROUTING going to sleep
  61. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_EXECUTE
  62. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/171@128.23.0.1) State EXECUTE
  63. 2012-06-04 12:09:33.010954 [DEBUG] mod_sofia.c:241 sofia/internal/171@128.23.0.1 SOFIA EXECUTE
  64. 2012-06-04 12:09:33.010954 [DEBUG] switch_core_state_machine.c:196 sofia/internal/171@128.23.0.1 Standard EXECUTE
  65. EXECUTE sofia/internal/171@128.23.0.1 lua(main.lua)
  66. EXECUTE sofia/internal/171@128.23.0.1 export(external_call=false)
  67. 2012-06-04 12:09:33.010954 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [external_call]=[false]
  68. 2012-06-04 12:09:33.010954 [INFO] switch_cpp.cpp:1227 Llamada internal a 7950
  69. 2012-06-04 12:09:33.038827 [INFO] switch_cpp.cpp:1227 executeOutbound[src=171;dst=7950;outboundId=1]
  70. 2012-06-04 12:09:33.061404 [INFO] switch_cpp.cpp:1227 comprobando si es un ring group
  71. 2012-06-04 12:09:33.061404 [INFO] switch_cpp.cpp:1227 comprobando ringroup 7950
  72. 2012-06-04 12:09:33.061404 [INFO] switch_cpp.cpp:1227 la row vale { ["EXTENSION"] = 7950,}
  73. 2012-06-04 12:09:33.061404 [INFO] switch_cpp.cpp:1227 es un ringGroup
  74. 2012-06-04 12:09:33.061404 [DEBUG] switch_cpp.cpp:1227 [executeRingGroup;ringGroupId=7950] START
  75. 2012-06-04 12:09:33.071347 [INFO] switch_cpp.cpp:1227 el moh vale
  76. EXECUTE sofia/internal/171@128.23.0.1 export(ring_group_id=7950)
  77. 2012-06-04 12:09:33.071347 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [ring_group_id]=[7950]
  78. EXECUTE sofia/internal/171@128.23.0.1 export(ring_group_type=0)
  79. 2012-06-04 12:09:33.071347 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [ring_group_type]=[0]
  80. EXECUTE sofia/internal/171@128.23.0.1 export(ring_group_max_ring_time=45)
  81. 2012-06-04 12:09:33.071347 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [ring_group_max_ring_time]=[45]
  82. EXECUTE sofia/internal/171@128.23.0.1 export(ring_group_extension_ring_time=0)
  83. 2012-06-04 12:09:33.071347 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [ring_group_extension_ring_time]=[0]
  84. 2012-06-04 12:09:33.071347 [INFO] switch_cpp.cpp:1227 el bridgeValue vale [execute_on_originate=limit hash callwaiting 100 1]user/100@${domain_name}
  85. EXECUTE sofia/internal/171@128.23.0.1 bridge([execute_on_originate=limit hash callwaiting 100 1]user/100@128.23.0.1)
  86. 2012-06-04 12:09:33.071347 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [external_call]=[false] to event
  87. 2012-06-04 12:09:33.071347 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_id]=[7950] to event
  88. 2012-06-04 12:09:33.071347 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_type]=[0] to event
  89. 2012-06-04 12:09:33.071347 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_max_ring_time]=[45] to event
  90. 2012-06-04 12:09:33.071347 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_extension_ring_time]=[0] to event
  91. 2012-06-04 12:09:33.071347 [DEBUG] switch_ivr_originate.c:1958 Parsing global variables
  92. 2012-06-04 12:09:33.071347 [DEBUG] switch_ivr_originate.c:2375 Parsing session specific variables
  93. 2012-06-04 12:09:33.071347 [DEBUG] switch_event.c:1470 Parsing variable [execute_on_originate]=[limit hash callwaiting 100 1]
  94. 2012-06-04 12:09:33.132161 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [external_call]=[false] to event
  95. 2012-06-04 12:09:33.132161 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_id]=[7950] to event
  96. 2012-06-04 12:09:33.132161 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_type]=[0] to event
  97. 2012-06-04 12:09:33.132161 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_max_ring_time]=[45] to event
  98. 2012-06-04 12:09:33.132161 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_extension_ring_time]=[0] to event
  99. 2012-06-04 12:09:33.132161 [DEBUG] switch_ivr_originate.c:1958 Parsing global variables
  100. 2012-06-04 12:09:33.132161 [DEBUG] switch_event.c:1470 Parsing variable [sip_invite_domain]=[128.23.0.1]
  101. 2012-06-04 12:09:33.132161 [DEBUG] switch_event.c:1470 Parsing variable [presence_id]=[100@128.23.0.1]
  102. 2012-06-04 12:09:33.132161 [DEBUG] switch_event.c:1470 Parsing variable [transfer_fallback_extension]=[100]
  103. 2012-06-04 12:09:33.132161 [NOTICE] switch_channel.c:926 New Channel sofia/internal/sip:100@128.23.0.63:5065 [01a0d93a-edce-4476-8200-1bdf9417b788]
  104. 2012-06-04 12:09:33.132161 [DEBUG] mod_sofia.c:4720 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_NEW -> CS_INIT
  105. 2012-06-04 12:09:33.132161 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  106. EXECUTE sofia/internal/sip:100@128.23.0.63:5065 limit(hash callwaiting 100 1)
  107. 2012-06-04 12:09:33.132161 [INFO] switch_limit.c:126 incr called: callwaiting_100 max:1, interval:0
  108. 2012-06-04 12:09:33.132161 [INFO] mod_hash.c:202 Usage for callwaiting_100 is now 1/1
  109. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_INIT
  110. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:100@128.23.0.63:5065) State INIT
  111. 2012-06-04 12:09:33.152890 [DEBUG] mod_sofia.c:85 sofia/internal/sip:100@128.23.0.63:5065 SOFIA INIT
  112. 2012-06-04 12:09:33.152890 [DEBUG] mod_sofia.c:125 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_INIT -> CS_ROUTING
  113. 2012-06-04 12:09:33.152890 [INFO] mod_hash.c:304 Usage for callwaiting_100 is now 0
  114. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  115. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:100@128.23.0.63:5065) State INIT going to sleep
  116. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_ROUTING
  117. 2012-06-04 12:09:33.152890 [DEBUG] switch_channel.c:1919 (sofia/internal/sip:100@128.23.0.63:5065) Callstate Change DOWN -> RINGING
  118. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/sip:100@128.23.0.63:5065) State ROUTING
  119. 2012-06-04 12:09:33.152890 [DEBUG] mod_sofia.c:148 sofia/internal/sip:100@128.23.0.63:5065 SOFIA ROUTING
  120. 2012-06-04 12:09:33.152890 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  121. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  122. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/sip:100@128.23.0.63:5065) State ROUTING going to sleep
  123. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_CONSUME_MEDIA
  124. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:100@128.23.0.63:5065) State CONSUME_MEDIA
  125. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:100@128.23.0.63:5065) State CONSUME_MEDIA going to sleep
  126. 2012-06-04 12:09:33.152890 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  127. 2012-06-04 12:09:33.152890 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:100@128.23.0.63:5065 entering state [calling][0]
  128. 2012-06-04 12:09:33.190926 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  129. 2012-06-04 12:09:33.190926 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  130. 2012-06-04 12:09:33.230968 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:100@128.23.0.63:5065 entering state [proceeding][180]
  131. 2012-06-04 12:09:33.230968 [NOTICE] sofia.c:5896 Ring-Ready sofia/internal/sip:100@128.23.0.63:5065!
  132. 2012-06-04 12:09:33.273185 [INFO] switch_ivr_originate.c:1156 Sending early media
  133. 2012-06-04 12:09:33.273185 [DEBUG] sofia_glue.c:3262 AUDIO RTP [sofia/internal/171@128.23.0.1] 128.23.0.1 port 18006 -> 128.23.0.2 port 8002 codec: 8 ms: 20
  134. 2012-06-04 12:09:33.273185 [DEBUG] switch_rtp.c:1688 Not using a timer
  135. 2012-06-04 12:09:33.273185 [DEBUG] sofia_glue.c:3526 Set 2833 dtmf send payload to 101
  136. 2012-06-04 12:09:33.273185 [DEBUG] sofia_glue.c:3532 Set 2833 dtmf receive payload to 101
  137. 2012-06-04 12:09:33.273185 [DEBUG] sofia_glue.c:3559 sofia/internal/171@128.23.0.1 Set rtp dtmf delay to 40
  138. 2012-06-04 12:09:33.273185 [DEBUG] mod_sofia.c:2592 Ring SDP:
  139. v=0
  140. o=FreeSWITCH 1338786567 1338786568 IN IP4 128.23.0.1
  141. s=FreeSWITCH
  142. c=IN IP4 128.23.0.1
  143. t=0 0
  144. m=audio 18006 RTP/AVP 8 101
  145. a=rtpmap:8 PCMA/8000
  146. a=rtpmap:101 telephone-event/8000
  147. a=fmtp:101 0-16
  148. a=silenceSupp:off - - - -
  149. a=ptime:20
  150. a=sendrecv
  151.  
  152. 2012-06-04 12:09:33.273185 [NOTICE] mod_sofia.c:2595 Pre-Answer sofia/internal/171@128.23.0.1!
  153. 2012-06-04 12:09:33.273185 [DEBUG] switch_channel.c:3042 (sofia/internal/171@128.23.0.1) Callstate Change RINGING -> EARLY
  154. 2012-06-04 12:09:33.273185 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  155. 2012-06-04 12:09:33.273185 [DEBUG] switch_ivr_originate.c:1205 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  156. 2012-06-04 12:09:33.273185 [DEBUG] switch_core_codec.c:219 sofia/internal/171@128.23.0.1 Push codec L16:70
  157. 2012-06-04 12:09:33.273185 [DEBUG] switch_ivr_originate.c:1268 Play Ringback Tone [%(2000,4000,440,480)]
  158. 2012-06-04 12:09:33.289882 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  159. 2012-06-04 12:09:33.289882 [DEBUG] sofia.c:5797 Channel sofia/internal/171@128.23.0.1 skipping state [early][183]
  160. 2012-06-04 12:09:33.350990 [DEBUG] switch_rtp.c:3257 Correct ip/port confirmed.
  161. freeswitch@internal>
  162. freeswitch@internal>
  163. freeswitch@internal>
  164. freeswitch@internal>
  165. freeswitch@internal>
  166. freeswitch@internal>
  167. freeswitch@internal>
  168. freeswitch@internal>
  169. freeswitch@internal>
  170. freeswitch@internal>
  171. freeswitch@internal>
  172. freeswitch@internal>
  173. freeswitch@internal>
  174. freeswitch@internal>
  175. freeswitch@internal>
  176. 2012-06-04 12:09:37.410966 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  177. 2012-06-04 12:09:37.410966 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  178. 2012-06-04 12:09:37.491464 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:100@128.23.0.63:5065 entering state [completing][200]
  179. 2012-06-04 12:09:37.491464 [DEBUG] sofia.c:5815 Remote SDP:
  180. v=0
  181. o=- 8787942 8787942 IN IP4 128.23.0.63
  182. s=-
  183. c=IN IP4 128.23.0.63
  184. t=0 0
  185. m=audio 16434 RTP/AVP 0 101
  186. a=rtpmap:0 PCMU/8000
  187. a=rtpmap:101 telephone-event/8000
  188. a=fmtp:101 0-15
  189. a=ptime:30
  190.  
  191. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:3923 Looking for zrtp-hash
  192. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:3901 Deciding whether to pass zrtp-hash between legs
  193. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:3903 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  194. 2012-06-04 12:09:37.491464 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  195. 2012-06-04 12:09:37.491464 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  196. 2012-06-04 12:09:37.491464 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:100@128.23.0.63:5065 entering state [ready][200]
  197. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:3923 Looking for zrtp-hash
  198. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:3901 Deciding whether to pass zrtp-hash between legs
  199. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:3903 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  200. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [PCMU:0:8000:30:64000]/[PCMA:8:8000:20:64000]
  201. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [PCMU:0:8000:30:64000]/[PCMU:0:8000:20:64000]
  202. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [PCMU:0:8000:30:64000]/[GSM:3:8000:20:13200]
  203. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:5068 Substituting codec PCMU@30i@8000h
  204. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:3013 Set Codec sofia/internal/sip:100@128.23.0.63:5065 PCMU/8000 30 ms 240 samples 64000 bits
  205. 2012-06-04 12:09:37.491464 [DEBUG] switch_core_codec.c:111 sofia/internal/sip:100@128.23.0.63:5065 Original read codec set to PCMU:0
  206. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:5129 Set 2833 dtmf send payload to 101
  207. 2012-06-04 12:09:37.491464 [DEBUG] sofia_glue.c:3262 AUDIO RTP [sofia/internal/sip:100@128.23.0.63:5065] 128.23.0.1 port 17616 -> 128.23.0.63 port 16434 codec: 0 ms: 30
  208. 2012-06-04 12:09:37.491464 [DEBUG] switch_rtp.c:1688 Not using a timer
  209. 2012-06-04 12:09:37.502363 [DEBUG] sofia_glue.c:3526 Set 2833 dtmf send payload to 101
  210. 2012-06-04 12:09:37.502363 [DEBUG] sofia_glue.c:3532 Set 2833 dtmf receive payload to 101
  211. 2012-06-04 12:09:37.502363 [DEBUG] sofia_glue.c:3559 sofia/internal/sip:100@128.23.0.63:5065 Set rtp dtmf delay to 40
  212. 2012-06-04 12:09:37.502363 [DEBUG] switch_channel.c:3301 (sofia/internal/sip:100@128.23.0.63:5065) Callstate Change RINGING -> ACTIVE
  213. 2012-06-04 12:09:37.502363 [DEBUG] switch_channel.c:3313 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  214. 2012-06-04 12:09:37.502363 [NOTICE] sofia.c:6539 Channel [sofia/internal/sip:100@128.23.0.63:5065] has been answered
  215. 2012-06-04 12:09:37.502363 [DEBUG] switch_core_codec.c:244 sofia/internal/171@128.23.0.1 Restore previous codec PCMA:8.
  216. 2012-06-04 12:09:37.540975 [DEBUG] mod_sofia.c:754 Local SDP sofia/internal/171@128.23.0.1:
  217. v=0
  218. o=FreeSWITCH 1338786567 1338786569 IN IP4 128.23.0.1
  219. s=FreeSWITCH
  220. c=IN IP4 128.23.0.1
  221. t=0 0
  222. m=audio 18006 RTP/AVP 8 101
  223. a=rtpmap:8 PCMA/8000
  224. a=rtpmap:101 telephone-event/8000
  225. a=fmtp:101 0-16
  226. a=silenceSupp:off - - - -
  227. a=ptime:20
  228. a=sendrecv
  229.  
  230. 2012-06-04 12:09:37.540975 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  231. 2012-06-04 12:09:37.540975 [DEBUG] switch_channel.c:3301 (sofia/internal/171@128.23.0.1) Callstate Change EARLY -> ACTIVE
  232. 2012-06-04 12:09:37.540975 [NOTICE] switch_ivr_originate.c:3284 Channel [sofia/internal/171@128.23.0.1] has been answered
  233. 2012-06-04 12:09:37.540975 [DEBUG] switch_ivr_originate.c:3341 Originate Resulted in Success: [sofia/internal/sip:100@128.23.0.63:5065]
  234. EXECUTE sofia/internal/sip:100@128.23.0.63:5065 limit(hash callwaiting 100 1)
  235. 2012-06-04 12:09:37.540975 [INFO] switch_limit.c:126 incr called: callwaiting_100 max:1, interval:0
  236. 2012-06-04 12:09:37.540975 [INFO] mod_hash.c:202 Usage for callwaiting_100 is now 1/1
  237. 2012-06-04 12:09:37.540975 [DEBUG] switch_ivr_originate.c:3341 Originate Resulted in Success: [sofia/internal/sip:100@128.23.0.63:5065]
  238. 2012-06-04 12:09:37.540975 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  239. 2012-06-04 12:09:37.540975 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  240. 2012-06-04 12:09:37.540975 [DEBUG] switch_ivr_bridge.c:1359 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  241. 2012-06-04 12:09:37.540975 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  242. 2012-06-04 12:09:37.562264 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_EXCHANGE_MEDIA
  243. 2012-06-04 12:09:37.562264 [DEBUG] switch_core_state_machine.c:443 (sofia/internal/sip:100@128.23.0.63:5065) State EXCHANGE_MEDIA
  244. 2012-06-04 12:09:37.562264 [DEBUG] mod_sofia.c:582 SOFIA EXCHANGE_MEDIA
  245. 2012-06-04 12:09:37.562264 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  246. 2012-06-04 12:09:37.562264 [DEBUG] sofia.c:5804 Channel sofia/internal/171@128.23.0.1 entering state [completed][200]
  247. 2012-06-04 12:09:37.571526 [DEBUG] switch_core_io.c:1148 Engaging Write Buffer at 480 bytes to accommodate 320->480
  248. 2012-06-04 12:09:37.597373 [DEBUG] switch_rtp.c:3257 Correct ip/port confirmed.
  249. 2012-06-04 12:09:37.597373 [DEBUG] switch_core_io.c:1148 Engaging Write Buffer at 320 bytes to accommodate 320->320
  250. 2012-06-04 12:09:37.597373 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  251. 2012-06-04 12:09:37.597373 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  252. 2012-06-04 12:09:37.597373 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  253. 2012-06-04 12:09:37.597373 [DEBUG] sofia.c:5804 Channel sofia/internal/171@128.23.0.1 entering state [ready][200]
  254. 2012-06-04 12:09:37.631012 [DEBUG] switch_core_session.c:839 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  255. 2012-06-04 12:09:37.631012 [DEBUG] switch_core_session.c:839 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  256. 2012-06-04 12:09:37.721157 [WARNING] mod_sofia.c:1158 Asynchronous PTIME not supported, changing our end from 30 to 20
  257. 2012-06-04 12:09:37.721157 [DEBUG] sofia_glue.c:2931 Changing Codec from PCMU@30ms@8000hz to PCMU@20ms@8000hz
  258. 2012-06-04 12:09:37.781156 [DEBUG] sofia_glue.c:3013 Set Codec sofia/internal/sip:100@128.23.0.63:5065 PCMU/8000 20 ms 160 samples 64000 bits
  259. freeswitch@internal>
  260. freeswitch@internal>
  261. freeswitch@internal>
  262. freeswitch@internal>
  263. freeswitch@internal>
  264. freeswitch@internal>
  265. freeswitch@internal>
  266. freeswitch@internal>
  267. freeswitch@internal>
  268. freeswitch@internal>
  269. freeswitch@internal>
  270. freeswitch@internal>
  271. 2012-06-04 12:09:41.870957 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  272. 2012-06-04 12:09:41.870957 [DEBUG] switch_channel.c:2899 (sofia/internal/sip:100@128.23.0.63:5065) Callstate Change ACTIVE -> HANGUP
  273. 2012-06-04 12:09:41.870957 [NOTICE] sofia.c:711 Hangup sofia/internal/sip:100@128.23.0.63:5065 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  274. 2012-06-04 12:09:41.870957 [DEBUG] switch_channel.c:2922 Send signal sofia/internal/sip:100@128.23.0.63:5065 [KILL]
  275. 2012-06-04 12:09:41.870957 [INFO] mod_hash.c:304 Usage for callwaiting_100 is now 0
  276. 2012-06-04 12:09:41.870957 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  277. 2012-06-04 12:09:41.883898 [DEBUG] switch_ivr_bridge.c:588 BRIDGE THREAD DONE [sofia/internal/sip:100@128.23.0.63:5065]
  278. 2012-06-04 12:09:41.883898 [DEBUG] switch_ivr_bridge.c:613 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  279. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_state_machine.c:443 (sofia/internal/sip:100@128.23.0.63:5065) State EXCHANGE_MEDIA going to sleep
  280. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_HANGUP
  281. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/sip:100@128.23.0.63:5065) State HANGUP
  282. 2012-06-04 12:09:41.883898 [DEBUG] mod_sofia.c:469 Channel sofia/internal/sip:100@128.23.0.63:5065 hanging up, cause: NORMAL_CLEARING
  283. 2012-06-04 12:09:41.883898 [DEBUG] switch_ivr_bridge.c:588 BRIDGE THREAD DONE [sofia/internal/171@128.23.0.1]
  284. 2012-06-04 12:09:41.883898 [DEBUG] switch_ivr_bridge.c:613 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  285. 2012-06-04 12:09:41.883898 [DEBUG] switch_ivr_bridge.c:1456 sofia/internal/sip:100@128.23.0.63:5065 skip receive message [UNBRIDGE] (channel is hungup already)
  286. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  287. 2012-06-04 12:09:41.883898 [DEBUG] switch_channel.c:2899 (sofia/internal/171@128.23.0.1) Callstate Change ACTIVE -> HANGUP
  288. 2012-06-04 12:09:41.883898 [NOTICE] switch_ivr_bridge.c:1483 Hangup sofia/internal/171@128.23.0.1 [CS_EXECUTE] [NORMAL_CLEARING]
  289. 2012-06-04 12:09:41.883898 [DEBUG] switch_channel.c:2922 Send signal sofia/internal/171@128.23.0.1 [KILL]
  290. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  291. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_session.c:2333 sofia/internal/171@128.23.0.1 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  292. 2012-06-04 12:09:41.883898 [DEBUG] switch_cpp.cpp:1007 sofia/internal/171@128.23.0.1 destroy/unlink session from object
  293. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_session.c:2333 sofia/internal/171@128.23.0.1 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  294. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/171@128.23.0.1) State EXECUTE going to sleep
  295. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_HANGUP
  296. 2012-06-04 12:09:41.883898 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/171@128.23.0.1) State HANGUP
  297. 2012-06-04 12:09:41.883898 [DEBUG] mod_sofia.c:463 sofia/internal/171@128.23.0.1 Overriding SIP cause 480 with 200 from the other leg
  298. 2012-06-04 12:09:41.883898 [DEBUG] mod_sofia.c:469 Channel sofia/internal/171@128.23.0.1 hanging up, cause: NORMAL_CLEARING
  299. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:47 sofia/internal/sip:100@128.23.0.63:5065 Standard HANGUP, cause: NORMAL_CLEARING
  300. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/sip:100@128.23.0.63:5065) State HANGUP going to sleep
  301. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_HANGUP -> CS_REPORTING
  302. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  303. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_REPORTING
  304. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/sip:100@128.23.0.63:5065) State REPORTING
  305. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:79 sofia/internal/sip:100@128.23.0.63:5065 Standard REPORTING, cause: NORMAL_CLEARING
  306. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/sip:100@128.23.0.63:5065) State REPORTING going to sleep
  307. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_REPORTING -> CS_DESTROY
  308. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  309. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_session.c:1428 Session 77 (sofia/internal/sip:100@128.23.0.63:5065) Locked, Waiting on external entities
  310. 2012-06-04 12:09:41.951543 [NOTICE] switch_core_session.c:1446 Session 77 (sofia/internal/sip:100@128.23.0.63:5065) Ended
  311. 2012-06-04 12:09:41.951543 [NOTICE] switch_core_session.c:1448 Close Channel sofia/internal/sip:100@128.23.0.63:5065 [CS_DESTROY]
  312. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:514 (sofia/internal/sip:100@128.23.0.63:5065) Callstate Change HANGUP -> DOWN
  313. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:517 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_DESTROY
  314. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/sip:100@128.23.0.63:5065) State DESTROY
  315. 2012-06-04 12:09:41.951543 [DEBUG] mod_sofia.c:374 sofia/internal/sip:100@128.23.0.63:5065 SOFIA DESTROY
  316. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:86 sofia/internal/sip:100@128.23.0.63:5065 Standard DESTROY
  317. 2012-06-04 12:09:41.951543 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/sip:100@128.23.0.63:5065) State DESTROY going to sleep
  318. 2012-06-04 12:09:41.991123 [DEBUG] mod_sofia.c:517 Sending BYE to sofia/internal/171@128.23.0.1
  319. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:47 sofia/internal/171@128.23.0.1 Standard HANGUP, cause: NORMAL_CLEARING
  320. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/171@128.23.0.1) State HANGUP going to sleep
  321. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/171@128.23.0.1) State Change CS_HANGUP -> CS_REPORTING
  322. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  323. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_REPORTING
  324. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/171@128.23.0.1) State REPORTING
  325. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:79 sofia/internal/171@128.23.0.1 Standard REPORTING, cause: NORMAL_CLEARING
  326. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/171@128.23.0.1) State REPORTING going to sleep
  327. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/171@128.23.0.1) State Change CS_REPORTING -> CS_DESTROY
  328. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  329. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_session.c:1428 Session 76 (sofia/internal/171@128.23.0.1) Locked, Waiting on external entities
  330. 2012-06-04 12:09:41.991123 [NOTICE] switch_core_session.c:1446 Session 76 (sofia/internal/171@128.23.0.1) Ended
  331. 2012-06-04 12:09:41.991123 [NOTICE] switch_core_session.c:1448 Close Channel sofia/internal/171@128.23.0.1 [CS_DESTROY]
  332. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:514 (sofia/internal/171@128.23.0.1) Callstate Change HANGUP -> DOWN
  333. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:517 (sofia/internal/171@128.23.0.1) Running State Change CS_DESTROY
  334. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/171@128.23.0.1) State DESTROY
  335. 2012-06-04 12:09:41.991123 [DEBUG] mod_sofia.c:374 sofia/internal/171@128.23.0.1 SOFIA DESTROY
  336. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:86 sofia/internal/171@128.23.0.1 Standard DESTROY
  337. 2012-06-04 12:09:41.991123 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/171@128.23.0.1) State DESTROY going to sleep
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement
Public Pastes
Advertisement
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!