From Nathan Sinclair, 7 Years ago, written in Plain Text.
- view diff
Embed
  1. Original Sip Trace before changing codec negotation settings:
  2.  
  3. Mon May 08 2017 15:02:31 GMT+1200 (New Zealand Standard Time) | sip.transport | received WebSocket text message:
  4.  
  5. SIP/2.0 488 Not Acceptable Here
  6. Via: SIP/2.0/WS 8cl1o75ecd4v.invalid;branch=z9hG4bK635563;received=111.69.178.179;rport=59909
  7. Max-Forwards: 70
  8. From: <sip:1002@104.130.11.232>;tag=dvgo1ufg6i
  9. To: <sip:1001@104.130.11.232>;tag=663v5Smt28KFr
  10. Call-ID: 5dd9vgn8aofl4p8pcmre
  11. CSeq: 6467 INVITE
  12. User-Agent: FreeSWITCH-mod_sofia/1.9.0-380-c66a012~64bit
  13. Accept: application/sdp
  14. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  15. Supported: timer, path, replaces
  16. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  17. Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
  18. Content-Length: 0
  19. Remote-Party-ID: "1001" <sip:1001@104.130.11.232>;party=calling;privacy=off;screen=no
  20.  
  21. 2017-05-08 04:01:54.569551 [ERR] mod_sofia.c:2491 CODEC NEGOTIATION ERROR.  SDP:
  22. v=0
  23. o=- 4107240291395641169 2 IN IP4 127.0.0.1
  24. s=-
  25. t=0 0
  26. a=group:BUNDLE data
  27. a=msid-semantic: WMS
  28. m=application 59754 DTLS/SCTP 5000
  29. c=IN IP4
  30. a=candidate:1520298561 1 udp 2113937151 192.168.178.32 59754 typ host generation 0 network-cost 50
  31. a=candidate:519633664 1 udp 2113939711 IP6 typ host generation 0 network-cost 50
  32. a=candidate:842163049 1 udp 1677729535 IP 59754 typ srflx raddr 192.168.178.32 rport 59754 generation 0 network-cost 50
  33. a=ice-ufrag:mcEl
  34. a=ice-pwd:RTt4tpf5/pvftC5JN6zydT0/
  35. a=fingerprint:sha-256 22:1F:B8:FF:EA:63:8A:64:33:09:D5:B5:4A:08:6D:22:A1:9A:94:0A:34:CB:49:FF:A0:2A:9D:DB:D5:6A:B5:28
  36. a=setup:actpass
  37. a=mid:data
  38. a=sctpmap:5000 webrtc-datachannel 1024
  39.  
  40. 2017-05-08 04:05:46.089495 [NOTICE] switch_channel.c:3522 Hangup sofia/internal/1001@104.130.11.232 [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
  41. 2017-05-08 04:05:46.089495 [DEBUG] switch_ivr_originate.c:1221 sofia/internal/1001@104.130.11.232 Media Establishment Failed.
  42. 2017-05-08 04:05:46.089495 [DEBUG] switch_ivr_originate.c:3838 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
  43. 2017-05-08 04:05:46.089495 [NOTICE] switch_ivr_originate.c:3928 Hangup sofia/internal/hmk51v8e@dossu8lknnc7.invalid [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
  44. 2017-05-08 04:05:46.089495 [NOTICE] switch_ivr_originate.c:2850 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL]
  45.  
  46. COMPLETE DEBUG LOG:
  47.  
  48. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:41.981253 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1002@104.130.11.232 [225e4f44-e81f-4e5f-a947-d53528e33ed1]
  49. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:41.981253 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_NEW (Cur 1 Tot 28)
  50. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:41.981253 [DEBUG] sofia.c:10028 sofia/internal/1002@104.130.11.232 receiving invite from 111.69.178.179:50029 version: 1.9.0 -380-c66a012 64bit
  51. 2017-05-08 22:25:41.981253 [DEBUG] sofia.c:10199 IP 111.69.178.179 Rejected by acl "domains". Falling back to Digest auth.
  52. 2017-05-08 22:25:41.981253 [DEBUG] sofia.c:2405 detaching session 225e4f44-e81f-4e5f-a947-d53528e33ed1
  53. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:41.981253 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1002@104.130.11.232) State NEW
  54. 2017-05-08 22:25:42.221265 [DEBUG] sofia.c:2514 Re-attaching to session 225e4f44-e81f-4e5f-a947-d53528e33ed1
  55. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:10028 sofia/internal/1002@104.130.11.232 receiving invite from 111.69.178.179:50029 version: 1.9.0 -380-c66a012 64bit
  56. 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:10199 IP 111.69.178.179 Rejected by acl "domains". Falling back to Digest auth.
  57. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:11325 Setting NAT mode based on websockets
  58. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:7247 Channel sofia/internal/1002@104.130.11.232 entering state [received][100]
  59. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:7257 Remote SDP:
  60. 225e4f44-e81f-4e5f-a947-d53528e33ed1 v=0
  61. 225e4f44-e81f-4e5f-a947-d53528e33ed1 o=- 2572589166536569753 2 IN IP4 127.0.0.1
  62. 225e4f44-e81f-4e5f-a947-d53528e33ed1 s=-
  63. 225e4f44-e81f-4e5f-a947-d53528e33ed1 t=0 0
  64. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=group:BUNDLE data
  65. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=msid-semantic: WMS
  66. 225e4f44-e81f-4e5f-a947-d53528e33ed1 m=application 52428 DTLS/SCTP 5000
  67. 225e4f44-e81f-4e5f-a947-d53528e33ed1 c=IN IP4 111.69.178.179
  68. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:1520298561 1 udp 2113937151 192.168.178.32 52428 typ host generation 0 network-cost 50
  69. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:505634932 1 udp 2113939711 2406:e007:2f57:1:18cd:41cb:c133:81a9 52429 typ host generation 0 network-cost 50
  70. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:842163049 1 udp 1677729535 111.69.178.179 52428 typ srflx raddr 192.168.178.32 rport 52428 generation 0 network-cost 50
  71. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-ufrag:g5pc
  72. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-pwd:jqxsQvsj7iQ+9s2Qn4WEX6Vb
  73. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=fingerprint:sha-256 51:DD:BE:FE:0B:22:47:B5:60:A1:23:2E:D4:58:C8:68:ED:6A:7C:C3:81:4E:20:AC:CE:2F:6C:8E:00:CF:AF:FD
  74. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=setup:actpass
  75. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=mid:data
  76. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=sctpmap:5000 webrtc-datachannel 1024
  77. 225e4f44-e81f-4e5f-a947-d53528e33ed1
  78. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:7649 (sofia/internal/1002@104.130.11.232) State Change CS_NEW -> CS_INIT
  79. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_INIT (Cur 1 Tot 28)
  80. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@104.130.11.232) State INIT
  81. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_sofia.c:93 sofia/internal/1002@104.130.11.232 SOFIA INIT
  82. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1002@104.130.11.232 Standard INIT
  83. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1002@104.130.11.232) State Change CS_INIT -> CS_ROUTING
  84. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@104.130.11.232) State INIT going to sleep
  85. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_ROUTING (Cur 1 Tot 28)
  86. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:2249 (sofia/internal/1002@104.130.11.232) Callstate Change DOWN -> RINGING
  87. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@104.130.11.232) State ROUTING
  88. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_sofia.c:154 sofia/internal/1002@104.130.11.232 SOFIA ROUTING
  89. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1002@104.130.11.232 Standard ROUTING
  90. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] mod_dialplan_xml.c:637 Processing 1002 <1002>->1001 in context default
  91. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->unloop] continue=false
  92. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  93. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  94. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->tod_example] continue=true
  95. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Date/TimeMatch (FAIL) [tod_example] break=on-false
  96. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->holiday_example] continue=true
  97. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  98. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->global-intercept] continue=false
  99. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [global-intercept] destination_number(1001) =~ /^886$/ break=on-false
  100. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->group-intercept] continue=false
  101. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [group-intercept] destination_number(1001) =~ /^\*8$/ break=on-false
  102. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->intercept-ext] continue=false
  103. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [intercept-ext] destination_number(1001) =~ /^\*\*(\d+)$/ break=on-false
  104. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->redial] continue=false
  105. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [redial] destination_number(1001) =~ /^(redial|870)$/ break=on-false
  106. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->global] continue=true
  107. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  108. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [global] ${default_password}($AXB67ky32$) =~ /^1234$/ break=never
  109. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 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_$225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  110. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  111. 225e4f44-e81f-4e5f-a947-d53528e33ed1 o=- 2572589166536569753 2 IN IP4 127.0.0.1
  112. 225e4f44-e81f-4e5f-a947-d53528e33ed1 s=-
  113. 225e4f44-e81f-4e5f-a947-d53528e33ed1 t=0 0
  114. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=group:BUNDLE data
  115. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=msid-semantic: WMS
  116. 225e4f44-e81f-4e5f-a947-d53528e33ed1 m=application 52428 DTLS/SCTP 5000
  117. 225e4f44-e81f-4e5f-a947-d53528e33ed1 c=IN IP4 111.69.178.179
  118. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:1520298561 1 udp 2113937151 192.168.178.32 52428 typ host generation 0 network-cost 50
  119. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:505634932 1 udp 2113939711 2406:e007:2f57:1:18cd:41cb:c133:81a9 52429 typ host generation 0 network-cost 50
  120. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:842163049 1 udp 1677729535 111.69.178.179 52428 typ srflx raddr 192.168.178.32 rport 52428 generation 0 network-cost 50
  121. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-ufrag:g5pc
  122. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-pwd:jqxsQvsj7iQ+9s2Qn4WEX6Vb
  123. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=fingerprint:sha-256 51:DD:BE:FE:0B:22:47:B5:60:A1:23:2E:D4:58:C8:68:ED:6A:7C:C3:81:4E:20:AC:CE:2F:6C:8E:00:CF:AF:FD
  124. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=setup:actpass
  125. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=mid:data
  126. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=sctpmap:5000 webrtc-datachannel 1024
  127. 225e4f44-e81f-4e5f-a947-d53528e33ed1 ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  128. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Absolute Condition [global]
  129. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  130. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  131. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  132. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  133. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->snom-demo-2] continue=false
  134. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [snom-demo-2] destination_number(1001) =~ /^9001$/ break=on-false
  135. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->snom-demo-1] continue=false
  136. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [snom-demo-1] destination_number(1001) =~ /^9000$/ break=on-false
  137. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->eavesdrop] continue=false
  138. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  139. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->eavesdrop] continue=false
  140. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^779$/ break=on-false
  141. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->call_return] continue=false
  142. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [call_return] destination_number(1001) =~ /^\*69$|^869$|^lcr$/ break=on-false
  143. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->del-group] continue=false
  144. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [del-group] destination_number(1001) =~ /^80(\d{2})$/ break=on-false
  145. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->add-group] continue=false
  146. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [add-group] destination_number(1001) =~ /^81(\d{2})$/ break=on-false
  147. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->call-group-simo] continue=false
  148. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [call-group-simo] destination_number(1001) =~ /^82(\d{2})$/ break=on-false
  149. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->call-group-order] continue=false
  150. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [call-group-order] destination_number(1001) =~ /^83(\d{2})$/ break=on-false
  151. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->extension-intercom] continue=false
  152. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [extension-intercom] destination_number(1001) =~ /^8(10[01][0-9])$/ break=on-false
  153. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->Local_Extension] continue=false
  154. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (PASS) [Local_Extension] destination_number(1001) =~ /^(10[01][0-9])$/ break=on-false
  155. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action export(dialed_extension=1001)
  156. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bind_meta_app(1 b s execute_extension::dx XML features)
  157. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bind_meta_app(2 b s record_session::/var/lib/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.w$225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bind_meta_app(3 b s execute_extension::cf XML features)
  158. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
  159. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(ringback=${us-ring})
  160. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(transfer_ringback=local_stream://moh)
  161. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(call_timeout=30)
  162. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(hangup_after_bridge=true)
  163. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(continue_on_fail=true)
  164. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  165. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  166. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
  167. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})
  168. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})
  169. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
  170. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bridge(user/${dialed_extension}@${domain_name})
  171. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action answer()
  172. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action sleep(1000)
  173. 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
  174. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1002@104.130.11.232) State Change CS_ROUTING -> CS_EXECUTE
  175. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@104.130.11.232) State ROUTING going to sleep
  176. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_EXECUTE (Cur 1 Tot 28)
  177. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1002@104.130.11.232) State EXECUTE
  178. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_sofia.c:209 sofia/internal/1002@104.130.11.232 SOFIA EXECUTE
  179. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1002@104.130.11.232 Standard EXECUTE
  180. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-spymap/1002/225e4f44-e81f-4e5f-a947-d53528e33ed1)
  181. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial/1002/1001)
  182. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial/global/225e4f44-e81f-4e5f-a947-d53528e33ed1)
  183. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 export(RFC2822_DATE=Mon, 08 May 2017 22:25:42 +0000)
  184. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 08 May 2017 22:25:42 +0000]
  185. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 export(dialed_extension=1001)
  186. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1001]
  187. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bind_meta_app(1 b s execute_extension::dx XML features)
  188. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] switch_ivr_async.c:4252 Bound B-Leg: *1 execute_extension::dx XML features
  189. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bind_meta_app(2 b s record_session::/var/lib/freeswitch/recordings/1002.2017-05-08-22-25-42.wav)
  190. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] switch_ivr_async.c:4252 Bound B-Leg: *2 record_session::/var/lib/freeswitch/recordings/1002.2017-05-08-22-25-42.wav
  191. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bind_meta_app(3 b s execute_extension::cf XML features)
  192. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] switch_ivr_async.c:4252 Bound B-Leg: *3 execute_extension::cf XML features
  193. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bind_meta_app(4 b s execute_extension::att_xfer XML features)
  194. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] switch_ivr_async.c:4252 Bound B-Leg: *4 execute_extension::att_xfer XML features
  195. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(ringback=%(2000,4000,440,480))
  196. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [ringback]=[%(2000,4000,440,480)]
  197. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(transfer_ringback=local_stream://moh)
  198. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [transfer_ringback]=[local_stream://moh]
  199. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(call_timeout=30)
  200. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [call_timeout]=[30]
  201. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(hangup_after_bridge=true)
  202. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [hangup_after_bridge]=[true]
  203. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(continue_on_fail=true)
  204. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [continue_on_fail]=[true]
  205. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-call_return/1001/1002)
  206. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial_ext/1001/225e4f44-e81f-4e5f-a947-d53528e33ed1)
  207. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(called_party_callgroup=techsupport)
  208. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [called_party_callgroup]=[techsupport]
  209. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial_ext/techsupport/225e4f44-e81f-4e5f-a947-d53528e33ed1)
  210. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial_ext/global/225e4f44-e81f-4e5f-a947-d53528e33ed1)
  211. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial/techsupport/225e4f44-e81f-4e5f-a947-d53528e33ed1)
  212. 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bridge(user/1001@104.130.11.232)
  213. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:1250 sofia/internal/1002@104.130.11.232 EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 08 May 2017 22:25:42 +0000] to event225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:1250 sofia/internal/1002@104.130.11.232 EXPORTING[export_vars] [dialed_extension]=[1001] to event
  214. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables
  215. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.261251 [DEBUG] switch_channel.c:1250 sofia/internal/1002@104.130.11.232 EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 08 May 2017 22:25:42 +0000] to event225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.261251 [DEBUG] switch_channel.c:1250 sofia/internal/1002@104.130.11.232 EXPORTING[export_vars] [dialed_extension]=[1001] to event
  216. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.261251 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables
  217. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid [76ab1f55-6a07-4a68-a7e4-360519c4acf1]
  218. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] mod_sofia.c:5012 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_NEW -> CS_INIT
  219. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_INIT (Cur 2 Tot 29)
  220. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State INIT
  221. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] mod_sofia.c:93 sofia/internal/is2jtitt@cieonafgfl9q.invalid SOFIA INIT
  222. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] sofia_glue.c:1267 sip:is2jtitt@111.69.178.179:50038;transport=ws Setting proxy route to sofia/internal/is2jtitt@cieonafgfl9q.invalid
  223. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] sofia_glue.c:1298 sofia/internal/is2jtitt@cieonafgfl9q.invalid sending invite version: 1.9.0 -380-c66a012 64bit
  224. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 Local SDP:
  225. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 v=0
  226. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 o=FreeSWITCH 1494265954 1494265955 IN IP4 104.130.11.232
  227. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 s=FreeSWITCH
  228. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 c=IN IP4 104.130.11.232
  229. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 t=0 0
  230. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=msid-semantic: WMS Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh
  231. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 m=audio 16388 RTP/SAVPF 9 0 8 101
  232. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:9 G722/8000
  233. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:0 PCMU/8000
  234. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:8 PCMA/8000
  235. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:101 telephone-event/8000
  236. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=fingerprint:sha-256 76:9A:20:F8:92:01:15:BC:4F:B7:4E:6C:D8:B7:10:2E:B8:D1:19:2E:E3:5F:96:62:D4:B5:82:2B:1D:82:57:A8
  237. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=setup:actpass
  238. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-mux
  239. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp:16388 IN IP4 104.130.11.232
  240. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2971565262 cname:JPrAmDIgupg2B4au
  241. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2971565262 msid:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh a0
  242. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2971565262 mslabel:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh
  243. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2971565262 label:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDha0
  244. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ice-ufrag:tzRaNtr6wcKOodVX
  245. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ice-pwd:Tdo5GXc0ciTEdTYxjFxWZPTl
  246. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=candidate:0170082732 1 udp 659136 104.130.11.232 16388 typ host generation 0
  247. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=candidate:0170082732 2 udp 659136 104.130.11.232 16388 typ host generation 0
  248. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ptime:20
  249. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=sendrecv
  250. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 m=video 16384 RTP/SAVPF 102
  251. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 b=AS:1024
  252. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:102 VP8/90000
  253. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=sendrecv
  254. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=fingerprint:sha-256 76:9A:20:F8:92:01:15:BC:4F:B7:4E:6C:D8:B7:10:2E:B8:D1:19:2E:E3:5F:96:62:D4:B5:82:2B:1D:82:57:A8
  255. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=setup:actpass
  256. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-mux
  257. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp:16384 IN IP4 104.130.11.232
  258. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-fb:102 ccm fir
  259. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-fb:102 ccm tmmbr
  260. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-fb:102 nack
  261. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-fb:102 nack pli
  262. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2224445067 cname:JPrAmDIgupg2B4au
  263. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2224445067 msid:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh v0
  264. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2224445067 mslabel:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh
  265. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2224445067 label:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDhv0
  266. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ice-ufrag:IFzrWUdM2OfbcPov
  267. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ice-pwd:yDpZFPT8jhFy8WwGrWYeG1Qz
  268. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=candidate:6684695135 1 udp 659136 104.130.11.232 16384 typ host generation 0
  269. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=candidate:6684695135 2 udp 659135 104.130.11.232 16384 typ host generation 0
  270. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=end-of-candidates
  271. 76ab1f55-6a07-4a68-a7e4-360519c4acf1
  272. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:40 sofia/internal/is2jtitt@cieonafgfl9q.invalid Standard INIT
  273. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_INIT -> CS_ROUTING
  274. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State INIT going to sleep
  275. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_ROUTING (Cur 2 Tot 29)
  276. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] sofia.c:7247 Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid entering state [calling][0]
  277. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State ROUTING
  278. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] mod_sofia.c:154 sofia/internal/is2jtitt@cieonafgfl9q.invalid SOFIA ROUTING
  279. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  280. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State ROUTING going to sleep
  281. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 29)
  282. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State CONSUME_MEDIA
  283. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State CONSUME_MEDIA going to sleep
  284. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.541258 [DEBUG] sofia.c:7247 Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid entering state [proceeding][180]
  285. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.541258 [NOTICE] sofia.c:7355 Ring-Ready sofia/internal/is2jtitt@cieonafgfl9q.invalid!
  286. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.541258 [DEBUG] switch_channel.c:3353 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Callstate Change DOWN -> RINGING
  287. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [INFO] switch_ivr_originate.c:1220 Sending early media
  288. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [ERR] mod_sofia.c:2491 CODEC NEGOTIATION ERROR.  SDP:
  289. 225e4f44-e81f-4e5f-a947-d53528e33ed1 v=0
  290. 225e4f44-e81f-4e5f-a947-d53528e33ed1 o=- 2572589166536569753 2 IN IP4 127.0.0.1
  291. 225e4f44-e81f-4e5f-a947-d53528e33ed1 s=-
  292. 225e4f44-e81f-4e5f-a947-d53528e33ed1 t=0 0
  293. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=group:BUNDLE data
  294. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=msid-semantic: WMS
  295. 225e4f44-e81f-4e5f-a947-d53528e33ed1 m=application 52428 DTLS/SCTP 5000
  296. 225e4f44-e81f-4e5f-a947-d53528e33ed1 c=IN IP4 111.69.178.179
  297. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:1520298561 1 udp 2113937151 192.168.178.32 52428 typ host generation 0 network-cost 50
  298. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:505634932 1 udp 2113939711 2406:e007:2f57:1:18cd:41cb:c133:81a9 52429 typ host generation 0 network-cost 50
  299. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:842163049 1 udp 1677729535 111.69.178.179 52428 typ srflx raddr 192.168.178.32 rport 52428 generation 0 network-cost 50
  300. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-ufrag:g5pc
  301. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-pwd:jqxsQvsj7iQ+9s2Qn4WEX6Vb
  302. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=fingerprint:sha-256 51:DD:BE:FE:0B:22:47:B5:60:A1:23:2E:D4:58:C8:68:ED:6A:7C:C3:81:4E:20:AC:CE:2F:6C:8E:00:CF:AF:FD
  303. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=setup:actpass
  304. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=mid:data
  305. 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=sctpmap:5000 webrtc-datachannel 1024
  306. 225e4f44-e81f-4e5f-a947-d53528e33ed1
  307. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_channel.c:3522 Hangup sofia/internal/1002@104.130.11.232 [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
  308. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_ivr_originate.c:1221 sofia/internal/1002@104.130.11.232 Media Establishment Failed.
  309. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_ivr_originate.c:3838 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
  310. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [NOTICE] switch_ivr_originate.c:3928 Hangup sofia/internal/is2jtitt@cieonafgfl9q.invalid [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
  311. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_ivr_originate.c:2850 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL]
  312. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_ivr_originate.c:3838 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
  313. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [NOTICE] switch_ivr_originate.c:3928 Hangup sofia/internal/is2jtitt@cieonafgfl9q.invalid [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL]
  314. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_ivr_originate.c:2850 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL]
  315. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_ivr_originate.c:3838 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]
  316. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [INFO] mod_dptools.c:3490 Originate Failed.  Cause: ORIGINATOR_CANCEL
  317. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_session.c:2884 sofia/internal/1002@104.130.11.232 skip receive message [PHONE_EVENT] (channel is hungup already)
  318. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1002@104.130.11.232) State EXECUTE going to sleep
  319. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_HANGUP (Cur 2 Tot 29)
  320. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1002@104.130.11.232) Callstate Change RINGING -> HANGUP
  321. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1002@104.130.11.232) State HANGUP
  322. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1002@104.130.11.232 hanging up, cause: INCOMPATIBLE_DESTINATION
  323. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:588 Responding to INVITE with: 488
  324. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1002@104.130.11.232 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
  325. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1002@104.130.11.232) State HANGUP going to sleep
  326. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1002@104.130.11.232) State Change CS_HANGUP -> CS_REPORTING
  327. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_REPORTING (Cur 2 Tot 29)
  328. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1002@104.130.11.232) State REPORTING
  329. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1002@104.130.11.232 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
  330. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1002@104.130.11.232) State REPORTING going to sleep
  331. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1002@104.130.11.232) State Change CS_REPORTING -> CS_DESTROY
  332. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_session.c:1712 Session 28 (sofia/internal/1002@104.130.11.232) Locked, Waiting on external entities
  333. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_core_session.c:1730 Session 28 (sofia/internal/1002@104.130.11.232) Ended
  334. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_core_session.c:1734 Close Channel sofia/internal/1002@104.130.11.232 [CS_DESTROY]
  335. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1002@104.130.11.232) Running State Change CS_DESTROY (Cur 1 Tot 29)
  336. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1002@104.130.11.232) State DESTROY
  337. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:354 sofia/internal/1002@104.130.11.232 SOFIA DESTROY
  338. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1002@104.130.11.232 Standard DESTROY
  339. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1002@104.130.11.232) State DESTROY going to sleep
  340. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_HANGUP (Cur 1 Tot 29)
  341. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Callstate Change RINGING -> HANGUP
  342. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State HANGUP
  343. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:449 Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid hanging up, cause: ORIGINATOR_CANCEL
  344. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:513 Sending CANCEL to sofia/internal/is2jtitt@cieonafgfl9q.invalid
  345. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:60 sofia/internal/is2jtitt@cieonafgfl9q.invalid Standard HANGUP, cause: ORIGINATOR_CANCEL
  346. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State HANGUP going to sleep
  347. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_HANGUP -> CS_REPORTING
  348. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_REPORTING (Cur 1 Tot 29)
  349. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State REPORTING
  350. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:174 sofia/internal/is2jtitt@cieonafgfl9q.invalid Standard REPORTING, cause: ORIGINATOR_CANCEL
  351. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State REPORTING going to sleep
  352. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_REPORTING -> CS_DESTROY
  353. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_session.c:1712 Session 29 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Locked, Waiting on external entities
  354. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [NOTICE] switch_core_session.c:1730 Session 29 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Ended
  355. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [NOTICE] switch_core_session.c:1734 Close Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid [CS_DESTROY]
  356. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_DESTROY (Cur 0 Tot 29)
  357. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State DESTROY
  358. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:354 sofia/internal/is2jtitt@cieonafgfl9q.invalid SOFIA DESTROY
  359. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:181 sofia/internal/is2jtitt@cieonafgfl9q.invalid Standard DESTROY
  360. 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State DESTROY going to sleep
  361.  
  362. SIP TRACE of socket:
  363.  
  364. INVITE sip:1001@104.130.11.232 SIP/2.0
  365. Via: SIP/2.0/WS sh7nvsngi33a.invalid;branch=z9hG4bK1445474
  366. Max-Forwards: 70
  367. To: <sip:1001@104.130.11.232>
  368. From: <sip:1002@104.130.11.232>;tag=t1hatm4phs
  369. Call-ID: jidiqnu3nsepdd44prtl
  370. CSeq: 4347 INVITE
  371. Proxy-Authorization: Digest algorithm=MD5, username="1002", realm="104.130.11.232", nonce="0868d677-8e38-45c1-85d7-c49a57c209ba", uri="sip:1001@104.130.11.232", response="e46204eefde8bbf3b631512d0c389ee7", qop=auth, cnonce="egm2tqu2mfgq", nc=00000001
  372. Contact: <sip:loahejbu@sh7nvsngi33a.invalid;transport=ws;ob>
  373. Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER
  374. Supported: outbound
  375. User-Agent: SIP.js/0.7.7
  376. Content-Type: application/sdp
  377. Content-Length: 761
  378.  
  379. v=0
  380. o=- 1578489363367963671 2 IN IP4 127.0.0.1
  381. s=-
  382. t=0 0
  383. a=group:BUNDLE data
  384. a=msid-semantic: WMS
  385. m=application 61279 DTLS/SCTP 5000
  386. c=IN IP4 111.69.178.179
  387. a=candidate:1520298561 1 udp 2113937151 192.168.178.32 61279 typ host generation 0 network-cost 50
  388. a=candidate:505634932 1 udp 2113939711 2406:e007:2f57:1:18cd:41cb:c133:81a9 61280 typ host generation 0 network-cost 50
  389. a=candidate:842163049 1 udp 1677729535 111.69.178.179 61279 typ srflx raddr 192.168.178.32 rport 61279 generation 0 network-cost 50
  390. a=ice-ufrag:28pV
  391. a=ice-pwd:WY4lXqVRHE3wlwfyRv4gWdVe
  392. a=fingerprint:sha-256 71:62:91:79:02:E8:BF:BF:0A:3D:C4:12:66:97:3F:BF:27:A6:83:0A:E8:A6:1D:BF:6B:57:C3:B0:28:B8:C5:BC
  393. a=setup:actpass
  394. a=mid:data
  395. a=sctpmap:5000 webrtc-datachannel 1024
  396.  
  397. SIP/2.0 100 Trying
  398. Via: SIP/2.0/WS sh7nvsngi33a.invalid;branch=z9hG4bK1445474;received=111.69.178.179;rport=51195
  399. From: <sip:1002@104.130.11.232>;tag=t1hatm4phs
  400. To: <sip:1001@104.130.11.232>
  401. Call-ID: jidiqnu3nsepdd44prtl
  402. CSeq: 4347 INVITE
  403. User-Agent: FreeSWITCH-mod_sofia/1.9.0-380-c66a012~64bit
  404. Content-Length: 0
  405.  
  406. SIP/2.0 488 Not Acceptable Here
  407. Via: SIP/2.0/WS sh7nvsngi33a.invalid;branch=z9hG4bK1445474;received=111.69.178.179;rport=51195
  408. Max-Forwards: 70
  409. From: <sip:1002@104.130.11.232>;tag=t1hatm4phs
  410. To: <sip:1001@104.130.11.232>;tag=98v1aUaXH6N1m
  411. Call-ID: jidiqnu3nsepdd44prtl
  412. CSeq: 4347 INVITE
  413. User-Agent: FreeSWITCH-mod_sofia/1.9.0-380-c66a012~64bit
  414. Accept: application/sdp
  415. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  416. Supported: timer, path, replaces
  417. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  418. Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
  419. Content-Length: 0
  420. Remote-Party-ID: "1001" <sip:1001@104.130.11.232>;party=calling;privacy=off;screen=no
  421.  
  422. ACK sip:1001@104.130.11.232 SIP/2.0
  423. Via: SIP/2.0/WS sh7nvsngi33a.invalid;branch=z9hG4bK1445474
  424. To: <sip:1001@104.130.11.232>;tag=98v1aUaXH6N1m
  425. From: <sip:1002@104.130.11.232>;tag=t1hatm4phs
  426. Call-ID: jidiqnu3nsepdd44prtl
  427. Content-Length: 0
  428. CSeq: 4347 ACK