Berofix Gatewaykonfiguration / Routingkonfiguration - fehlende Rufnummer im SIP TO Feld

128 views
Skip to first unread message

AndreasB

unread,
Apr 2, 2016, 6:49:00 PM4/2/16
to gs5-users
Hallo an alle,

ich versuche folgendes Szenario zum laufen zu bringen (Szenario ist hier reduziert damit es nicht zu verwirrend wird, z.B. kein VoIP Provider):

  • ISDN Anschluss mit 4 MSN Rufnummern
  • 2 VoIP Telefone
  • 2 ISDN Telefone
  • 1 Berofix Gateway mit 2 ISDN Ports
    • Berofix ISDN Port 1: Anschluss ans externe ISDN Netz über NTBA
    • Berofix ISDN Port 2: Anschluss ans internen ISDN S0 Bus mit den 2 ISDN Telefonen dran
  • GS5 als Telefonanlage
Da ich die Berofix wie 2 Gateways benutze (zum ersten als Gateway zum externen ISDN Netz, zum zweiten als Gateway zum internen ISDN Bus), gehe ich davon aus, das eine Authentifizierung an der GS5 per IP nicht geht (weil ja beide Gateways auf der selben IP laufen) und dass ich somit User/Passwort verwenden muss. Ich habe mich entschlosse die Berofix an der GS5 per inbound_username/inbound_password anmelden zu lassen zum Zwecke der Identifizierung/Authentifizierung, weiß aber nicht ob es Alternativen gibt. Die Anmeldung der Berofix mit den Konten für internes und externes ISDN funktioniert auch.

Nun habe ich verschiedenste Varianten probiert, aber bei den INVITE Paketen an den "Berofix_int.ISDN"-Gateway-Account fehlt immer die Zielrufnummer im To Feld. Da steht dann immer nur: "To: <sip:Berofix_...@192.168.0.2:5060>".

Damit kann die Berofix natürlich nichts anfangen und gibt "SIP/2.0 603 Invalid Number" zurück.

Die GS5 Gateway Konfiguration lautet wie folgt:

<?xml version='1.0' encoding='utf-8' ?>
<gateway description='Beronet VoIP Gateway - ISDN Intern' identifier='gateway3' inbound='true' name='Berofix - Interne Telefone' outbound='true' technology='sip'>
    <gateway_settings count='5'>
        <setting class_type='String' description='IP der Berofix' name='domain' value='192.168.0.2'></setting>
        <setting class_type='String' description='' name='inbound_username' value='Berofix_int.ISDN'></setting>
        <setting class_type='String' description='' name='inbound_password' value='geheim'></setting>
        <setting class_type='Boolean' description='' name='register' value='false'></setting>
        <setting class_type='String' description='' name='number_source' value='sip_to_user'></setting>
    </gateway_settings>
    <gateway_parameters count='2'>
        <parameter class_type='String' description='IP der Berofix' name='proxy' value='192.168.0.2'></parameter>
        <parameter class_type='String' description='' name='extension' value='{sip_to_user}'></parameter>
    </gateway_parameters>
    <call_routes count='2'>
        <call_route name='Berofix - Interne Telefone' position='1' routing_table='inbound'>
            <route_elements count='0'>
            </route_elements>
        </call_route>
        <call_route name='Berofix - Interne Telefone' position='1' routing_table='outbound'>
            <route_elements count='2'>
                <route_element action='match' mandatory='true' pattern='^(9876%d%d?)$' position='1' replacement='%1' var_in='destination_number' var_out='destination_number'></route_element>
                <route_element action='set' mandatory='false' pattern='^%+(49.+)$' position='2' replacement='%1' var_in='val:caller_id_numbers' var_out='caller_id_name'></route_element>
            </route_elements>
        </call_route>
    </call_routes>
</gateway>



Wenn ich jetzt z.B. vom internen Softphone (192.168.0.32) das interne ISDN Telefon über GS5 (IG-PBX-DGEM-002.gs5.local) und Berofix (192.168.0.2) erreichen will sieht das so aus:

00:11:16.984431 IP (tos 0x0, ttl 128, id 7942, offset 0, flags [none], proto UDP (17), length 1500)
    192.168.0.32.sip > IG-PBX-DGEM-002.gs5.local.sip: SIP, length: 1472
        INVITE sip:987654@sip.gs5.local SIP/2.0
        Via: SIP/2.0/UDP 192.168.0.32:5060;branch=z9hG4bK8070577d8df7e5118446e19f9fac79b6;rport
        From: "PC" <sip:e2d98ac0f63c944b7138@sip.gs5.local>;tag=3074236959
        To: <sip:987654@sip.gs5.local>
        Call-ID: 8070577D-8DF7-E511-8443-E19F9FAC79B6@192.168.0.32
        CSeq: 153 INVITE
        Contact: <sip:e2d98ac0f63c944b7138@192.168.0.32:5060>
        Proxy-Authorization: Digest username="e2d98ac0f63c944b7138", realm="sip.gs5.local", nonce="975df09b-5cfd-4d49-a664-2b2c9ffb7168", uri="sip:987...@sip.gs5.local", response="000aa486a586850c6c4773c00ae937ee", algorithm=MD5, cnonce="6070577d8df7e5228995e19f9fac79b4", qop=auth, nc=00000001
        Content-Type: application/sdp
        Allow: INVITE, ACK, BYE, CANCEL, INFO, MESSAGE, NOTIFY, OPTIONS, REFER, UPDATE
        Max-Forwards: 70
        Supported: 100rel, replaces, from-change
        P-Early-Media: supported
        User-Agent: SIPPER for PhonerLite
        P-Preferred-Identity: <sip:e2d98ac0f63c944b7138@sip.gs5.local>
        Content-Length:   448




Das INVITE Paket von GS5 (IG-PBX-DGEM-002.gs5.local) an die Berofix (192.168.0.2) sieht aber so aus:

00:11:17.022414 IP (tos 0x0, ttl 64, id 13910, offset 0, flags [none], proto UDP (17), length 1208)
    IG-PBX-DGEM-002.gs5.local.sip > 192.168.0.2.sip: SIP, length: 1180
        INVITE sip:Berofix_int.ISDN@192.168.0.2:5060 SIP/2.0
        Via: SIP/2.0/UDP 192.168.0.1;rport;branch=z9hG4bKHK36pgQ9295jH
        Max-Forwards: 69
        From: "491234987655" <sip:+491234987655@192.168.0.1>;tag=78eBDrrc84B7B
        To: <sip:Berofix_int.ISDN@192.168.0.2:5060>
        Call-ID: a98c96aa-73c2-1234-cb8e-000c29ce1dbe
        CSeq: 89481474 INVITE
        Contact: <sip:mod_sofia@192.168.0.1:5060>
        User-Agent: Gemeinschaft5
        Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
        Supported: timer, path, replaces
        Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
        Privacy: none
        Content-Type: application/sdp
        Content-Disposition: session
        Content-Length: 213
        X-FS-Support: update_display,send_info
        Alert-Info: <http://amooma.de>;info=Ringer1;x-line-id=0
        P-Asserted-Identity: "491234987655" <sip:+491234987655@192.168.0.1>

