FreeSWITCH Pastebin

Untitled

From Soiled Pudu, 6 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/81c2a8f9
Embed
Download Paste or View Raw
  1. freeswitch@ip-10-0-0-245>
  2. freeswitch@ip-10-0-0-245>
  3. freeswitch@ip-10-0-0-245>
  4. recv 1415 bytes from tcp/[10.0.0.26]:19113 at 14:25:29.092683:
  5.    ------------------------------------------------------------------------
  6.    INVITE sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SIP/2.0
  7.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.~yovM~tl9;rport
  8.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=q8jhJA8QA
  9.    To: sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com
  10.    CSeq: 20 INVITE
  11.    Call-ID: uvJrwaoxC8
  12.    Max-Forwards: 70
  13.    Supported: replaces, outbound
  14.   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
  15.    Content-Type: application/sdp
  16.    Content-Length: 549
  17.    Contact: <sip:12132841480@10.0.0.26:19113;transport=tcp>;+sip.instance="<urn:uuid:8737813e-5d59-4f16-b563-b7fa8ffbd161>"
  18.    X-callerLatitude: 32.1011897
  19.    X-callerLongitude: -96.624755
  20.    X-callerLocationDateTime: 2017-06-01 14:25:27
  21.    X-serviceRequest: doctor
  22.    User-Agent: NGA911Android/3.2.6 (belle-sip/1.6.1)
  23.    
  24.    v=0
  25.    o=12132841480 1394 2602 IN IP4 10.0.0.26
  26.    s=Talk
  27.    c=IN IP4 10.0.0.26
  28.    b=AS:380
  29.    t=0 0
  30.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
  31.    m=audio 4970 RTP/AVP 0 8 18 101
  32.    a=fmtp:18 annexb=yes
  33.    a=rtpmap:101 telephone-event/8000
  34.    a=rtcp-fb:* ccm tmmbr
  35.    m=video 14334 RTP/AVP 96 97
  36.    a=rtpmap:96 VP8/90000
  37.    a=rtpmap:97 H264/90000
  38.    a=fmtp:97 profile-level-id=42801F
  39.    a=rtcp-fb:* ccm tmmbr
  40.    a=rtcp-fb:96 nack pli
  41.    a=rtcp-fb:96 nack sli
  42.    a=rtcp-fb:96 ack rpsi
  43.    a=rtcp-fb:96 ccm fir
  44.    a=rtcp-fb:97 nack pli
  45.    a=rtcp-fb:97 ccm fir
  46.    ------------------------------------------------------------------------
  47. send 422 bytes to tcp/[10.0.0.26]:19113 at 14:25:29.092928:
  48.    ------------------------------------------------------------------------
  49.    SIP/2.0 100 Trying
  50.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.~yovM~tl9;rport=19113;received=10.0.0.26
  51.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=q8jhJA8QA
  52.    To: sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com
  53.    Call-ID: uvJrwaoxC8
  54.    CSeq: 20 INVITE
  55.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170109T225752Z~6fe1deda45~64bit
  56.    Content-Length: 0
  57.    
  58.    ------------------------------------------------------------------------
  59. 2017-06-01 14:25:29.086533 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com [7df8cf62-0707-4e30-9a2e-7f95d5acd152]
  60. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_NEW (Cur 1 Tot 21)
  61. 2017-06-01 14:25:29.086533 [DEBUG] sofia.c:9997 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com receiving invite from 10.0.0.26:19113 version: 1.9.0 git 6fe1ded 2017-01-09 22:57:52Z 64bit
  62. 2017-06-01 14:25:29.086533 [DEBUG] sofia.c:10109 IP 10.0.0.26 Approved by acl "domains[gate10-0-0-26@10.0.0.245]". Access Granted.
  63. 2017-06-01 14:25:29.086533 [DEBUG] sofia.c:10246 Authenticating user gate10-0-0-26@10.0.0.245
  64. 2017-06-01 14:25:29.086533 [DEBUG] sofia.c:7229 Channel sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com entering state [received][100]
  65. 2017-06-01 14:25:29.086533 [DEBUG] sofia.c:7239 Remote SDP:
  66. v=0
  67. o=12132841480 1394 2602 IN IP4 10.0.0.26
  68. s=Talk
  69. c=IN IP4 10.0.0.26
  70. b=AS:380
  71. t=0 0
  72. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
  73. m=audio 4970 RTP/AVP 0 8 18 101
  74. a=fmtp:18 annexb=yes
  75. a=rtpmap:101 telephone-event/8000
  76. a=rtcp-fb:* ccm tmmbr
  77. m=video 14334 RTP/AVP 96 97
  78. a=rtpmap:96 VP8/90000
  79. a=rtpmap:97 H264/90000
  80. a=fmtp:97 profile-level-id=42801F
  81. a=rtcp-fb:* ccm tmmbr
  82. a=rtcp-fb:96 nack pli
  83. a=rtcp-fb:96 nack sli
  84. a=rtcp-fb:96 ack rpsi
  85. a=rtcp-fb:96 ccm fir
  86. a=rtcp-fb:97 nack pli
  87. a=rtcp-fb:97 ccm fir
  88.  
  89. 2017-06-01 14:25:29.086533 [DEBUG] sofia.c:7631 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State Change CS_NEW -> CS_INIT
  90. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State NEW
  91. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_INIT (Cur 1 Tot 21)
  92. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State INIT
  93. 2017-06-01 14:25:29.086533 [DEBUG] mod_sofia.c:92 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SOFIA INIT
  94. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:40 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard INIT
  95. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State Change CS_INIT -> CS_ROUTING
  96. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State INIT going to sleep
  97. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_ROUTING (Cur 1 Tot 21)
  98. 2017-06-01 14:25:29.086533 [DEBUG] switch_channel.c:2249 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Callstate Change DOWN -> RINGING
  99. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State ROUTING
  100. 2017-06-01 14:25:29.086533 [DEBUG] mod_sofia.c:145 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SOFIA ROUTING
  101. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:236 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard ROUTING
  102. 2017-06-01 14:25:29.086533 [INFO] mod_dialplan_xml.c:637 Processing 12132841480 <12132841480>->12149119111 in context e911
  103. Dialplan: sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com parsing [e911->911] continue=false
  104. Dialplan: sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Regex (PASS) [911] destination_number(12149119111) =~ /^1(\d{10})$/ break=on-false
  105. Dialplan: sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Action set(sip_nat_detected=false)
  106. Dialplan: sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Action python(dialplan)
  107. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State Change CS_ROUTING -> CS_EXECUTE
  108. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State ROUTING going to sleep
  109. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_EXECUTE (Cur 1 Tot 21)
  110. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State EXECUTE
  111. 2017-06-01 14:25:29.086533 [DEBUG] mod_sofia.c:200 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SOFIA EXECUTE
  112. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:328 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard EXECUTE
  113. EXECUTE sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com set(sip_nat_detected=false)
  114. 2017-06-01 14:25:29.086533 [DEBUG] mod_dptools.c:1562 SET sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com [sip_nat_detected]=[false]
  115. EXECUTE sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com python(dialplan)
  116. 2017-06-01 14:25:29.086533 [NOTICE] mod_python.c:212 Invoking py module: dialplan
  117. 2017-06-01 14:25:29.086533 [ERR] mod_python.c:231 Error importing module
  118. 2017-06-01 14:25:29.086533 [ERR] mod_python.c:164 Python Error by calling script "dialplan": <type 'exceptions.IndentationError'>
  119. Message: expected an indented block (dialplan.py, line 128)
  120. 2017-06-01 14:25:29.086533 [NOTICE] switch_core_state_machine.c:385 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com has executed the last dialplan instruction, hanging up.
  121. 2017-06-01 14:25:29.086533 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com [CS_EXECUTE] [NORMAL_CLEARING]
  122. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State EXECUTE going to sleep
  123. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_HANGUP (Cur 1 Tot 21)
  124. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Callstate Change RINGING -> HANGUP
  125. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State HANGUP
  126. 2017-06-01 14:25:29.086533 [DEBUG] mod_sofia.c:440 Channel sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com hanging up, cause: NORMAL_CLEARING
  127. 2017-06-01 14:25:29.086533 [DEBUG] mod_sofia.c:579 Responding to INVITE with: 480
  128. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:60 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard HANGUP, cause: NORMAL_CLEARING
  129. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State HANGUP going to sleep
  130. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State Change CS_HANGUP -> CS_REPORTING
  131. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_REPORTING (Cur 1 Tot 21)
  132. send 1005 bytes to tcp/[10.0.0.26]:19113 at 14:25:29.096377:
  133.    ------------------------------------------------------------------------
  134.    SIP/2.0 480 Temporarily Unavailable
  135.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.~yovM~tl9;rport=19113;received=10.0.0.26
  136.    Max-Forwards: 70
  137.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=q8jhJA8QA
  138.    To: <sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=mtDQ33aUt97ye
  139.    Call-ID: uvJrwaoxC8
  140.    CSeq: 20 INVITE
  141.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170109T225752Z~6fe1deda45~64bit
  142.    Accept: application/sdp
  143.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  144.    Supported: timer, path, replaces
  145.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  146.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  147.    Content-Length: 0
  148.    Remote-Party-ID: "12149119111" <sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;party=calling;privacy=off;screen=no
  149.    
  150. 2017-06-01 14:25:29.086533 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State REPORTING
  151.    ------------------------------------------------------------------------
  152. recv 540 bytes from tcp/[10.0.0.26]:19113 at 14:25:29.177680:
  153.    ------------------------------------------------------------------------
  154.    ACK sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SIP/2.0
  155.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.~yovM~tl9;rport
  156.    Call-ID: uvJrwaoxC8
  157.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=q8jhJA8QA
  158.    To: <sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=mtDQ33aUt97ye
  159.    Contact: <sip:12132841480@10.0.0.26:19113;transport=tcp>;+sip.instance="<urn:uuid:8737813e-5d59-4f16-b563-b7fa8ffbd161>"
  160.    Max-Forwards: 70
  161.    CSeq: 20 ACK
  162.    Content-Length: 0
  163.    
  164.    ------------------------------------------------------------------------
  165. 2017-06-01 14:25:29.266523 [ERR] mod_xml_cdr.c:395 Got error [500] posting to web server [https://csp.nga911.com/call/LogXML]
  166. 2017-06-01 14:25:29.266523 [ERR] mod_xml_cdr.c:402 Retry will be with url [https://csp.nga911.com/call/LogXML]
  167. 2017-06-01 14:25:31.426528 [ERR] mod_xml_cdr.c:395 Got error [500] posting to web server [https://csp.nga911.com/call/LogXML]
  168. 2017-06-01 14:25:31.426528 [ERR] mod_xml_cdr.c:402 Retry will be with url [https://csp.nga911.com/call/LogXML]
  169. recv 1415 bytes from tcp/[10.0.0.26]:19113 at 14:25:32.961674:
  170.    ------------------------------------------------------------------------
  171.    INVITE sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SIP/2.0
  172.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.NR0T-~iUI;rport
  173.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=Sp3s3vgzl
  174.    To: sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com
  175.    CSeq: 20 INVITE
  176.    Call-ID: L~ZcU3sS4X
  177.    Max-Forwards: 70
  178.    Supported: replaces, outbound
  179.   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
  180.    Content-Type: application/sdp
  181.    Content-Length: 549
  182.    Contact: <sip:12132841480@10.0.0.26:19113;transport=tcp>;+sip.instance="<urn:uuid:8737813e-5d59-4f16-b563-b7fa8ffbd161>"
  183.    X-callerLatitude: 32.1011897
  184.    X-callerLongitude: -96.624755
  185.    X-callerLocationDateTime: 2017-06-01 14:25:31
  186.    X-serviceRequest: doctor
  187.    User-Agent: NGA911Android/3.2.6 (belle-sip/1.6.1)
  188.    
  189.    v=0
  190.    o=12132841480 570 3365 IN IP4 10.0.0.26
  191.    s=Talk
  192.    c=IN IP4 10.0.0.26
  193.    b=AS:380
  194.    t=0 0
  195.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
  196.    m=audio 25152 RTP/AVP 0 8 18 101
  197.    a=fmtp:18 annexb=yes
  198.    a=rtpmap:101 telephone-event/8000
  199.    a=rtcp-fb:* ccm tmmbr
  200.    m=video 47384 RTP/AVP 96 97
  201.    a=rtpmap:96 VP8/90000
  202.    a=rtpmap:97 H264/90000
  203.    a=fmtp:97 profile-level-id=42801F
  204.    a=rtcp-fb:* ccm tmmbr
  205.    a=rtcp-fb:96 nack pli
  206.    a=rtcp-fb:96 nack sli
  207.    a=rtcp-fb:96 ack rpsi
  208.    a=rtcp-fb:96 ccm fir
  209.    a=rtcp-fb:97 nack pli
  210.    a=rtcp-fb:97 ccm fir
  211.    ------------------------------------------------------------------------
  212. send 422 bytes to tcp/[10.0.0.26]:19113 at 14:25:32.961922:
  213.    ------------------------------------------------------------------------
  214.    SIP/2.0 100 Trying
  215.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.NR0T-~iUI;rport=19113;received=10.0.0.26
  216.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=Sp3s3vgzl
  217.    To: sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com
  218.    Call-ID: L~ZcU3sS4X
  219.    CSeq: 20 INVITE
  220.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170109T225752Z~6fe1deda45~64bit
  221.    Content-Length: 0
  222.    
  223.    ------------------------------------------------------------------------
  224. 2017-06-01 14:25:32.946540 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com [0d85fc56-6e3e-43d2-a11d-1ee19fa35234]
  225. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_NEW (Cur 2 Tot 22)
  226. 2017-06-01 14:25:32.946540 [DEBUG] sofia.c:9997 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com receiving invite from 10.0.0.26:19113 version: 1.9.0 git 6fe1ded 2017-01-09 22:57:52Z 64bit
  227. 2017-06-01 14:25:32.946540 [DEBUG] sofia.c:10109 IP 10.0.0.26 Approved by acl "domains[gate10-0-0-26@10.0.0.245]". Access Granted.
  228. 2017-06-01 14:25:32.946540 [DEBUG] sofia.c:10246 Authenticating user gate10-0-0-26@10.0.0.245
  229. 2017-06-01 14:25:32.946540 [DEBUG] sofia.c:7229 Channel sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com entering state [received][100]
  230. 2017-06-01 14:25:32.946540 [DEBUG] sofia.c:7239 Remote SDP:
  231. v=0
  232. o=12132841480 570 3365 IN IP4 10.0.0.26
  233. s=Talk
  234. c=IN IP4 10.0.0.26
  235. b=AS:380
  236. t=0 0
  237. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
  238. m=audio 25152 RTP/AVP 0 8 18 101
  239. a=fmtp:18 annexb=yes
  240. a=rtpmap:101 telephone-event/8000
  241. a=rtcp-fb:* ccm tmmbr
  242. m=video 47384 RTP/AVP 96 97
  243. a=rtpmap:96 VP8/90000
  244. a=rtpmap:97 H264/90000
  245. a=fmtp:97 profile-level-id=42801F
  246. a=rtcp-fb:* ccm tmmbr
  247. a=rtcp-fb:96 nack pli
  248. a=rtcp-fb:96 nack sli
  249. a=rtcp-fb:96 ack rpsi
  250. a=rtcp-fb:96 ccm fir
  251. a=rtcp-fb:97 nack pli
  252. a=rtcp-fb:97 ccm fir
  253.  
  254. 2017-06-01 14:25:32.946540 [DEBUG] sofia.c:7631 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State Change CS_NEW -> CS_INIT
  255. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State NEW
  256. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_INIT (Cur 2 Tot 22)
  257. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State INIT
  258. 2017-06-01 14:25:32.946540 [DEBUG] mod_sofia.c:92 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SOFIA INIT
  259. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:40 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard INIT
  260. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State Change CS_INIT -> CS_ROUTING
  261. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State INIT going to sleep
  262. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_ROUTING (Cur 2 Tot 22)
  263. 2017-06-01 14:25:32.946540 [DEBUG] switch_channel.c:2249 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Callstate Change DOWN -> RINGING
  264. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State ROUTING
  265. 2017-06-01 14:25:32.946540 [DEBUG] mod_sofia.c:145 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SOFIA ROUTING
  266. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:236 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard ROUTING
  267. 2017-06-01 14:25:32.946540 [INFO] mod_dialplan_xml.c:637 Processing 12132841480 <12132841480>->12149119111 in context e911
  268. Dialplan: sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com parsing [e911->911] continue=false
  269. Dialplan: sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Regex (PASS) [911] destination_number(12149119111) =~ /^1(\d{10})$/ break=on-false
  270. Dialplan: sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Action set(sip_nat_detected=false)
  271. Dialplan: sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Action python(dialplan)
  272. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State Change CS_ROUTING -> CS_EXECUTE
  273. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State ROUTING going to sleep
  274. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_EXECUTE (Cur 2 Tot 22)
  275. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State EXECUTE
  276. 2017-06-01 14:25:32.946540 [DEBUG] mod_sofia.c:200 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SOFIA EXECUTE
  277. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:328 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard EXECUTE
  278. EXECUTE sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com set(sip_nat_detected=false)
  279. 2017-06-01 14:25:32.946540 [DEBUG] mod_dptools.c:1562 SET sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com [sip_nat_detected]=[false]
  280. EXECUTE sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com python(dialplan)
  281. 2017-06-01 14:25:32.946540 [NOTICE] mod_python.c:212 Invoking py module: dialplan
  282. 2017-06-01 14:25:32.946540 [ERR] mod_python.c:231 Error importing module
  283. 2017-06-01 14:25:32.946540 [ERR] mod_python.c:164 Python Error by calling script "dialplan": <type 'exceptions.IndentationError'>
  284. Message: expected an indented block (dialplan.py, line 128)
  285. 2017-06-01 14:25:32.946540 [NOTICE] switch_core_state_machine.c:385 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com has executed the last dialplan instruction, hanging up.
  286. 2017-06-01 14:25:32.946540 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com [CS_EXECUTE] [NORMAL_CLEARING]
  287. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State EXECUTE going to sleep
  288. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_HANGUP (Cur 2 Tot 22)
  289. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Callstate Change RINGING -> HANGUP
  290. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State HANGUP
  291. 2017-06-01 14:25:32.946540 [DEBUG] mod_sofia.c:440 Channel sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com hanging up, cause: NORMAL_CLEARING
  292. 2017-06-01 14:25:32.946540 [DEBUG] mod_sofia.c:579 Responding to INVITE with: 480
  293. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:60 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard HANGUP, cause: NORMAL_CLEARING
  294. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State HANGUP going to sleep
  295. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State Change CS_HANGUP -> CS_REPORTING
  296. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_REPORTING (Cur 2 Tot 22)
  297. send 1005 bytes to tcp/[10.0.0.26]:19113 at 14:25:32.965440:
  298.    ------------------------------------------------------------------------
  299.    SIP/2.0 480 Temporarily Unavailable
  300.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.NR0T-~iUI;rport=19113;received=10.0.0.26
  301.    Max-Forwards: 70
  302.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=Sp3s3vgzl
  303.    To: <sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=N36F5yUyQjyHa
  304.    Call-ID: L~ZcU3sS4X
  305.    CSeq: 20 INVITE
  306.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170109T225752Z~6fe1deda45~64bit
  307.    Accept: application/sdp
  308.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  309.    Supported: timer, path, replaces
  310.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  311.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  312.    Content-Length: 0
  313.    Remote-Party-ID: "12149119111" <sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;party=calling;privacy=off;screen=no
  314.    
  315.    ------------------------------------------------------------------------
  316. 2017-06-01 14:25:32.946540 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State REPORTING
  317. 2017-06-01 14:25:33.086522 [ERR] mod_xml_cdr.c:395 Got error [500] posting to web server [https://csp.nga911.com/call/LogXML]
  318. 2017-06-01 14:25:33.086522 [ERR] mod_xml_cdr.c:402 Retry will be with url [https://csp.nga911.com/call/LogXML]
  319. recv 540 bytes from tcp/[10.0.0.26]:19113 at 14:25:33.103146:
  320.    ------------------------------------------------------------------------
  321.    ACK sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SIP/2.0
  322.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.NR0T-~iUI;rport
  323.    Call-ID: L~ZcU3sS4X
  324.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=Sp3s3vgzl
  325.    To: <sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=N36F5yUyQjyHa
  326.    Contact: <sip:12132841480@10.0.0.26:19113;transport=tcp>;+sip.instance="<urn:uuid:8737813e-5d59-4f16-b563-b7fa8ffbd161>"
  327.    Max-Forwards: 70
  328.    CSeq: 20 ACK
  329.    Content-Length: 0
  330.    
  331.    ------------------------------------------------------------------------
  332. 2017-06-01 14:25:33.586521 [ERR] mod_xml_cdr.c:395 Got error [500] posting to web server [https://csp.nga911.com/call/LogXML]
  333. 2017-06-01 14:25:33.586521 [ERR] mod_xml_cdr.c:402 Retry will be with url [https://csp.nga911.com/call/LogXML]
  334. freeswitch@ip-10-0-0-245>
  335. freeswitch@ip-10-0-0-245>
  336. freeswitch@ip-10-0-0-245>
  337. freeswitch@ip-10-0-0-245>
  338. freeswitch@ip-10-0-0-245>
  339. freeswitch@ip-10-0-0-245>
  340. 2017-06-01 14:25:35.366533 [ERR] mod_xml_cdr.c:395 Got error [500] posting to web server [https://csp.nga911.com/call/LogXML]
  341. 2017-06-01 14:25:35.366533 [ERR] mod_xml_cdr.c:402 Retry will be with url [https://csp.nga911.com/call/LogXML]
  342. 2017-06-01 14:25:35.766523 [ERR] mod_xml_cdr.c:395 Got error [500] posting to web server [https://csp.nga911.com/call/LogXML]
  343. 2017-06-01 14:25:35.766523 [ERR] mod_xml_cdr.c:402 Retry will be with url [https://csp.nga911.com/call/LogXML]
  344. 2017-06-01 14:25:35.766523 [ERR] mod_xml_cdr.c:418 Unable to post to web server, writing to file
  345. 2017-06-01 14:25:35.766523 [DEBUG] switch_core_state_machine.c:174 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard REPORTING, cause: NORMAL_CLEARING
  346. 2017-06-01 14:25:35.766523 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State REPORTING going to sleep
  347. 2017-06-01 14:25:35.766523 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State Change CS_REPORTING -> CS_DESTROY
  348. 2017-06-01 14:25:35.766523 [DEBUG] switch_core_session.c:1695 Session 21 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Locked, Waiting on external entities
  349. 2017-06-01 14:25:35.766523 [NOTICE] switch_core_session.c:1713 Session 21 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Ended
  350. 2017-06-01 14:25:35.766523 [NOTICE] switch_core_session.c:1717 Close Channel sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com [CS_DESTROY]
  351. 2017-06-01 14:25:35.766523 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) Running State Change CS_DESTROY (Cur 1 Tot 22)
  352. 2017-06-01 14:25:35.766523 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State DESTROY
  353. 2017-06-01 14:25:35.766523 [DEBUG] mod_sofia.c:345 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SOFIA DESTROY
  354. 2017-06-01 14:25:35.766523 [DEBUG] switch_core_state_machine.c:181 sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com Standard DESTROY
  355. 2017-06-01 14:25:35.766523 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com) State DESTROY going to sleep
  356. 2017-06-01 14:25:37.546520 [ERR] mod_xml_cdr.c:395 Got error [500] posting to web server [https://csp.nga911.com/call/LogXML]
  357. 2017-06-01 14:25:37.546520 [ERR] mod_xml_cdr.c:402 Retry will be with url [https://csp.nga911.com/call/LogXML]
  358. recv 1416 bytes from tcp/[10.0.0.26]:19113 at 14:25:39.355115:
  359.    ------------------------------------------------------------------------
  360.    INVITE sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com SIP/2.0
  361.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.23-pYygXa;rport
  362.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=bbm0c17W0
  363.    To: sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com
  364.    CSeq: 20 INVITE
  365.    Call-ID: fQvc2ms6UT
  366.    Max-Forwards: 70
  367.    Supported: replaces, outbound
  368.   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
  369.    Content-Type: application/sdp
  370.    Content-Length: 550
  371.    Contact: <sip:12132841480@10.0.0.26:19113;transport=tcp>;+sip.instance="<urn:uuid:8737813e-5d59-4f16-b563-b7fa8ffbd161>"
  372.    X-callerLatitude: 32.1011897
  373.    X-callerLongitude: -96.624755
  374.    X-callerLocationDateTime: 2017-06-01 14:25:37
  375.    X-serviceRequest: doctor
  376.    User-Agent: NGA911Android/3.2.6 (belle-sip/1.6.1)
  377.    
  378.    v=0
  379.    o=12132841480 3978 2614 IN IP4 10.0.0.26
  380.    s=Talk
  381.    c=IN IP4 10.0.0.26
  382.    b=AS:380
  383.    t=0 0
  384.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
  385.    m=audio 61218 RTP/AVP 0 8 18 101
  386.    a=fmtp:18 annexb=yes
  387.    a=rtpmap:101 telephone-event/8000
  388.    a=rtcp-fb:* ccm tmmbr
  389.    m=video 29820 RTP/AVP 96 97
  390.    a=rtpmap:96 VP8/90000
  391.    a=rtpmap:97 H264/90000
  392.    a=fmtp:97 profile-level-id=42801F
  393.    a=rtcp-fb:* ccm tmmbr
  394.    a=rtcp-fb:96 nack pli
  395.    a=rtcp-fb:96 nack sli
  396.    a=rtcp-fb:96 ack rpsi
  397.    a=rtcp-fb:96 ccm fir
  398.    a=rtcp-fb:97 nack pli
  399.    a=rtcp-fb:97 ccm fir
  400.    ------------------------------------------------------------------------
  401. send 422 bytes to tcp/[10.0.0.26]:19113 at 14:25:39.355365:
  402.    ------------------------------------------------------------------------
  403.    SIP/2.0 100 Trying
  404.    Via: SIP/2.0/TCP 104.34.194.130:49384;branch=z9hG4bK.23-pYygXa;rport=19113;received=10.0.0.26
  405.    From: <sip:12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com>;tag=bbm0c17W0
  406.    To: sip:12149119111@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com
  407.    Call-ID: fQvc2ms6UT
  408.    CSeq: 20 INVITE
  409.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170109T225752Z~6fe1deda45~64bit
  410.    Content-Length: 0
  411.    
  412.    ------------------------------------------------------------------------
  413. 2017-06-01 14:25:39.346540 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/12132841480@E911LoadBalancer-473001725.us-west-2.elb.amazonaws.com [40fec172-53a6-44e0-94f2-e073b54c19dd]
  414.  

Reply to "Untitled"