API tools faq
paste
Advertisement
josefu

app execute_on_origiate multiple members

josefu
Jun 4th, 2012
424
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
FreeSWITCH 39.25 KB | None | 0 0
raw download clone embed print report
  1. span class="re2"> 2012-06-04 12:12:44.152962 [DEBUG] sofia.c:7880 IP 128.23.0.2 Rejected by acl "domains". Falling back to Digest auth.
  2. 2012-06-04 12:12:44.200951 [DEBUG] sofia.c:7880 IP 128.23.0.2 Rejected by acl "domains". Falling back to Digest auth.
  3. 2012-06-04 12:12:44.260958 [NOTICE] switch_channel.c:926 New Channel sofia/internal/171@128.23.0.1 [abe9750b-62a4-43e9-a4cb-5009250ef126]
  4. 2012-06-04 12:12:44.260958 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_NEW
  5. 2012-06-04 12:12:44.260958 [DEBUG] switch_core_state_machine.c:403 (sofia/internal/171@128.23.0.1) State NEW
  6. 2012-06-04 12:12:44.290950 [DEBUG] sofia.c:5804 Channel sofia/internal/171@128.23.0.1 entering state [received][100]
  7. 2012-06-04 12:12:44.290950 [DEBUG] sofia.c:5815 Remote SDP:
  8. v=0
  9. o=twinkle 1013166781 235205730 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:12:44.290950 [DEBUG] sofia_glue.c:3923 Looking for zrtp-hash
  24. 2012-06-04 12:12:44.290950 [DEBUG] sofia_glue.c:3901 Deciding whether to pass zrtp-hash between legs
  25. 2012-06-04 12:12:44.290950 [DEBUG] sofia_glue.c:3903 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  26. 2012-06-04 12:12:44.290950 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:98:16000:20:0]/[PCMA:8:8000:20:64000]
  27. 2012-06-04 12:12:44.290950 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:98:16000:20:0]/[PCMU:0:8000:20:64000]
  28. 2012-06-04 12:12:44.290950 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:98:16000:20:0]/[GSM:3:8000:20:13200]
  29. 2012-06-04 12:12:44.290950 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:97:8000:20:0]/[PCMA:8:8000:20:64000]
  30. 2012-06-04 12:12:44.290950 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:97:8000:20:0]/[PCMU:0:8000:20:64000]
  31. 2012-06-04 12:12:44.290950 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [speex:97:8000:20:0]/[GSM:3:8000:20:13200]
  32. 2012-06-04 12:12:44.290950 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
  33. 2012-06-04 12:12:44.290950 [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:12:44.290950 [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:12:44.290950 [DEBUG] sofia_glue.c:5136 Set 2833 dtmf send/recv payload to 101
  36. 2012-06-04 12:12:44.290950 [DEBUG] sofia.c:6043 (sofia/internal/171@128.23.0.1) State Change CS_NEW -> CS_INIT
  37. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  38. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_INIT
  39. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/171@128.23.0.1) State INIT
  40. 2012-06-04 12:12:44.290950 [DEBUG] mod_sofia.c:85 sofia/internal/171@128.23.0.1 SOFIA INIT
  41. 2012-06-04 12:12:44.290950 [DEBUG] mod_sofia.c:125 (sofia/internal/171@128.23.0.1) State Change CS_INIT -> CS_ROUTING
  42. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  43. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/171@128.23.0.1) State INIT going to sleep
  44. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_ROUTING
  45. 2012-06-04 12:12:44.290950 [DEBUG] switch_channel.c:1919 (sofia/internal/171@128.23.0.1) Callstate Change DOWN -> RINGING
  46. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/171@128.23.0.1) State ROUTING
  47. 2012-06-04 12:12:44.290950 [DEBUG] mod_sofia.c:148 sofia/internal/171@128.23.0.1 SOFIA ROUTING
  48. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_state_machine.c:104 sofia/internal/171@128.23.0.1 Standard ROUTING
  49. 2012-06-04 12:12:44.290950 [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:12:44.290950 [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:12:44.290950 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  60. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/171@128.23.0.1) State ROUTING going to sleep
  61. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_EXECUTE
  62. 2012-06-04 12:12:44.290950 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/171@128.23.0.1) State EXECUTE
  63. 2012-06-04 12:12:44.290950 [DEBUG] mod_sofia.c:241 sofia/internal/171@128.23.0.1 SOFIA EXECUTE
  64. 2012-06-04 12:12:44.290950 [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:12:44.290950 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [external_call]=[false]
  68. 2012-06-04 12:12:44.290950 [INFO] switch_cpp.cpp:1227 Llamada internal a 7950
  69. 2012-06-04 12:12:44.341026 [INFO] switch_cpp.cpp:1227 executeOutbound[src=171;dst=7950;outboundId=1]
  70. 2012-06-04 12:12:44.351397 [INFO] switch_cpp.cpp:1227 comprobando si es un ring group
  71. 2012-06-04 12:12:44.351397 [INFO] switch_cpp.cpp:1227 comprobando ringroup 7950
  72. 2012-06-04 12:12:44.351397 [INFO] switch_cpp.cpp:1227 la row vale { ["EXTENSION"] = 7950,}
  73. 2012-06-04 12:12:44.351397 [INFO] switch_cpp.cpp:1227 es un ringGroup
  74. 2012-06-04 12:12:44.351397 [DEBUG] switch_cpp.cpp:1227 [executeRingGroup;ringGroupId=7950] START
  75. 2012-06-04 12:12:44.351397 [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:12:44.351397 [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:12:44.351397 [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:12:44.351397 [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:12:44.351397 [DEBUG] switch_channel.c:1093 EXPORT (export_vars) [ring_group_extension_ring_time]=[0]
  84. 2012-06-04 12:12:44.362482 [INFO] switch_cpp.cpp:1227 el bridgeValue vale [execute_on_originate=limit hash callwaiting 100 1]user/100@${domain_name},[execute_on_originate=limit hash callwaiting 171 1]user/171@${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,[execute_on_originate=limit hash callwaiting 171 1]user/171@128.23.0.1)
  86. 2012-06-04 12:12:44.362482 [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:12:44.362482 [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:12:44.362482 [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:12:44.362482 [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:12:44.362482 [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:12:44.362482 [DEBUG] switch_ivr_originate.c:1958 Parsing global variables
  92. 2012-06-04 12:12:44.362482 [DEBUG] switch_ivr_originate.c:2375 Parsing session specific variables
  93. 2012-06-04 12:12:44.362482 [DEBUG] switch_event.c:1470 Parsing variable [execute_on_originate]=[limit hash callwaiting 100 1]
  94. 2012-06-04 12:12:44.405463 [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:12:44.405463 [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:12:44.405463 [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:12:44.405463 [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:12:44.405463 [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:12:44.405463 [DEBUG] switch_ivr_originate.c:1958 Parsing global variables
  100. 2012-06-04 12:12:44.405463 [DEBUG] switch_event.c:1470 Parsing variable [sip_invite_domain]=[128.23.0.1]
  101. 2012-06-04 12:12:44.405463 [DEBUG] switch_event.c:1470 Parsing variable [presence_id]=[100@128.23.0.1]
  102. 2012-06-04 12:12:44.405463 [DEBUG] switch_event.c:1470 Parsing variable [transfer_fallback_extension]=[100]
  103. 2012-06-04 12:12:44.405463 [NOTICE] switch_channel.c:926 New Channel sofia/internal/sip:100@128.23.0.63:5065 [cdd9f95d-1614-43b7-9d70-ba2c685682b0]
  104. 2012-06-04 12:12:44.405463 [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:12:44.405463 [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:12:44.405463 [INFO] switch_limit.c:126 incr called: callwaiting_100 max:1, interval:0
  108. 2012-06-04 12:12:44.405463 [INFO] mod_hash.c:202 Usage for callwaiting_100 is now 1/1
  109. EXECUTE sofia/internal/sip:100@128.23.0.63:5065 limit(hash callwaiting 100 1)
  110. 2012-06-04 12:12:44.405463 [INFO] switch_limit.c:126 incr called: callwaiting_100 max:1, interval:0
  111. 2012-06-04 12:12:44.405463 [DEBUG] switch_ivr_originate.c:2375 Parsing session specific variables
  112. 2012-06-04 12:12:44.405463 [DEBUG] switch_event.c:1470 Parsing variable [execute_on_originate]=[limit hash callwaiting 171 1]
  113. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_INIT
  114. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:100@128.23.0.63:5065) State INIT
  115. 2012-06-04 12:12:44.419600 [DEBUG] mod_sofia.c:85 sofia/internal/sip:100@128.23.0.63:5065 SOFIA INIT
  116. 2012-06-04 12:12:44.419600 [DEBUG] mod_sofia.c:125 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_INIT -> CS_ROUTING
  117. 2012-06-04 12:12:44.419600 [INFO] mod_hash.c:304 Usage for callwaiting_100 is now 0
  118. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  119. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:100@128.23.0.63:5065) State INIT going to sleep
  120. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_ROUTING
  121. 2012-06-04 12:12:44.419600 [DEBUG] switch_channel.c:1919 (sofia/internal/sip:100@128.23.0.63:5065) Callstate Change DOWN -> RINGING
  122. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/sip:100@128.23.0.63:5065) State ROUTING
  123. 2012-06-04 12:12:44.419600 [DEBUG] mod_sofia.c:148 sofia/internal/sip:100@128.23.0.63:5065 SOFIA ROUTING
  124. 2012-06-04 12:12:44.419600 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  125. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  126. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/sip:100@128.23.0.63:5065) State ROUTING going to sleep
  127. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_CONSUME_MEDIA
  128. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:100@128.23.0.63:5065) State CONSUME_MEDIA
  129. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:100@128.23.0.63:5065) State CONSUME_MEDIA going to sleep
  130. 2012-06-04 12:12:44.419600 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  131. 2012-06-04 12:12:44.419600 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:100@128.23.0.63:5065 entering state [calling][0]
  132. 2012-06-04 12:12:44.459203 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  133. 2012-06-04 12:12:44.459203 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  134. 2012-06-04 12:12:44.459203 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [external_call]=[false] to event
  135. 2012-06-04 12:12:44.459203 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_id]=[7950] to event
  136. 2012-06-04 12:12:44.459203 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_type]=[0] to event
  137. 2012-06-04 12:12:44.459203 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_max_ring_time]=[45] to event
  138. 2012-06-04 12:12:44.459203 [DEBUG] switch_channel.c:1047 sofia/internal/171@128.23.0.1 EXPORTING[export_vars] [ring_group_extension_ring_time]=[0] to event
  139. 2012-06-04 12:12:44.459203 [DEBUG] switch_ivr_originate.c:1958 Parsing global variables
  140. 2012-06-04 12:12:44.459203 [DEBUG] switch_event.c:1470 Parsing variable [sip_invite_domain]=[128.23.0.1]
  141. 2012-06-04 12:12:44.459203 [DEBUG] switch_event.c:1470 Parsing variable [presence_id]=[171@128.23.0.1]
  142. 2012-06-04 12:12:44.459203 [DEBUG] switch_event.c:1470 Parsing variable [transfer_fallback_extension]=[171]
  143. 2012-06-04 12:12:44.459203 [NOTICE] switch_channel.c:926 New Channel sofia/internal/sip:171@128.23.0.2 [6d99bb53-5c37-4822-92b1-6f31c853667c]
  144. 2012-06-04 12:12:44.459203 [DEBUG] mod_sofia.c:4720 (sofia/internal/sip:171@128.23.0.2) State Change CS_NEW -> CS_INIT
  145. 2012-06-04 12:12:44.459203 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:171@128.23.0.2 [BREAK]
  146. EXECUTE sofia/internal/sip:171@128.23.0.2 limit(hash callwaiting 171 1)
  147. 2012-06-04 12:12:44.459203 [INFO] switch_limit.c:126 incr called: callwaiting_171 max:1, interval:0
  148. 2012-06-04 12:12:44.459203 [INFO] mod_hash.c:202 Usage for callwaiting_171 is now 1/1
  149. EXECUTE sofia/internal/sip:171@128.23.0.2 limit(hash callwaiting 171 1)
  150. 2012-06-04 12:12:44.459203 [INFO] switch_limit.c:126 incr called: callwaiting_171 max:1, interval:0
  151. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:171@128.23.0.2) Running State Change CS_INIT
  152. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:171@128.23.0.2) State INIT
  153. 2012-06-04 12:12:44.495190 [DEBUG] mod_sofia.c:85 sofia/internal/sip:171@128.23.0.2 SOFIA INIT
  154. 2012-06-04 12:12:44.495190 [DEBUG] mod_sofia.c:125 (sofia/internal/sip:171@128.23.0.2) State Change CS_INIT -> CS_ROUTING
  155. 2012-06-04 12:12:44.495190 [INFO] mod_hash.c:304 Usage for callwaiting_171 is now 0
  156. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:171@128.23.0.2 [BREAK]
  157. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_state_machine.c:424 (sofia/internal/sip:171@128.23.0.2) State INIT going to sleep
  158. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:171@128.23.0.2) Running State Change CS_ROUTING
  159. 2012-06-04 12:12:44.495190 [DEBUG] switch_channel.c:1919 (sofia/internal/sip:171@128.23.0.2) Callstate Change DOWN -> RINGING
  160. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/sip:171@128.23.0.2) State ROUTING
  161. 2012-06-04 12:12:44.495190 [DEBUG] mod_sofia.c:148 sofia/internal/sip:171@128.23.0.2 SOFIA ROUTING
  162. 2012-06-04 12:12:44.495190 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sip:171@128.23.0.2) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  163. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:171@128.23.0.2 [BREAK]
  164. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/sip:171@128.23.0.2) State ROUTING going to sleep
  165. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:171@128.23.0.2) Running State Change CS_CONSUME_MEDIA
  166. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:171@128.23.0.2) State CONSUME_MEDIA
  167. 2012-06-04 12:12:44.495190 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:171@128.23.0.2) State CONSUME_MEDIA going to sleep
  168. 2012-06-04 12:12:44.502524 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:171@128.23.0.2 [BREAK]
  169. 2012-06-04 12:12:44.502524 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:171@128.23.0.2 entering state [calling][0]
  170. 2012-06-04 12:12:44.502524 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:171@128.23.0.2 [BREAK]
  171. 2012-06-04 12:12:44.502524 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:171@128.23.0.2 [BREAK]
  172. 2012-06-04 12:12:44.521391 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:100@128.23.0.63:5065 entering state [proceeding][180]
  173. 2012-06-04 12:12:44.521391 [NOTICE] sofia.c:5896 Ring-Ready sofia/internal/sip:100@128.23.0.63:5065!
  174. 2012-06-04 12:12:44.561006 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:171@128.23.0.2 entering state [proceeding][180]
  175. 2012-06-04 12:12:44.561006 [NOTICE] sofia.c:5896 Ring-Ready sofia/internal/sip:171@128.23.0.2!
  176. 2012-06-04 12:12:44.591006 [INFO] switch_ivr_originate.c:1156 Sending early media
  177. 2012-06-04 12:12:44.591006 [DEBUG] sofia_glue.c:3262 AUDIO RTP [sofia/internal/171@128.23.0.1] 128.23.0.1 port 18956 -> 128.23.0.2 port 8002 codec: 8 ms: 20
  178. 2012-06-04 12:12:44.591006 [DEBUG] switch_rtp.c:1688 Not using a timer
  179. 2012-06-04 12:12:44.606084 [DEBUG] sofia_glue.c:3526 Set 2833 dtmf send payload to 101
  180. 2012-06-04 12:12:44.606084 [DEBUG] sofia_glue.c:3532 Set 2833 dtmf receive payload to 101
  181. 2012-06-04 12:12:44.606084 [DEBUG] sofia_glue.c:3559 sofia/internal/171@128.23.0.1 Set rtp dtmf delay to 40
  182. 2012-06-04 12:12:44.606084 [DEBUG] mod_sofia.c:2592 Ring SDP:
  183. v=0
  184. o=FreeSWITCH 1338785808 1338785809 IN IP4 128.23.0.1
  185. s=FreeSWITCH
  186. c=IN IP4 128.23.0.1
  187. t=0 0
  188. m=audio 18956 RTP/AVP 8 101
  189. a=rtpmap:8 PCMA/8000
  190. a=rtpmap:101 telephone-event/8000
  191. a=fmtp:101 0-16
  192. a=silenceSupp:off - - - -
  193. a=ptime:20
  194. a=sendrecv
  195.  
  196. 2012-06-04 12:12:44.606084 [NOTICE] mod_sofia.c:2595 Pre-Answer sofia/internal/171@128.23.0.1!
  197. 2012-06-04 12:12:44.606084 [DEBUG] switch_channel.c:3042 (sofia/internal/171@128.23.0.1) Callstate Change RINGING -> EARLY
  198. 2012-06-04 12:12:44.606084 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  199. 2012-06-04 12:12:44.606084 [DEBUG] switch_ivr_originate.c:1205 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  200. 2012-06-04 12:12:44.606084 [DEBUG] switch_core_codec.c:219 sofia/internal/171@128.23.0.1 Push codec L16:70
  201. 2012-06-04 12:12:44.606084 [DEBUG] switch_ivr_originate.c:1268 Play Ringback Tone [%(2000,4000,440,480)]
  202. 2012-06-04 12:12:44.637575 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  203. 2012-06-04 12:12:44.642263 [DEBUG] sofia.c:5797 Channel sofia/internal/171@128.23.0.1 skipping state [early][183]
  204. 2012-06-04 12:12:44.711278 [DEBUG] switch_rtp.c:3257 Correct ip/port confirmed.
  205. freeswitch@internal>
  206. freeswitch@internal>
  207. freeswitch@internal>
  208. freeswitch@internal>
  209. freeswitch@internal>
  210. freeswitch@internal>
  211. freeswitch@internal>
  212. freeswitch@internal>
  213. freeswitch@internal>
  214. freeswitch@internal>
  215. freeswitch@internal>
  216. freeswitch@internal>
  217. freeswitch@internal>
  218. freeswitch@internal>
  219. freeswitch@internal>
  220. freeswitch@internal>
  221. 2012-06-04 12:12:52.181132 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  222. 2012-06-04 12:12:52.181132 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  223. 2012-06-04 12:12:52.225487 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:100@128.23.0.63:5065 entering state [completing][200]
  224. 2012-06-04 12:12:52.225487 [DEBUG] sofia.c:5815 Remote SDP:
  225. v=0
  226. o=- 8807067 8807067 IN IP4 128.23.0.63
  227. s=-
  228. c=IN IP4 128.23.0.63
  229. t=0 0
  230. m=audio 16436 RTP/AVP 0 101
  231. a=rtpmap:0 PCMU/8000
  232. a=rtpmap:101 telephone-event/8000
  233. a=fmtp:101 0-15
  234. a=ptime:30
  235.  
  236. 2012-06-04 12:12:52.225487 [DEBUG] sofia_glue.c:3923 Looking for zrtp-hash
  237. 2012-06-04 12:12:52.225487 [DEBUG] sofia_glue.c:3901 Deciding whether to pass zrtp-hash between legs
  238. 2012-06-04 12:12:52.225487 [DEBUG] sofia_glue.c:3903 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  239. 2012-06-04 12:12:52.225487 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  240. 2012-06-04 12:12:52.225487 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  241. 2012-06-04 12:12:52.225487 [DEBUG] sofia.c:5804 Channel sofia/internal/sip:100@128.23.0.63:5065 entering state [ready][200]
  242. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:3923 Looking for zrtp-hash
  243. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:3901 Deciding whether to pass zrtp-hash between legs
  244. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:3903 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  245. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [PCMU:0:8000:30:64000]/[PCMA:8:8000:20:64000]
  246. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [PCMU:0:8000:30:64000]/[PCMU:0:8000:20:64000]
  247. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:5015 Audio Codec Compare [PCMU:0:8000:30:64000]/[GSM:3:8000:20:13200]
  248. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:5068 Substituting codec PCMU@30i@8000h
  249. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:3013 Set Codec sofia/internal/sip:100@128.23.0.63:5065 PCMU/8000 30 ms 240 samples 64000 bits
  250. 2012-06-04 12:12:52.230991 [DEBUG] switch_core_codec.c:111 sofia/internal/sip:100@128.23.0.63:5065 Original read codec set to PCMU:0
  251. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:5129 Set 2833 dtmf send payload to 101
  252. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:3262 AUDIO RTP [sofia/internal/sip:100@128.23.0.63:5065] 128.23.0.1 port 24292 -> 128.23.0.63 port 16436 codec: 0 ms: 30
  253. 2012-06-04 12:12:52.230991 [DEBUG] switch_rtp.c:1688 Not using a timer
  254. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:3526 Set 2833 dtmf send payload to 101
  255. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:3532 Set 2833 dtmf receive payload to 101
  256. 2012-06-04 12:12:52.230991 [DEBUG] sofia_glue.c:3559 sofia/internal/sip:100@128.23.0.63:5065 Set rtp dtmf delay to 40
  257. 2012-06-04 12:12:52.230991 [DEBUG] switch_channel.c:3301 (sofia/internal/sip:100@128.23.0.63:5065) Callstate Change RINGING -> ACTIVE
  258. 2012-06-04 12:12:52.230991 [DEBUG] switch_channel.c:3313 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  259. 2012-06-04 12:12:52.230991 [NOTICE] sofia.c:6539 Channel [sofia/internal/sip:100@128.23.0.63:5065] has been answered
  260. 2012-06-04 12:12:52.247927 [DEBUG] switch_core_codec.c:244 sofia/internal/171@128.23.0.1 Restore previous codec PCMA:8.
  261. 2012-06-04 12:12:52.247927 [DEBUG] switch_channel.c:2899 (sofia/internal/sip:171@128.23.0.2) Callstate Change RINGING -> HANGUP
  262. 2012-06-04 12:12:52.247927 [NOTICE] switch_ivr_originate.c:3257 Hangup sofia/internal/sip:171@128.23.0.2 [CS_CONSUME_MEDIA] [LOSE_RACE]
  263. 2012-06-04 12:12:52.247927 [DEBUG] switch_channel.c:2922 Send signal sofia/internal/sip:171@128.23.0.2 [KILL]
  264. 2012-06-04 12:12:52.247927 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:171@128.23.0.2 [BREAK]
  265. 2012-06-04 12:12:52.247927 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:171@128.23.0.2) Running State Change CS_HANGUP
  266. 2012-06-04 12:12:52.247927 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/sip:171@128.23.0.2) State HANGUP
  267. 2012-06-04 12:12:52.247927 [DEBUG] mod_sofia.c:469 Channel sofia/internal/sip:171@128.23.0.2 hanging up, cause: LOSE_RACE
  268. 2012-06-04 12:12:52.291300 [DEBUG] mod_sofia.c:754 Local SDP sofia/internal/171@128.23.0.1:
  269. v=0
  270. o=FreeSWITCH 1338785808 1338785810 IN IP4 128.23.0.1
  271. s=FreeSWITCH
  272. c=IN IP4 128.23.0.1
  273. t=0 0
  274. m=audio 18956 RTP/AVP 8 101
  275. a=rtpmap:8 PCMA/8000
  276. a=rtpmap:101 telephone-event/8000
  277. a=fmtp:101 0-16
  278. a=silenceSupp:off - - - -
  279. a=ptime:20
  280. a=sendrecv
  281.  
  282. 2012-06-04 12:12:52.291300 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  283. 2012-06-04 12:12:52.291300 [DEBUG] switch_channel.c:3301 (sofia/internal/171@128.23.0.1) Callstate Change EARLY -> ACTIVE
  284. 2012-06-04 12:12:52.291300 [NOTICE] switch_ivr_originate.c:3284 Channel [sofia/internal/171@128.23.0.1] has been answered
  285. 2012-06-04 12:12:52.291300 [DEBUG] switch_ivr_originate.c:3341 Originate Resulted in Success: [sofia/internal/sip:100@128.23.0.63:5065]
  286. 2012-06-04 12:12:52.291300 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  287. 2012-06-04 12:12:52.291300 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  288. 2012-06-04 12:12:52.291300 [DEBUG] switch_ivr_bridge.c:1359 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  289. 2012-06-04 12:12:52.291300 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  290. 2012-06-04 12:12:52.304363 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_EXCHANGE_MEDIA
  291. 2012-06-04 12:12:52.304363 [DEBUG] switch_core_state_machine.c:443 (sofia/internal/sip:100@128.23.0.63:5065) State EXCHANGE_MEDIA
  292. 2012-06-04 12:12:52.304363 [DEBUG] mod_sofia.c:582 SOFIA EXCHANGE_MEDIA
  293. 2012-06-04 12:12:52.304363 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  294. 2012-06-04 12:12:52.304363 [DEBUG] sofia.c:5804 Channel sofia/internal/171@128.23.0.1 entering state [completed][200]
  295. 2012-06-04 12:12:52.341684 [DEBUG] switch_rtp.c:3257 Correct ip/port confirmed.
  296. 2012-06-04 12:12:52.341684 [DEBUG] switch_core_io.c:1148 Engaging Write Buffer at 320 bytes to accommodate 320->320
  297. 2012-06-04 12:12:52.341684 [DEBUG] switch_core_io.c:1148 Engaging Write Buffer at 480 bytes to accommodate 320->480
  298. 2012-06-04 12:12:52.341684 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  299. 2012-06-04 12:12:52.341684 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  300. 2012-06-04 12:12:52.341684 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  301. 2012-06-04 12:12:52.341684 [DEBUG] sofia.c:5804 Channel sofia/internal/171@128.23.0.1 entering state [ready][200]
  302. 2012-06-04 12:12:52.391304 [DEBUG] mod_sofia.c:527 Sending CANCEL to sofia/internal/sip:171@128.23.0.2
  303. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:47 sofia/internal/sip:171@128.23.0.2 Standard HANGUP, cause: LOSE_RACE
  304. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/sip:171@128.23.0.2) State HANGUP going to sleep
  305. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/sip:171@128.23.0.2) State Change CS_HANGUP -> CS_REPORTING
  306. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:171@128.23.0.2 [BREAK]
  307. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:171@128.23.0.2) Running State Change CS_REPORTING
  308. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/sip:171@128.23.0.2) State REPORTING
  309. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:79 sofia/internal/sip:171@128.23.0.2 Standard REPORTING, cause: LOSE_RACE
  310. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/sip:171@128.23.0.2) State REPORTING going to sleep
  311. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/sip:171@128.23.0.2) State Change CS_REPORTING -> CS_DESTROY
  312. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:171@128.23.0.2 [BREAK]
  313. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_session.c:1428 Session 80 (sofia/internal/sip:171@128.23.0.2) Locked, Waiting on external entities
  314. 2012-06-04 12:12:52.391304 [NOTICE] switch_core_session.c:1446 Session 80 (sofia/internal/sip:171@128.23.0.2) Ended
  315. 2012-06-04 12:12:52.391304 [NOTICE] switch_core_session.c:1448 Close Channel sofia/internal/sip:171@128.23.0.2 [CS_DESTROY]
  316. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:514 (sofia/internal/sip:171@128.23.0.2) Callstate Change HANGUP -> DOWN
  317. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:517 (sofia/internal/sip:171@128.23.0.2) Running State Change CS_DESTROY
  318. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/sip:171@128.23.0.2) State DESTROY
  319. 2012-06-04 12:12:52.391304 [DEBUG] mod_sofia.c:374 sofia/internal/sip:171@128.23.0.2 SOFIA DESTROY
  320. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:86 sofia/internal/sip:171@128.23.0.2 Standard DESTROY
  321. 2012-06-04 12:12:52.391304 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/sip:171@128.23.0.2) State DESTROY going to sleep
  322. 2012-06-04 12:12:52.440977 [WARNING] mod_sofia.c:1158 Asynchronous PTIME not supported, changing our end from 30 to 20
  323. 2012-06-04 12:12:52.440977 [DEBUG] sofia_glue.c:2931 Changing Codec from PCMU@30ms@8000hz to PCMU@20ms@8000hz
  324. 2012-06-04 12:12:52.451009 [DEBUG] switch_core_session.c:839 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  325. 2012-06-04 12:12:52.451009 [DEBUG] switch_core_session.c:839 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  326. 2012-06-04 12:12:52.501259 [DEBUG] sofia_glue.c:3013 Set Codec sofia/internal/sip:100@128.23.0.63:5065 PCMU/8000 20 ms 160 samples 64000 bits
  327. freeswitch@internal>
  328. freeswitch@internal>
  329. freeswitch@internal>
  330. freeswitch@internal>
  331. freeswitch@internal>
  332. freeswitch@internal>
  333. freeswitch@internal>
  334. freeswitch@internal>
  335. freeswitch@internal>
  336. freeswitch@internal>
  337. freeswitch@internal>
  338. freeswitch@internal>
  339. freeswitch@internal>
  340. freeswitch@internal>
  341. freeswitch@internal>
  342. freeswitch@internal>
  343. freeswitch@internal>
  344. freeswitch@internal>
  345. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_session.c:923 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  346. 2012-06-04 12:12:59.760961 [DEBUG] switch_channel.c:2899 (sofia/internal/sip:100@128.23.0.63:5065) Callstate Change ACTIVE -> HANGUP
  347. 2012-06-04 12:12:59.760961 [NOTICE] sofia.c:711 Hangup sofia/internal/sip:100@128.23.0.63:5065 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  348. 2012-06-04 12:12:59.760961 [DEBUG] switch_channel.c:2922 Send signal sofia/internal/sip:100@128.23.0.63:5065 [KILL]
  349. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  350. 2012-06-04 12:12:59.760961 [DEBUG] switch_ivr_bridge.c:501 sofia/internal/sip:100@128.23.0.63:5065 ending bridge by request from write function
  351. 2012-06-04 12:12:59.760961 [DEBUG] switch_ivr_bridge.c:588 BRIDGE THREAD DONE [sofia/internal/171@128.23.0.1]
  352. 2012-06-04 12:12:59.760961 [DEBUG] switch_ivr_bridge.c:613 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  353. 2012-06-04 12:12:59.760961 [DEBUG] switch_ivr_bridge.c:1456 sofia/internal/sip:100@128.23.0.63:5065 skip receive message [UNBRIDGE] (channel is hungup already)
  354. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_session.c:777 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  355. 2012-06-04 12:12:59.760961 [DEBUG] switch_channel.c:2899 (sofia/internal/171@128.23.0.1) Callstate Change ACTIVE -> HANGUP
  356. 2012-06-04 12:12:59.760961 [NOTICE] switch_ivr_bridge.c:1483 Hangup sofia/internal/171@128.23.0.1 [CS_EXECUTE] [NORMAL_CLEARING]
  357. 2012-06-04 12:12:59.760961 [DEBUG] switch_channel.c:2922 Send signal sofia/internal/171@128.23.0.1 [KILL]
  358. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  359. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_session.c:2333 sofia/internal/171@128.23.0.1 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  360. 2012-06-04 12:12:59.760961 [DEBUG] switch_cpp.cpp:1007 sofia/internal/171@128.23.0.1 destroy/unlink session from object
  361. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_session.c:2333 sofia/internal/171@128.23.0.1 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  362. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/171@128.23.0.1) State EXECUTE going to sleep
  363. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_HANGUP
  364. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/171@128.23.0.1) State HANGUP
  365. 2012-06-04 12:12:59.760961 [DEBUG] mod_sofia.c:463 sofia/internal/171@128.23.0.1 Overriding SIP cause 480 with 200 from the other leg
  366. 2012-06-04 12:12:59.760961 [DEBUG] mod_sofia.c:469 Channel sofia/internal/171@128.23.0.1 hanging up, cause: NORMAL_CLEARING
  367. 2012-06-04 12:12:59.760961 [DEBUG] switch_ivr_bridge.c:588 BRIDGE THREAD DONE [sofia/internal/sip:100@128.23.0.63:5065]
  368. 2012-06-04 12:12:59.760961 [DEBUG] switch_ivr_bridge.c:613 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  369. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_state_machine.c:443 (sofia/internal/sip:100@128.23.0.63:5065) State EXCHANGE_MEDIA going to sleep
  370. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_HANGUP
  371. 2012-06-04 12:12:59.760961 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/sip:100@128.23.0.63:5065) State HANGUP
  372. 2012-06-04 12:12:59.760961 [DEBUG] mod_sofia.c:469 Channel sofia/internal/sip:100@128.23.0.63:5065 hanging up, cause: NORMAL_CLEARING
  373. 2012-06-04 12:12:59.811014 [DEBUG] mod_sofia.c:517 Sending BYE to sofia/internal/171@128.23.0.1
  374. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:47 sofia/internal/171@128.23.0.1 Standard HANGUP, cause: NORMAL_CLEARING
  375. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/171@128.23.0.1) State HANGUP going to sleep
  376. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/171@128.23.0.1) State Change CS_HANGUP -> CS_REPORTING
  377. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  378. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/171@128.23.0.1) Running State Change CS_REPORTING
  379. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/171@128.23.0.1) State REPORTING
  380. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:79 sofia/internal/171@128.23.0.1 Standard REPORTING, cause: NORMAL_CLEARING
  381. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/171@128.23.0.1) State REPORTING going to sleep
  382. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/171@128.23.0.1) State Change CS_REPORTING -> CS_DESTROY
  383. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/171@128.23.0.1 [BREAK]
  384. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_session.c:1428 Session 78 (sofia/internal/171@128.23.0.1) Locked, Waiting on external entities
  385. 2012-06-04 12:12:59.811014 [NOTICE] switch_core_session.c:1446 Session 78 (sofia/internal/171@128.23.0.1) Ended
  386. 2012-06-04 12:12:59.811014 [NOTICE] switch_core_session.c:1448 Close Channel sofia/internal/171@128.23.0.1 [CS_DESTROY]
  387. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:514 (sofia/internal/171@128.23.0.1) Callstate Change HANGUP -> DOWN
  388. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:517 (sofia/internal/171@128.23.0.1) Running State Change CS_DESTROY
  389. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/171@128.23.0.1) State DESTROY
  390. 2012-06-04 12:12:59.811014 [DEBUG] mod_sofia.c:374 sofia/internal/171@128.23.0.1 SOFIA DESTROY
  391. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:86 sofia/internal/171@128.23.0.1 Standard DESTROY
  392. 2012-06-04 12:12:59.811014 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/171@128.23.0.1) State DESTROY going to sleep
  393. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:47 sofia/internal/sip:100@128.23.0.63:5065 Standard HANGUP, cause: NORMAL_CLEARING
  394. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/sip:100@128.23.0.63:5065) State HANGUP going to sleep
  395. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_HANGUP -> CS_REPORTING
  396. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  397. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:385 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_REPORTING
  398. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/sip:100@128.23.0.63:5065) State REPORTING
  399. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:79 sofia/internal/sip:100@128.23.0.63:5065 Standard REPORTING, cause: NORMAL_CLEARING
  400. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:685 (sofia/internal/sip:100@128.23.0.63:5065) State REPORTING going to sleep
  401. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/sip:100@128.23.0.63:5065) State Change CS_REPORTING -> CS_DESTROY
  402. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_session.c:1228 Send signal sofia/internal/sip:100@128.23.0.63:5065 [BREAK]
  403. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_session.c:1428 Session 79 (sofia/internal/sip:100@128.23.0.63:5065) Locked, Waiting on external entities
  404. 2012-06-04 12:12:59.841114 [NOTICE] switch_core_session.c:1446 Session 79 (sofia/internal/sip:100@128.23.0.63:5065) Ended
  405. 2012-06-04 12:12:59.841114 [NOTICE] switch_core_session.c:1448 Close Channel sofia/internal/sip:100@128.23.0.63:5065 [CS_DESTROY]
  406. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:514 (sofia/internal/sip:100@128.23.0.63:5065) Callstate Change HANGUP -> DOWN
  407. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:517 (sofia/internal/sip:100@128.23.0.63:5065) Running State Change CS_DESTROY
  408. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/sip:100@128.23.0.63:5065) State DESTROY
  409. 2012-06-04 12:12:59.841114 [DEBUG] mod_sofia.c:374 sofia/internal/sip:100@128.23.0.63:5065 SOFIA DESTROY
  410. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:86 sofia/internal/sip:100@128.23.0.63:5065 Standard DESTROY
  411. 2012-06-04 12:12:59.841114 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/sip:100@128.23.0.63:5065) 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!