00:11:17.027527 IP (tos 0xa0, ttl 64, id 16860, offset 0, flags [none], proto UDP (17), length 374)
    192.168.0.2.sip > IG-PBX-DGEM-002.gs5.local.sip: SIP, length: 346
        SIP/2.0 100 Trying
        Via: SIP/2.0/UDP 192.168.0.1;rport=5060;branch=z9hG4bKHK36pgQ9295jH
        From: "491234987655" <sip:+491234987655@192.168.0.1>;tag=78eBDrrc84B7B
        To: <sip:Berofix_int.ISDN@192.168.0.2:5060>
        Call-ID: a98c96aa-73c2-1234-cb8e-000c29ce1dbe
        CSeq: 89481474 INVITE
        User-Agent: Berofix VOIP Gateway (3.0.08)
        Content-Length: 0


00:11:17.034750 IP (tos 0xa0, ttl 64, id 16861, offset 0, flags [none], proto UDP (17), length 620)
    192.168.0.2.sip > IG-PBX-DGEM-002.gs5.local.sip: SIP, length: 592
        SIP/2.0 603 Invalid number
        Via: SIP/2.0/UDP 192.168.0.1;rport=5060;branch=z9hG4bKHK36pgQ9295jH
        From: "491234987655" <sip:+491234987655@192.168.0.1>;tag=78eBDrrc84B7B
        To: <sip:Berofix_int.ISDN@192.168.0.2:5060>;tag=e87QF9K13ByDm
        Call-ID: a98c96aa-73c2-1234-cb8e-000c29ce1dbe
        CSeq: 89481474 INVITE
        User-Agent: Berofix VOIP Gateway (3.0.08)
        Accept: application/sdp
        Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, INFO, REGISTER
        Supported: timer, 100rel, replaces
        Reason: ISGW;cause=0;text="Invalid number format"
        Content-Length: 0

Jetzt komme ich nicht weiter, wie bekomme ich die Zielrufnummer im INVITE Paket zwischen GS5 und Berofix in das To Feld?

Voller Verzweiflung hoffe auf eure Tipps.

Freundliche Grüße

Andreas B.

AndreasB

unread,
Apr 3, 2016, 9:53:12 AM4/3/16
to gs5-users
Weitere Infos:

Ich habe auf  http://<ip_address>/gs_parameters
   dump_variables        ->  true
gesetzt.
Damit kann man nach einem Neustart auf der FS Konsole ( Befehl:  fs_cli -l 7 ) sehen, welche Nummer (und andere Header) üebertragen werrden.

Folgendes wurde mitgeschnitten:

2016-04-03 12:26:59.778188 [NOTICE] switch_channel.c:1049 New Channel sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [cee71632-7570-4689-9b79-2445ab6e35ac]
2016-04-03 12:26:59.778188 [DEBUG] switch_core_session.c:1016 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.778188 [DEBUG] switch_core_session.c:1016 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_NEW
2016-04-03 12:26:59.778188 [DEBUG] sofia.c:7916 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local receiving invite from 192.168.0.32:5060 version: 1.2.23 -n20140703T120559Z-1~wheezy+1 32bit
2016-04-03 12:26:59.778188 [DEBUG] sofia.c:5847 Channel sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local entering state [received][100]
2016-04-03 12:26:59.778188 [DEBUG] sofia.c:5860 Remote SDP:
v=0
o=- 1558029358 1 IN IP4 192.168.0.32
s=SIPPER for PhonerLite
c=IN IP4 192.168.0.32
t=0 0
m=audio 5062 RTP/AVP 107 8 0 2 3 97 110 111 9 101
a=rtpmap:107 opus/48000/2
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:950052159

2016-04-03 12:26:59.778188 [DEBUG] sofia_glue.c:5284 Audio Codec Compare [opus:107:48000:20:0]/[PCMA:8:8000:20:64000]
2016-04-03 12:26:59.778188 [DEBUG] sofia_glue.c:5284 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2016-04-03 12:26:59.778188 [DEBUG] sofia_glue.c:3192 Set Codec sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local PCMA/8000 20 ms 160 samples 64000 bits
2016-04-03 12:26:59.778188 [DEBUG] switch_core_codec.c:111 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Original read codec set to PCMA:8
2016-04-03 12:26:59.778188 [DEBUG] sofia_glue.c:5453 Set 2833 dtmf send/recv payload to 101
2016-04-03 12:26:59.778188 [DEBUG] sofia.c:6127 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_NEW -> CS_INIT
2016-04-03 12:26:59.778188 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:437 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State NEW
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_INIT
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:458 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State INIT
2016-04-03 12:26:59.778188 [DEBUG] mod_sofia.c:87 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SOFIA INIT
2016-04-03 12:26:59.778188 [DEBUG] mod_sofia.c:127 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_INIT -> CS_ROUTING
2016-04-03 12:26:59.778188 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:458 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State INIT going to sleep
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_ROUTING
2016-04-03 12:26:59.778188 [DEBUG] switch_channel.c:2165 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Callstate Change DOWN -> RINGING
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:474 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State ROUTING
2016-04-03 12:26:59.778188 [DEBUG] mod_sofia.c:150 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SOFIA ROUTING
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:117 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard ROUTING
2016-04-03 12:26:59.778188 [INFO] mod_dialplan_xml.c:558 Processing PC <e2d98ac0f63c944b7138>->987654 in context default
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local parsing [default->invoke_default_dialplan] continue=true
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Absolute Condition [invoke_default_dialplan]
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Action set(script=${lua(dialplan_default.lua)})
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local parsing [default->transfer_loop] continue=false
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Regex (FAIL) [transfer_loop] endpoint_disposition() =~ /BLIND_TRANSFER/ break=on-false
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:167 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_ROUTING -> CS_EXECUTE
2016-04-03 12:26:59.778188 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:474 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State ROUTING going to sleep
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_EXECUTE
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:481 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State EXECUTE
2016-04-03 12:26:59.778188 [DEBUG] mod_sofia.c:243 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SOFIA EXECUTE
2016-04-03 12:26:59.778188 [DEBUG] switch_core_state_machine.c:209 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard EXECUTE
2016-04-03 12:26:59.778188 [CONSOLE] sofia_presence.c:1610 Event Thread Started
2016-04-03 12:26:59.798168 [DEBUG] freeswitch_lua.cpp:360 DBH handle 0xb5db9c98 Connected.
2016-04-03 12:26:59.798168 [DEBUG] freeswitch_lua.cpp:360 DBH handle 0xb5db9c98 Connected.
2016-04-03 12:26:59.798168 [NOTICE] switch_cpp.cpp:1288 ### [cee71632-7570-4689-9b79-2445ab6e35ac] DIALPLAN_DOMAIN - setting default domain: 192.168.0.1
EXECUTE sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local info(notice)
2016-04-03 12:26:59.798168 [NOTICE] mod_dptools.c:1614 CHANNEL_DATA:
Channel-State: [CS_EXECUTE]
Channel-Call-State: [RINGING]
Channel-State-Number: [4]
Channel-Name: [sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local]
Unique-ID: [cee71632-7570-4689-9b79-2445ab6e35ac]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Channel-HIT-Dialplan: [true]
Channel-Presence-ID: [e2d98ac0f63c944b7138@sip.gs5.local]
Channel-Call-UUID: [cee71632-7570-4689-9b79-2445ab6e35ac]
Answer-State: [ringing]
Channel-Read-Codec-Name: [PCMA]
Channel-Read-Codec-Rate: [8000]
Channel-Read-Codec-Bit-Rate: [64000]
Channel-Write-Codec-Name: [PCMA]
Channel-Write-Codec-Rate: [8000]
Channel-Write-Codec-Bit-Rate: [64000]
Caller-Direction: [inbound]
Caller-Username: [e2d98ac0f63c944b7138]
Caller-Dialplan: [XML]
Caller-Caller-ID-Name: [PC]
Caller-Caller-ID-Number: [e2d98ac0f63c944b7138]
Caller-Orig-Caller-ID-Name: [PC]
Caller-Orig-Caller-ID-Number: [e2d98ac0f63c944b7138]
Caller-Network-Addr: [192.168.0.32]
Caller-ANI: [e2d98ac0f63c944b7138]
Caller-Destination-Number: [987654]
Caller-Unique-ID: [cee71632-7570-4689-9b79-2445ab6e35ac]
Caller-Source: [mod_sofia]
Caller-Context: [default]
Caller-Channel-Name: [sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1459679219778188]
Caller-Channel-Created-Time: [1459679219778188]
Caller-Channel-Answered-Time: [0]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [0]
Caller-Channel-Transfer-Time: [0]
Caller-Channel-Resurrect-Time: [0]
Caller-Channel-Bridged-Time: [0]
Caller-Channel-Last-Hold: [0]
Caller-Channel-Hold-Accum: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
variable_direction: [inbound]
variable_uuid: [cee71632-7570-4689-9b79-2445ab6e35ac]
variable_session_id: [1]
variable_sip_from_user: [e2d98ac0f63c944b7138]
variable_sip_from_uri: [e2d98ac0f63c944b7138@sip.gs5.local]
variable_sip_from_host: [sip.gs5.local]
variable_channel_name: [sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local]
variable_sip_call_id: [801B0644-F4F7-E511-8464-E19F9FAC79B6@192.168.0.32]
variable_sip_local_network_addr: [192.168.0.1]
variable_sip_network_ip: [192.168.0.32]
variable_sip_network_port: [5060]
variable_sip_received_ip: [192.168.0.32]
variable_sip_received_port: [5060]
variable_sip_via_protocol: [udp]
variable_sip_from_user_stripped: [e2d98ac0f63c944b7138]
variable_sip_from_tag: [961864493]
variable_sofia_profile_name: [gemeinschaft]
variable_recovery_profile_name: [gemeinschaft]
variable_sip_P-Preferred-Identity: [<sip:e2d98ac0f63c944b7138@sip.gs5.local>]
variable_sip_cid_type: [pid]
variable_sip_full_via: [SIP/2.0/UDP 192.168.0.32:5060;branch=z9hG4bK801b0644f4f7e5118465e19f9fac79b6;rport=5060]
variable_sip_from_display: [PC]
variable_sip_full_from: ["PC" <sip:e2d98ac0f63c944b7138@sip.gs5.local>;tag=961864493]
variable_sip_full_to: [<sip:987654@sip.gs5.local>]
variable_sip_req_user: [987654]
variable_sip_req_uri: [987654@sip.gs5.local]
variable_sip_req_host: [sip.gs5.local]
variable_sip_to_user: [987654]
variable_sip_to_uri: [987654@sip.gs5.local]
variable_sip_to_host: [sip.gs5.local]
variable_sip_contact_user: [e2d98ac0f63c944b7138]
variable_sip_contact_port: [5060]
variable_sip_contact_uri: [e2d98ac0f63c944b7138@192.168.0.32:5060]
variable_sip_contact_host: [192.168.0.32]
variable_rtp_use_codec_string: [PCMA,G7221@32000h,G7221@16000h,G722,PCMU,GSM]
variable_sip_user_agent: [SIPPER for PhonerLite]
variable_sip_via_host: [192.168.0.32]
variable_sip_via_port: [5060]
variable_sip_via_rport: [5060]
variable_max_forwards: [70]
variable_presence_id: [e2d98ac0f63c944b7138@sip.gs5.local]
variable_sip_h_P-Early-Media: [supported]
variable_switch_r_sdp: [v=0
o=- 1558029358 1 IN IP4 192.168.0.32
s=SIPPER for PhonerLite
c=IN IP4 192.168.0.32
t=0 0
m=audio 5062 RTP/AVP 107 8 0 2 3 97 110 111 9 101
a=rtpmap:107 opus/48000/2
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:950052159
]
variable_remote_media_ip: [192.168.0.32]
variable_remote_media_port: [5062]
variable_sip_audio_recv_pt: [8]
variable_sip_use_codec_name: [PCMA]
variable_sip_use_codec_rate: [8000]
variable_sip_use_codec_ptime: [20]
variable_read_codec: [PCMA]
variable_read_rate: [8000]
variable_write_codec: [PCMA]
variable_write_rate: [8000]
variable_endpoint_disposition: [RECEIVED]
variable_call_uuid: [cee71632-7570-4689-9b79-2445ab6e35ac]
variable_gs_account_node_local: [true]
variable_current_application_data: [notice]
variable_current_application: [info]


2016-04-03 12:26:59.798168 [DEBUG] switch_cpp.cpp:1288 ### [cee71632-7570-4689-9b79-2445ab6e35ac] AUTHENTICATION_REQUIRED_SIP_ACCOUNT - contact host: 192.168.0.32, ip: 192.168.0.32, domain: 192.168.0.1
EXECUTE sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local respond(407 192.168.0.1)
2016-04-03 12:26:59.798168 [DEBUG] mod_sofia.c:2713 Responding with 407 [192.168.0.1]
2016-04-03 12:26:59.798168 [NOTICE] mod_sofia.c:2772 Hangup sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [CS_EXECUTE] [CALL_REJECTED]
2016-04-03 12:26:59.798168 [DEBUG] switch_channel.c:3189 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [KILL]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:2817 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-04-03 12:26:59.798168 [DEBUG] switch_cpp.cpp:670 CoreSession::hangup
2016-04-03 12:26:59.798168 [DEBUG] freeswitch_lua.cpp:377 DBH handle 0xb5db9c98 released.
2016-04-03 12:26:59.798168 [DEBUG] freeswitch_lua.cpp:377 DBH handle 0xb5db9c98 released.
2016-04-03 12:26:59.798168 [DEBUG] switch_cpp.cpp:1051 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local destroy/unlink session from object
EXECUTE sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local set(script=)
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:2802 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local skip receive message [APPLICATION_EXEC] (channel is hungup already)
2016-04-03 12:26:59.798168 [DEBUG] mod_dptools.c:1402 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SET [script]=[UNDEF]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:2817 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:481 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State EXECUTE going to sleep
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_HANGUP
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:681 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Callstate Change RINGING -> HANGUP
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:683 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State HANGUP
2016-04-03 12:26:59.798168 [DEBUG] mod_sofia.c:506 Channel sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local hanging up, cause: CALL_REJECTED
2016-04-03 12:26:59.798168 [DEBUG] mod_sofia.c:602 Challenging call
2016-04-03 12:26:59.798168 [DEBUG] mod_sofia.c:640 Responding to INVITE with: 407
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:48 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard HANGUP, cause: CALL_REJECTED
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:683 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State HANGUP going to sleep
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:450 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_HANGUP -> CS_REPORTING
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_REPORTING
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:767 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State REPORTING
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:92 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard REPORTING, cause: CALL_REJECTED
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:767 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State REPORTING going to sleep
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:444 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_REPORTING -> CS_DESTROY
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:1559 Session 1 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Locked, Waiting on external entities
2016-04-03 12:26:59.798168 [NOTICE] switch_core_session.c:1577 Session 1 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Ended
2016-04-03 12:26:59.798168 [NOTICE] switch_core_session.c:1581 Close Channel sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [CS_DESTROY]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:572 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_DESTROY
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:582 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State DESTROY
2016-04-03 12:26:59.798168 [DEBUG] mod_sofia.c:399 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SOFIA DESTROY
2016-04-03 12:26:59.798168 [NOTICE] switch_channel.c:1049 New Channel sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:1016 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:1016 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_NEW
2016-04-03 12:26:59.798168 [DEBUG] sofia.c:7916 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local receiving invite from 192.168.0.32:5060 version: 1.2.23 -n20140703T120559Z-1~wheezy+1 32bit
2016-04-03 12:26:59.798168 [DEBUG] freeswitch_lua.cpp:360 DBH handle 0xb5db9c98 Connected.
2016-04-03 12:26:59.798168 [DEBUG] switch_cpp.cpp:1288 #C# [192.168.0.1] DIRECTORY_SIP_ACCOUNT - auth_name: e2d98ac0f63c944b7138, caller_name: PC, domain: 192.168.0.1
2016-04-03 12:26:59.798168 [DEBUG] freeswitch_lua.cpp:377 DBH handle 0xb5db9c98 released.
2016-04-03 12:26:59.798168 [DEBUG] sofia.c:5847 Channel sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local entering state [received][100]
2016-04-03 12:26:59.798168 [DEBUG] sofia.c:5860 Remote SDP:
v=0
o=- 1558029358 1 IN IP4 192.168.0.32
s=SIPPER for PhonerLite
c=IN IP4 192.168.0.32
t=0 0
m=audio 5062 RTP/AVP 107 8 0 2 3 97 110 111 9 101
a=rtpmap:107 opus/48000/2
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:950052159

2016-04-03 12:26:59.798168 [DEBUG] sofia_glue.c:5284 Audio Codec Compare [opus:107:48000:20:0]/[PCMA:8:8000:20:64000]
2016-04-03 12:26:59.798168 [DEBUG] sofia_glue.c:5284 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2016-04-03 12:26:59.798168 [DEBUG] sofia_glue.c:3192 Set Codec sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local PCMA/8000 20 ms 160 samples 64000 bits
2016-04-03 12:26:59.798168 [DEBUG] switch_core_codec.c:111 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Original read codec set to PCMA:8
2016-04-03 12:26:59.798168 [DEBUG] sofia_glue.c:5453 Set 2833 dtmf send/recv payload to 101
2016-04-03 12:26:59.798168 [DEBUG] sofia.c:6127 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_NEW -> CS_INIT
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:437 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State NEW
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_INIT
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:458 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State INIT
2016-04-03 12:26:59.798168 [DEBUG] mod_sofia.c:87 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SOFIA INIT
2016-04-03 12:26:59.798168 [DEBUG] mod_sofia.c:127 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_INIT -> CS_ROUTING
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:458 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State INIT going to sleep
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_ROUTING
2016-04-03 12:26:59.798168 [DEBUG] switch_channel.c:2165 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Callstate Change DOWN -> RINGING
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:474 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State ROUTING
2016-04-03 12:26:59.798168 [DEBUG] mod_sofia.c:150 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SOFIA ROUTING
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:117 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard ROUTING
2016-04-03 12:26:59.798168 [INFO] mod_dialplan_xml.c:558 Processing PC <e2d98ac0f63c944b7138>->987654 in context default
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local parsing [default->invoke_default_dialplan] continue=true
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Absolute Condition [invoke_default_dialplan]
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Action set(script=${lua(dialplan_default.lua)})
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local parsing [default->transfer_loop] continue=false
Dialplan: sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Regex (FAIL) [transfer_loop] endpoint_disposition() =~ /BLIND_TRANSFER/ break=on-false
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:167 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_ROUTING -> CS_EXECUTE
2016-04-03 12:26:59.798168 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:474 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State ROUTING going to sleep
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_EXECUTE
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:481 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State EXECUTE
2016-04-03 12:26:59.798168 [DEBUG] mod_sofia.c:243 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SOFIA EXECUTE
2016-04-03 12:26:59.798168 [DEBUG] switch_core_state_machine.c:209 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard EXECUTE
2016-04-03 12:26:59.818197 [DEBUG] freeswitch_lua.cpp:360 DBH handle 0xb5db9c98 Connected.
EXECUTE sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local info(notice)
2016-04-03 12:26:59.818197 [NOTICE] mod_dptools.c:1614 CHANNEL_DATA:
Channel-State: [CS_EXECUTE]
Channel-Call-State: [RINGING]
Channel-State-Number: [4]
Channel-Name: [sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local]
Unique-ID: [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Channel-HIT-Dialplan: [true]
Channel-Presence-ID: [e2d98ac0f63c944b7138@sip.gs5.local]
Channel-Call-UUID: [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b]
Answer-State: [ringing]
Channel-Read-Codec-Name: [PCMA]
Channel-Read-Codec-Rate: [8000]
Channel-Read-Codec-Bit-Rate: [64000]
Channel-Write-Codec-Name: [PCMA]
Channel-Write-Codec-Rate: [8000]
Channel-Write-Codec-Bit-Rate: [64000]
Caller-Direction: [inbound]
Caller-Username: [e2d98ac0f63c944b7138]
Caller-Dialplan: [XML]
Caller-Caller-ID-Name: [PC]
Caller-Caller-ID-Number: [e2d98ac0f63c944b7138]
Caller-Orig-Caller-ID-Name: [PC]
Caller-Orig-Caller-ID-Number: [e2d98ac0f63c944b7138]
Caller-Network-Addr: [192.168.0.32]
Caller-ANI: [e2d98ac0f63c944b7138]
Caller-Destination-Number: [987654]
Caller-Unique-ID: [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b]
Caller-Source: [mod_sofia]
Caller-Context: [default]
Caller-Channel-Name: [sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1459679219798168]
Caller-Channel-Created-Time: [1459679219798168]
Caller-Channel-Answered-Time: [0]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [0]
Caller-Channel-Transfer-Time: [0]
Caller-Channel-Resurrect-Time: [0]
Caller-Channel-Bridged-Time: [0]
Caller-Channel-Last-Hold: [0]
Caller-Channel-Hold-Accum: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
variable_direction: [inbound]
variable_uuid: [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b]
variable_session_id: [2]
variable_sip_from_user: [e2d98ac0f63c944b7138]
variable_sip_from_uri: [e2d98ac0f63c944b7138@sip.gs5.local]
variable_sip_from_host: [sip.gs5.local]
variable_channel_name: [sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local]
variable_sip_call_id: [801B0644-F4F7-E511-8464-E19F9FAC79B6@192.168.0.32]
variable_sip_local_network_addr: [192.168.0.1]
variable_sip_network_ip: [192.168.0.32]
variable_sip_network_port: [5060]
variable_sip_received_ip: [192.168.0.32]
variable_sip_received_port: [5060]
variable_sip_via_protocol: [udp]
variable_sip_authorized: [true]
variable_Event-Name: [REQUEST_PARAMS]
variable_Core-UUID: [ff80067f-436d-433c-85c0-227ab79ac0fd]
variable_FreeSWITCH-Hostname: [IG-PBX-DGEM-002]
variable_FreeSWITCH-Switchname: [IG-PBX-DGEM-002]
variable_FreeSWITCH-IPv4: [192.168.0.1]
variable_FreeSWITCH-IPv6: [::1]
variable_Event-Date-Local: [2016-04-03 12:26:59]
variable_Event-Date-GMT: [Sun, 03 Apr 2016 10:26:59 GMT]
variable_Event-Date-Timestamp: [1459679219798168]
variable_Event-Calling-File: [sofia.c]
variable_Event-Calling-Function: [sofia_handle_sip_i_invite]
variable_Event-Calling-Line-Number: [8115]
variable_Event-Sequence: [471]
variable_sip_number_alias: [e2d98ac0f63c944b7138]
variable_sip_auth_username: [e2d98ac0f63c944b7138]
variable_sip_auth_realm: [sip.gs5.local]
variable_number_alias: [e2d98ac0f63c944b7138]
variable_requested_domain_name: [192.168.0.1]
variable_gs_account_uuid: [2576a3c0-a97e-0131-0496-000c29ac92f9]
variable_gs_account_owner_id: [1]
variable_user_context: [default]
variable_gs_account_type: [SipAccount]
variable_gs_account_id: [1]
variable_gs_account_caller_name: [PC]
variable_gs_from_gateway: [false]
variable_gs_account_owner_type: [User]
variable_user_name: [e2d98ac0f63c944b7138]
variable_domain_name: [192.168.0.1]
variable_sip_from_user_stripped: [e2d98ac0f63c944b7138]
variable_sip_from_tag: [961864493]
variable_sofia_profile_name: [gemeinschaft]
variable_recovery_profile_name: [gemeinschaft]
variable_sip_P-Preferred-Identity: [<sip:e2d98ac0f63c944b7138@sip.gs5.local>]
variable_sip_cid_type: [pid]
variable_sip_full_via: [SIP/2.0/UDP 192.168.0.32:5060;branch=z9hG4bK801b0644f4f7e5118467e19f9fac79b6;rport=5060]
variable_sip_from_display: [PC]
variable_sip_full_from: ["PC" <sip:e2d98ac0f63c944b7138@sip.gs5.local>;tag=961864493]
variable_sip_full_to: [<sip:987654@sip.gs5.local>]
variable_sip_req_user: [987654]
variable_sip_req_uri: [987654@sip.gs5.local]
variable_sip_req_host: [sip.gs5.local]
variable_sip_to_user: [987654]
variable_sip_to_uri: [987654@sip.gs5.local]
variable_sip_to_host: [sip.gs5.local]
variable_sip_contact_user: [e2d98ac0f63c944b7138]
variable_sip_contact_port: [5060]
variable_sip_contact_uri: [e2d98ac0f63c944b7138@192.168.0.32:5060]
variable_sip_contact_host: [192.168.0.32]
variable_rtp_use_codec_string: [PCMA,G7221@32000h,G7221@16000h,G722,PCMU,GSM]
variable_sip_user_agent: [SIPPER for PhonerLite]
variable_sip_via_host: [192.168.0.32]
variable_sip_via_port: [5060]
variable_sip_via_rport: [5060]
variable_max_forwards: [70]
variable_presence_id: [e2d98ac0f63c944b7138@sip.gs5.local]
variable_sip_h_P-Early-Media: [supported]
variable_switch_r_sdp: [v=0
o=- 1558029358 1 IN IP4 192.168.0.32
s=SIPPER for PhonerLite
c=IN IP4 192.168.0.32
t=0 0
m=audio 5062 RTP/AVP 107 8 0 2 3 97 110 111 9 101
a=rtpmap:107 opus/48000/2
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ssrc:950052159
]
variable_remote_media_ip: [192.168.0.32]
variable_remote_media_port: [5062]
variable_sip_audio_recv_pt: [8]
variable_sip_use_codec_name: [PCMA]
variable_sip_use_codec_rate: [8000]
variable_sip_use_codec_ptime: [20]
variable_read_codec: [PCMA]
variable_read_rate: [8000]
variable_write_codec: [PCMA]
variable_write_rate: [8000]
variable_endpoint_disposition: [RECEIVED]
variable_call_uuid: [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b]
variable_gs_account_node_local: [true]
variable_current_application_data: [notice]
variable_current_application: [info]


2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] AUTH_SIP_ACCOUNT - SipAccount=/2576a3c0-a97e-0131-0496-000c29ac92f9
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] CALLER_DATA - auth account: sipaccount=1/2576a3c0-a97e-0131-0496-000c29ac92f9, groups: user_sip_accounts,international_calls,national_calls
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] CALLER_DATA - auth owner: user=1/4b04fb40-a940-0131-048e-000c29ac92f9, groups: users
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] CALLER_DATA - caller account: sipaccount=1/2576a3c0-a97e-0131-0496-000c29ac92f9, phone_numbers: 3, language: de, groups: user_sip_accounts,international_calls,national_calls
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] CALLER_DATA - caller owner: user=1/4b04fb40-a940-0131-048e-000c29ac92f9, groups: users
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=36/remove callto: from callto link handler (click2call)
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=37/remove tel: from tel link handler (click2call)
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=1/international call
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=2/national call
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=33/local call
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=3/log in
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=4/log out
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=5/toggle ACD membership
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=6/activate CLIP
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=7/deactivate CLIP
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=8/activate CLIR
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=9/deactivate CLIR
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=10/activate CLIR for call
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=11/deactivate CLIR for call
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=12/activate call waiting
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=13/deactivate call waiting
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=14/deactivate all call forwards
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=15/delete all call forwards
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=16/set unconditional call forwarding
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=17/deactivate unconditional call forwarding
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=18/delete unconditional call forwarding
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=19/call forward if not answered
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=20/deactivate call forward if not answered
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=21/delete call forward if not answered
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=22/call forward if offline
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=23/deactivate call forward if offline
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=24/delete call forward if offline
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=25/call forward if busy
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=26/deactivate call forward if busy
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=27/delete call forward if busy
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=28/redial
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=29/check voicemail
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=30/acivate auto logout
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE_NO_MATCH - prerouting=31/deacivate auto logout
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] DESTINATION_NEW - unknown=0/@1, number: 987654
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] DIALPLAN start - caller_id: +491234987655 "PC" , number: 987654, language: de
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] LOOP 1 - destination: unknown=0/@1, number: 987654
EXECUTE sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local export(alert_info=http://amooma.de;info=Ringer1;x-line-id=0)
2016-04-03 12:26:59.818197 [DEBUG] switch_channel.c:1241 EXPORT (export_vars) [alert_info]=[http://amooma.de;info=Ringer1;x-line-id=0]
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] SWITCH - clir: false, caller_id_numbers: +491234987655,+491234987655,55,987655
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] ROUTE 1 - outbound=35/Berofix - Interne Telefonanlage, destination: gateway=3, destination_number: 987654
EXECUTE sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local export(effective_callee_id_number=987654)
2016-04-03 12:26:59.818197 [DEBUG] switch_channel.c:1241 EXPORT (export_vars) [effective_callee_id_number]=[987654]
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] FORK 1/1 - gateway=3/@1, number: 987654, caller_id: "491234987655" <+491234987655>
2016-04-03 12:26:59.818197 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] FORK DIAL - destinations: 1, timeout: 120
2016-04-03 12:26:59.818197 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] FORK SESSION_START - call_url: {local_var_clobber=true}[gs_fork_index=1,ignore_display_updates=true,origination_caller_id_number='+491234987655',origination_caller_id_name='491234987655']sofia/gemeinschaft/Berofix_int.ISDN%192.168.0.1
2016-04-03 12:26:59.818197 [DEBUG] switch_channel.c:1195 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local EXPORTING[export_vars] [alert_info]=[http://amooma.de;info=Ringer1;x-line-id=0] to event
2016-04-03 12:26:59.818197 [DEBUG] switch_channel.c:1195 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local EXPORTING[export_vars] [effective_callee_id_number]=[987654] to event
2016-04-03 12:26:59.818197 [DEBUG] switch_ivr_originate.c:2069 Parsing global variables
2016-04-03 12:26:59.818197 [DEBUG] switch_event.c:1661 Parsing variable [local_var_clobber]=[true]
2016-04-03 12:26:59.818197 [DEBUG] switch_ivr_originate.c:2504 Parsing session specific variables
2016-04-03 12:26:59.818197 [DEBUG] switch_event.c:1661 Parsing variable [gs_fork_index]=[1]
2016-04-03 12:26:59.818197 [DEBUG] switch_event.c:1661 Parsing variable [ignore_display_updates]=[true]
2016-04-03 12:26:59.818197 [DEBUG] switch_event.c:1661 Parsing variable [origination_caller_id_number]=[+491234987655]
2016-04-03 12:26:59.818197 [DEBUG] switch_event.c:1661 Parsing variable [origination_caller_id_name]=[491234987655]
2016-04-03 12:26:59.818197 [NOTICE] switch_channel.c:1049 New Channel sofia/gemeinschaft/Berofix_int.ISDN [7328f453-2adf-4620-96cb-b1206e4f7f32]
2016-04-03 12:26:59.818197 [DEBUG] mod_sofia.c:5237 (sofia/gemeinschaft/Berofix_int.ISDN) State Change CS_NEW -> CS_INIT
2016-04-03 12:26:59.818197 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.818197 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/Berofix_int.ISDN) Running State Change CS_INIT
2016-04-03 12:26:59.818197 [DEBUG] switch_core_state_machine.c:458 (sofia/gemeinschaft/Berofix_int.ISDN) State INIT
2016-04-03 12:26:59.818197 [DEBUG] mod_sofia.c:87 sofia/gemeinschaft/Berofix_int.ISDN SOFIA INIT
2016-04-03 12:26:59.818197 [DEBUG] sofia_glue.c:2746 sofia/gemeinschaft/Berofix_int.ISDN sending invite version: 1.2.23 -n20140703T120559Z-1~wheezy+1 32bit
Local SDP:
v=0
o=Gemeinschaft 1459660773 1459660774 IN IP4 192.168.0.1
s=Gemeinschaft
c=IN IP4 192.168.0.1
t=0 0
m=audio 18446 RTP/AVP 8 9 0 3 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2016-04-03 12:26:59.818197 [DEBUG] mod_sofia.c:127 (sofia/gemeinschaft/Berofix_int.ISDN) State Change CS_INIT -> CS_ROUTING
2016-04-03 12:26:59.818197 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.818197 [DEBUG] switch_core_state_machine.c:458 (sofia/gemeinschaft/Berofix_int.ISDN) State INIT going to sleep
2016-04-03 12:26:59.818197 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/Berofix_int.ISDN) Running State Change CS_ROUTING
2016-04-03 12:26:59.818197 [DEBUG] switch_core_state_machine.c:474 (sofia/gemeinschaft/Berofix_int.ISDN) State ROUTING
2016-04-03 12:26:59.818197 [DEBUG] mod_sofia.c:150 sofia/gemeinschaft/Berofix_int.ISDN SOFIA ROUTING
2016-04-03 12:26:59.818197 [DEBUG] switch_ivr_originate.c:67 (sofia/gemeinschaft/Berofix_int.ISDN) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2016-04-03 12:26:59.818197 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.818197 [DEBUG] switch_core_state_machine.c:474 (sofia/gemeinschaft/Berofix_int.ISDN) State ROUTING going to sleep
2016-04-03 12:26:59.818197 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/Berofix_int.ISDN) Running State Change CS_CONSUME_MEDIA
2016-04-03 12:26:59.818197 [DEBUG] switch_core_state_machine.c:493 (sofia/gemeinschaft/Berofix_int.ISDN) State CONSUME_MEDIA
2016-04-03 12:26:59.818197 [DEBUG] switch_core_state_machine.c:493 (sofia/gemeinschaft/Berofix_int.ISDN) State CONSUME_MEDIA going to sleep
2016-04-03 12:26:59.818197 [DEBUG] switch_core_session.c:1016 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.818197 [DEBUG] sofia.c:5847 Channel sofia/gemeinschaft/Berofix_int.ISDN entering state [calling][0]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_session.c:1016 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_session.c:1016 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_session.c:1016 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.838207 [DEBUG] sofia.c:5847 Channel sofia/gemeinschaft/Berofix_int.ISDN entering state [terminated][603]
2016-04-03 12:26:59.838207 [NOTICE] sofia.c:6694 Hangup sofia/gemeinschaft/Berofix_int.ISDN [CS_CONSUME_MEDIA] [CALL_REJECTED]
2016-04-03 12:26:59.838207 [DEBUG] switch_channel.c:3189 Send signal sofia/gemeinschaft/Berofix_int.ISDN [KILL]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/Berofix_int.ISDN) Running State Change CS_HANGUP
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:681 (sofia/gemeinschaft/Berofix_int.ISDN) Callstate Change DOWN -> HANGUP
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:683 (sofia/gemeinschaft/Berofix_int.ISDN) State HANGUP
2016-04-03 12:26:59.838207 [DEBUG] mod_sofia.c:506 Channel sofia/gemeinschaft/Berofix_int.ISDN hanging up, cause: CALL_REJECTED
2016-04-03 12:26:59.838207 [DEBUG] switch_ivr_originate.c:3656 Originate Resulted in Error Cause: 21 [CALL_REJECTED]
2016-04-03 12:26:59.838207 [DEBUG] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] FORK SESSION_INIT - dial_time: 0
2016-04-03 12:26:59.838207 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] FORK ANSWER - status: UNSPECIFIED, dial_time: 0
2016-04-03 12:26:59.838207 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] FORK EXIT - disposition: CALL_REJECTED, cause: CALL_REJECTED, code: 603, phrase: Invalid number, dial_time: 0
2016-04-03 12:26:59.838207 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] SWITCH - failover - code: 603
2016-04-03 12:26:59.838207 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] DIALPLAN end - caller_id: +491234987655 "491234987655", destination: gateway=3/@1, number: 987654, result: 603 Invalid number
EXECUTE sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local respond(603 Invalid number)
2016-04-03 12:26:59.838207 [DEBUG] mod_sofia.c:2713 Responding with 603 [Invalid number]
2016-04-03 12:26:59.838207 [NOTICE] mod_sofia.c:2772 Hangup sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [CS_EXECUTE] [CALL_REJECTED]
2016-04-03 12:26:59.838207 [DEBUG] switch_channel.c:3189 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [KILL]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_session.c:2817 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-04-03 12:26:59.838207 [INFO] switch_cpp.cpp:1288 ### [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] HANGUP_HOOK: hangup
2016-04-03 12:26:59.838207 [DEBUG] switch_cpp.cpp:670 CoreSession::hangup
2016-04-03 12:26:59.838207 [DEBUG] freeswitch_lua.cpp:377 DBH handle 0xb5db9c98 released.
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:48 sofia/gemeinschaft/Berofix_int.ISDN Standard HANGUP, cause: CALL_REJECTED
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:683 (sofia/gemeinschaft/Berofix_int.ISDN) State HANGUP going to sleep
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:450 (sofia/gemeinschaft/Berofix_int.ISDN) State Change CS_HANGUP -> CS_REPORTING
2016-04-03 12:26:59.838207 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/Berofix_int.ISDN) Running State Change CS_REPORTING
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:767 (sofia/gemeinschaft/Berofix_int.ISDN) State REPORTING
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:92 sofia/gemeinschaft/Berofix_int.ISDN Standard REPORTING, cause: CALL_REJECTED
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:767 (sofia/gemeinschaft/Berofix_int.ISDN) State REPORTING going to sleep
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:444 (sofia/gemeinschaft/Berofix_int.ISDN) State Change CS_REPORTING -> CS_DESTROY
2016-04-03 12:26:59.838207 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/Berofix_int.ISDN [BREAK]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_session.c:1559 Session 3 (sofia/gemeinschaft/Berofix_int.ISDN) Locked, Waiting on external entities
2016-04-03 12:26:59.838207 [NOTICE] switch_core_session.c:1577 Session 3 (sofia/gemeinschaft/Berofix_int.ISDN) Ended
2016-04-03 12:26:59.838207 [NOTICE] switch_core_session.c:1581 Close Channel sofia/gemeinschaft/Berofix_int.ISDN [CS_DESTROY]
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:572 (sofia/gemeinschaft/Berofix_int.ISDN) Running State Change CS_DESTROY
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:582 (sofia/gemeinschaft/Berofix_int.ISDN) State DESTROY
2016-04-03 12:26:59.838207 [DEBUG] mod_sofia.c:399 sofia/gemeinschaft/Berofix_int.ISDN SOFIA DESTROY
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:99 sofia/gemeinschaft/Berofix_int.ISDN Standard DESTROY
2016-04-03 12:26:59.838207 [DEBUG] switch_core_state_machine.c:582 (sofia/gemeinschaft/Berofix_int.ISDN) State DESTROY going to sleep
2016-04-03 12:26:59.838207 [DEBUG] switch_cpp.cpp:1051 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local destroy/unlink session from object
EXECUTE sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local set(script=)
2016-04-03 12:26:59.858157 [DEBUG] switch_core_session.c:2802 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local skip receive message [APPLICATION_EXEC] (channel is hungup already)
2016-04-03 12:26:59.858157 [DEBUG] mod_dptools.c:1402 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SET [script]=[UNDEF]
2016-04-03 12:26:59.858157 [DEBUG] switch_core_session.c:2817 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:481 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State EXECUTE going to sleep
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_HANGUP
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:681 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Callstate Change RINGING -> HANGUP
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:683 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State HANGUP
2016-04-03 12:26:59.858157 [DEBUG] mod_sofia.c:500 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Overriding SIP cause 603 with 603 from the other leg
2016-04-03 12:26:59.858157 [DEBUG] mod_sofia.c:506 Channel sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local hanging up, cause: CALL_REJECTED
2016-04-03 12:26:59.858157 [DEBUG] mod_sofia.c:640 Responding to INVITE with: 603
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:48 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard HANGUP, cause: CALL_REJECTED
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:683 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State HANGUP going to sleep
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:450 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_HANGUP -> CS_REPORTING
2016-04-03 12:26:59.858157 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:418 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_REPORTING
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:767 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State REPORTING
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:92 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard REPORTING, cause: CALL_REJECTED
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:767 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State REPORTING going to sleep
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:444 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State Change CS_REPORTING -> CS_DESTROY
2016-04-03 12:26:59.858157 [DEBUG] switch_core_session.c:1351 Send signal sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [BREAK]
2016-04-03 12:26:59.858157 [DEBUG] switch_core_session.c:1559 Session 2 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Locked, Waiting on external entities
2016-04-03 12:26:59.858157 [NOTICE] switch_core_session.c:1577 Session 2 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Ended
2016-04-03 12:26:59.858157 [NOTICE] switch_core_session.c:1581 Close Channel sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local [CS_DESTROY]
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:572 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) Running State Change CS_DESTROY
2016-04-03 12:26:59.858157 [DEBUG] switch_core_state_machine.c:582 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State DESTROY
2016-04-03 12:26:59.858157 [DEBUG] mod_sofia.c:399 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local SOFIA DESTROY
2016-04-03 12:26:59.898215 [DEBUG] switch_core_state_machine.c:99 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard DESTROY
2016-04-03 12:26:59.898215 [DEBUG] switch_core_state_machine.c:582 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State DESTROY going to sleep
2016-04-03 12:26:59.958218 [DEBUG] switch_core_state_machine.c:99 sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local Standard DESTROY
2016-04-03 12:26:59.958218 [DEBUG] switch_core_state_machine.c:582 (sofia/gemeinschaft/e2d98ac0f63c944b7138@sip.gs5.local) State DESTROY going to sleep
2016-04-03 12:27:00.278192 [INFO] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] PRESENCE_INBOUND - protocol: any, account: e2d98ac0f63c944b7138, state: alerting
2016-04-03 12:27:00.278192 [DEBUG] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] PRESENCE - retrieve account data - account: e2d98ac0f63c944b7138
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: +491234987655@sip.gs5.local, state: confirmed, direction: inbound, uid: cee71632-7570-4689-9b79-2445ab6e35ac
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: 5...@sip.gs5.local, state: confirmed, direction: inbound, uid: cee71632-7570-4689-9b79-2445ab6e35ac
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: 987...@sip.gs5.local, state: confirmed, direction: inbound, uid: cee71632-7570-4689-9b79-2445ab6e35ac
2016-04-03 12:27:00.298158 [INFO] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] PRESENCE_INBOUND - protocol: any, account: e2d98ac0f63c944b7138, state: idle
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: +491234987655@sip.gs5.local, state: terminated, direction: inbound, uid: cee71632-7570-4689-9b79-2445ab6e35ac
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: 5...@sip.gs5.local, state: terminated, direction: inbound, uid: cee71632-7570-4689-9b79-2445ab6e35ac
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: 987...@sip.gs5.local, state: terminated, direction: inbound, uid: cee71632-7570-4689-9b79-2445ab6e35ac
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] CDR_SAVE - event: CHANNEL_DESTROY, direction: inbound, save_cdr: false
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] CALL_HISTORY_SAVE - event: CHANNEL_DESTROY, direction: inbound, save_cdr: false
2016-04-03 12:27:00.298158 [INFO] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] PRESENCE_INBOUND - protocol: any, account: e2d98ac0f63c944b7138, state: alerting
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: +491234987655@sip.gs5.local, state: confirmed, direction: inbound, uid: f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: 5...@sip.gs5.local, state: confirmed, direction: inbound, uid: f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: 987...@sip.gs5.local, state: confirmed, direction: inbound, uid: f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b
2016-04-03 12:27:00.298158 [INFO] switch_cpp.cpp:1288 #E# [7328f453-2adf-4620-96cb-b1206e4f7f32] PRESENCE_OUTBOUND - protocol: any, account: Berofix_int.ISDN, state: progressing
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [7328f453-2adf-4620-96cb-b1206e4f7f32] PRESENCE - retrieve account data - account: Berofix_int.ISDN
2016-04-03 12:27:00.298158 [INFO] switch_cpp.cpp:1288 #E# [7328f453-2adf-4620-96cb-b1206e4f7f32] PRESENCE_OUTBOUND - protocol: any, account: Berofix_int.ISDN, state: idle
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [7328f453-2adf-4620-96cb-b1206e4f7f32] PRESENCE - retrieve account data - account: Berofix_int.ISDN
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [7328f453-2adf-4620-96cb-b1206e4f7f32] CDR_SAVE - event: CHANNEL_DESTROY, direction: outbound, save_cdr: false
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [7328f453-2adf-4620-96cb-b1206e4f7f32] CALL_HISTORY_SAVE - event: CHANNEL_DESTROY, direction: outbound, save_cdr: false
2016-04-03 12:27:00.298158 [INFO] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] PRESENCE_INBOUND - protocol: any, account: e2d98ac0f63c944b7138, state: idle
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: +491234987655@sip.gs5.local, state: terminated, direction: inbound, uid: f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: 5...@sip.gs5.local, state: terminated, direction: inbound, uid: f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# PRESENCE - account: 987...@sip.gs5.local, state: terminated, direction: inbound, uid: f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b
2016-04-03 12:27:00.298158 [INFO] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] CDR_SAVE - account: "SipAccount"="1", caller: "+491234987655" "491234987655", callee: "987654" NULL, cause: "CALL_REJECTED"
2016-04-03 12:27:00.298158 [INFO] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] CALL_HISTORY_SAVE dialed - account: SipAccount=1, caller: "+491234987655" "491234987655", callee: "987654" NULL, result: "CALL_REJECTED"
2016-04-03 12:27:00.298158 [INFO] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] CALL_HISTORY_SAVE - ignore callee entry - account: unknown=0, local: true
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] PRESENCE_INBOUND no state - protocol: sip, account: +491234987655
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] PRESENCE_INBOUND no state - protocol: sip, account: 55
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] PRESENCE_INBOUND no state - protocol: sip, account: 987655
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] PRESENCE_INBOUND no state - protocol: sip, account: +491234987655
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] PRESENCE_INBOUND no state - protocol: sip, account: 55
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [cee71632-7570-4689-9b79-2445ab6e35ac] PRESENCE_INBOUND no state - protocol: sip, account: 987655
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] PRESENCE_INBOUND no state - protocol: sip, account: +491234987655
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] PRESENCE_INBOUND no state - protocol: sip, account: 55
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] PRESENCE_INBOUND no state - protocol: sip, account: 987655
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] PRESENCE_INBOUND no state - protocol: sip, account: +491234987655
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] PRESENCE_INBOUND no state - protocol: sip, account: 55
2016-04-03 12:27:00.298158 [DEBUG] switch_cpp.cpp:1288 #E# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] PRESENCE_INBOUND no state - protocol: sip, account: 987655
2016-04-03 12:27:00.398186 [DEBUG] switch_cpp.cpp:1288 #R# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] HTTP_REQUEST - url: http://127.0.0.1/trigger/sip_account_update/1?timestamp=1459679219798168&status=confirmed, auth: false, result: 200
2016-04-03 12:27:00.458155 [DEBUG] switch_cpp.cpp:1288 #R# [cee71632-7570-4689-9b79-2445ab6e35ac] HTTP_REQUEST - url: http://127.0.0.1/trigger/sip_account_update/1?timestamp=1459679219778188&status=confirmed, auth: false, result: 200
2016-04-03 12:27:00.498146 [DEBUG] switch_cpp.cpp:1288 #R# [f5fd63ff-d063-4bcc-a9bb-feeb8528ab4b] HTTP_REQUEST - url: http://127.0.0.1/trigger/sip_account_update/1?timestamp=1459679219858157&status=terminated, auth: false, result: 200
2016-04-03 12:27:01.118173 [DEBUG] switch_cpp.cpp:1288 #R# [cee71632-7570-4689-9b79-2445ab6e35ac] HTTP_REQUEST - url: http://127.0.0.1/trigger/sip_account_update/1?timestamp=1459679219798168&status=terminated, auth: false, result: 200



Reply all
Reply to author
Forward
0 new messages