As the Fritzbox supports G.729 by default, a call from my Snom 821 to it enables HD audio by default.
Unfortunately the sound is miserable as on the FritzFon I hear the caller is Mickey Mouse. On the Snom side though I hear nothing.
Calling from the FritzFon to the Snom does not enable HD audio and therefore everything is fine.
2012-12-19 14:19:28.016532 [NOTICE] switch_channel.c:968 New Channel sofia/gemeinschaft/[email protected] [aef27684-7df5-4dcc-b7e1-9041b37fcd31]
2012-12-19 14:19:28.016532 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.016532 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.016532 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_NEW
2012-12-19 14:19:28.016532 [DEBUG] switch_core_state_machine.c:433 (sofia/gemeinschaft/[email protected]) State NEW
2012-12-19 14:19:28.045595 [DEBUG] sofia.c:5599 Channel sofia/gemeinschaft/[email protected] entering state [received][100]
2012-12-19 14:19:28.045595 [DEBUG] sofia.c:5610 Remote SDP:
v=0
o=root 225669309 225669309 IN IP4 192.168.6.173
s=call
c=IN IP4 192.168.6.173
t=0 0
m=audio 49902 RTP/AVP 0 8 3 9 2 18 4 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:9 G722/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2012-12-19 14:19:28.045595 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMA:8:8000:20:64000]
2012-12-19 14:19:28.045595 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2012-12-19 14:19:28.045595 [DEBUG] sofia_glue.c:3093 Set Codec sofia/gemeinschaft/[email protected] PCMA/8000 20 ms 160 samples 64000 bits
2012-12-19 14:19:28.045595 [DEBUG] switch_core_codec.c:111 sofia/gemeinschaft/[email protected] Original read codec set to PCMA:8
2012-12-19 14:19:28.045595 [DEBUG] sofia_glue.c:5266 Set 2833 dtmf send/recv payload to 101
2012-12-19 14:19:28.045595 [DEBUG] sofia.c:5827 (sofia/gemeinschaft/[email protected]) State Change CS_NEW -> CS_INIT
2012-12-19 14:19:28.045595 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_INIT
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:454 (sofia/gemeinschaft/[email protected]) State INIT
2012-12-19 14:19:28.045595 [DEBUG] mod_sofia.c:86 sofia/gemeinschaft/[email protected] SOFIA INIT
2012-12-19 14:19:28.045595 [DEBUG] mod_sofia.c:126 (sofia/gemeinschaft/[email protected]) State Change CS_INIT -> CS_ROUTING
2012-12-19 14:19:28.045595 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:454 (sofia/gemeinschaft/[email protected]) State INIT going to sleep
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_ROUTING
2012-12-19 14:19:28.045595 [DEBUG] switch_channel.c:2003 (sofia/gemeinschaft/[email protected]) Callstate Change DOWN -> RINGING
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:470 (sofia/gemeinschaft/[email protected]) State ROUTING
2012-12-19 14:19:28.045595 [DEBUG] mod_sofia.c:149 sofia/gemeinschaft/[email protected] SOFIA ROUTING
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:117 sofia/gemeinschaft/[email protected] Standard ROUTING
2012-12-19 14:19:28.045595 [INFO] mod_dialplan_xml.c:557 Processing Call <e91e1047f66f3fbe8cd4>->21 in context default
Dialplan: sofia/gemeinschaft/[email protected] parsing [default->invoke_default_dialplan] continue=true
Dialplan: sofia/gemeinschaft/[email protected] Absolute Condition [invoke_default_dialplan]
Dialplan: sofia/gemeinschaft/[email protected] Action set(script=${lua(dialplan_default.lua)})
Dialplan: sofia/gemeinschaft/[email protected] parsing [default->transfer_loop] continue=false
Dialplan: sofia/gemeinschaft/[email protected] Regex (FAIL) [transfer_loop] endpoint_disposition() =~ /BLIND_TRANSFER/ break=on-false
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:167 (sofia/gemeinschaft/[email protected]) State Change CS_ROUTING -> CS_EXECUTE
2012-12-19 14:19:28.045595 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:470 (sofia/gemeinschaft/[email protected]) State ROUTING going to sleep
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_EXECUTE
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:477 (sofia/gemeinschaft/[email protected]) State EXECUTE
2012-12-19 14:19:28.045595 [DEBUG] mod_sofia.c:242 sofia/gemeinschaft/[email protected] SOFIA EXECUTE
2012-12-19 14:19:28.045595 [DEBUG] switch_core_state_machine.c:209 sofia/gemeinschaft/[email protected] Standard EXECUTE
2012-12-19 14:19:28.045595 [DEBUG] switch_cpp.cpp:1232 ### [aef27684-7df5-4dcc-b7e1-9041b37fcd31] AUTHENTICATION_REQUIRED - domain: 192.168.6.60
EXECUTE sofia/gemeinschaft/[email protected] respond(407 192.168.6.60)
2012-12-19 14:19:28.045595 [DEBUG] mod_sofia.c:2640 Responding with 407 [192.168.6.60]
2012-12-19 14:19:28.045595 [DEBUG] switch_channel.c:2994 (sofia/gemeinschaft/[email protected]) Callstate Change RINGING -> HANGUP
2012-12-19 14:19:28.045595 [NOTICE] mod_sofia.c:2699 Hangup sofia/gemeinschaft/[email protected] [CS_EXECUTE] [CALL_REJECTED]
2012-12-19 14:19:28.045595 [DEBUG] switch_channel.c:3017 Send signal sofia/gemeinschaft/[email protected] [KILL]
2012-12-19 14:19:28.045595 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.045595 [DEBUG] switch_core_session.c:2678 sofia/gemeinschaft/[email protected] skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-12-19 14:19:28.045595 [DEBUG] switch_cpp.cpp:635 CoreSession::hangup
2012-12-19 14:19:28.045595 [DEBUG] switch_cpp.cpp:1007 sofia/gemeinschaft/[email protected] destroy/unlink session from object
EXECUTE sofia/gemeinschaft/[email protected] set(script=)
2012-12-19 14:19:28.056022 [DEBUG] switch_core_session.c:2663 sofia/gemeinschaft/[email protected] skip receive message [APPLICATION_EXEC] (channel is hungup already)
2012-12-19 14:19:28.056022 [DEBUG] mod_dptools.c:1344 sofia/gemeinschaft/[email protected] SET [script]=[UNDEF]
2012-12-19 14:19:28.056022 [DEBUG] switch_core_session.c:2678 sofia/gemeinschaft/[email protected] skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-12-19 14:19:28.056022 [DEBUG] switch_core_state_machine.c:477 (sofia/gemeinschaft/[email protected]) State EXECUTE going to sleep
2012-12-19 14:19:28.056022 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_HANGUP
2012-12-19 14:19:28.056022 [DEBUG] switch_core_state_machine.c:667 (sofia/gemeinschaft/[email protected]) State HANGUP
2012-12-19 14:19:28.056022 [DEBUG] mod_sofia.c:503 Channel sofia/gemeinschaft/[email protected] hanging up, cause: CALL_REJECTED
2012-12-19 14:19:28.056022 [INFO] switch_cpp.cpp:1232 #E# [aef27684-7df5-4dcc-b7e1-9041b37fcd31] PRESENCE_INBOUND: account: e91e1047f66f3fbe8cd4, state: alerting
2012-12-19 14:19:28.056022 [DEBUG] switch_cpp.cpp:1232 #E# PRESENCE - account: [email protected], state: confirmed, direction: inbound, uid: aef27684-7df5-4dcc-b7e1-9041b37fcd31
2012-12-19 14:19:28.056022 [DEBUG] mod_sofia.c:595 Challenging call
2012-12-19 14:19:28.065888 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 407
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:48 sofia/gemeinschaft/[email protected] Standard HANGUP, cause: CALL_REJECTED
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:667 (sofia/gemeinschaft/[email protected]) State HANGUP going to sleep
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:446 (sofia/gemeinschaft/[email protected]) State Change CS_HANGUP -> CS_REPORTING
2012-12-19 14:19:28.065888 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_REPORTING
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:749 (sofia/gemeinschaft/[email protected]) State REPORTING
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:92 sofia/gemeinschaft/[email protected] Standard REPORTING, cause: CALL_REJECTED
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:749 (sofia/gemeinschaft/[email protected]) State REPORTING going to sleep
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:440 (sofia/gemeinschaft/[email protected]) State Change CS_REPORTING -> CS_DESTROY
2012-12-19 14:19:28.065888 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.065888 [DEBUG] switch_core_session.c:1488 Session 27 (sofia/gemeinschaft/[email protected]) Locked, Waiting on external entities
2012-12-19 14:19:28.065888 [NOTICE] switch_core_session.c:1506 Session 27 (sofia/gemeinschaft/[email protected]) Ended
2012-12-19 14:19:28.065888 [NOTICE] switch_core_session.c:1510 Close Channel sofia/gemeinschaft/[email protected] [CS_DESTROY]
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:556 (sofia/gemeinschaft/[email protected]) Callstate Change HANGUP -> DOWN
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:559 (sofia/gemeinschaft/[email protected]) Running State Change CS_DESTROY
2012-12-19 14:19:28.065888 [DEBUG] switch_core_state_machine.c:569 (sofia/gemeinschaft/[email protected]) State DESTROY
2012-12-19 14:19:28.065888 [DEBUG] mod_sofia.c:396 sofia/gemeinschaft/[email protected] SOFIA DESTROY
2012-12-19 14:19:28.065888 [INFO] switch_cpp.cpp:1232 #E# [aef27684-7df5-4dcc-b7e1-9041b37fcd31] PRESENCE_INBOUND: account: e91e1047f66f3fbe8cd4, state: idle
2012-12-19 14:19:28.065888 [DEBUG] switch_cpp.cpp:1232 #E# PRESENCE - account: [email protected], state: terminated, direction: inbound, uid: aef27684-7df5-4dcc-b7e1-9041b37fcd31
2012-12-19 14:19:28.065888 [DEBUG] switch_cpp.cpp:1232 #E# [aef27684-7df5-4dcc-b7e1-9041b37fcd31] CDR_SAVE - event: CHANNEL_DESTROY, direction: inbound, save_cdr: false
2012-12-19 14:19:28.065888 [DEBUG] switch_cpp.cpp:1232 #E# [aef27684-7df5-4dcc-b7e1-9041b37fcd31] CALL_HISTORY_SAVE - event: CHANNEL_DESTROY, direction: inbound, save_cdr: false
2012-12-19 14:19:28.105693 [NOTICE] switch_channel.c:968 New Channel sofia/gemeinschaft/[email protected] [7e316c85-68a7-4b41-a00b-9887a15ebb9b]
2012-12-19 14:19:28.105693 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.105693 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.105693 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_NEW
2012-12-19 14:19:28.105693 [DEBUG] switch_core_state_machine.c:433 (sofia/gemeinschaft/[email protected]) State NEW
2012-12-19 14:19:28.135703 [DEBUG] switch_cpp.cpp:1232 #C# [192.168.6.60] DIRECTORY_SIP_ACCOUNT - auth_name: e91e1047f66f3fbe8cd4, caller_name: Julian Pawlowski, domain: 192.168.6.60
2012-12-19 14:19:28.145670 [DEBUG] sofia.c:5599 Channel sofia/gemeinschaft/[email protected] entering state [received][100]
2012-12-19 14:19:28.145670 [DEBUG] sofia.c:5610 Remote SDP:
v=0
o=root 225669309 225669309 IN IP4 192.168.6.173
s=call
c=IN IP4 192.168.6.173
t=0 0
m=audio 49902 RTP/AVP 0 8 3 9 2 18 4 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:9 G722/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2012-12-19 14:19:28.145670 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMA:8:8000:20:64000]
2012-12-19 14:19:28.145670 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2012-12-19 14:19:28.145670 [DEBUG] sofia_glue.c:3093 Set Codec sofia/gemeinschaft/[email protected] PCMA/8000 20 ms 160 samples 64000 bits
2012-12-19 14:19:28.145670 [DEBUG] switch_core_codec.c:111 sofia/gemeinschaft/[email protected] Original read codec set to PCMA:8
2012-12-19 14:19:28.145670 [DEBUG] sofia_glue.c:5266 Set 2833 dtmf send/recv payload to 101
2012-12-19 14:19:28.145670 [DEBUG] sofia.c:5827 (sofia/gemeinschaft/[email protected]) State Change CS_NEW -> CS_INIT
2012-12-19 14:19:28.145670 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_INIT
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:454 (sofia/gemeinschaft/[email protected]) State INIT
2012-12-19 14:19:28.145670 [DEBUG] mod_sofia.c:86 sofia/gemeinschaft/[email protected] SOFIA INIT
2012-12-19 14:19:28.145670 [DEBUG] mod_sofia.c:126 (sofia/gemeinschaft/[email protected]) State Change CS_INIT -> CS_ROUTING
2012-12-19 14:19:28.145670 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:454 (sofia/gemeinschaft/[email protected]) State INIT going to sleep
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_ROUTING
2012-12-19 14:19:28.145670 [DEBUG] switch_channel.c:2003 (sofia/gemeinschaft/[email protected]) Callstate Change DOWN -> RINGING
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:470 (sofia/gemeinschaft/[email protected]) State ROUTING
2012-12-19 14:19:28.145670 [DEBUG] mod_sofia.c:149 sofia/gemeinschaft/[email protected] SOFIA ROUTING
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:117 sofia/gemeinschaft/[email protected] Standard ROUTING
2012-12-19 14:19:28.145670 [INFO] mod_dialplan_xml.c:557 Processing Call <e91e1047f66f3fbe8cd4>->21 in context default
Dialplan: sofia/gemeinschaft/[email protected] parsing [default->invoke_default_dialplan] continue=true
Dialplan: sofia/gemeinschaft/[email protected] Absolute Condition [invoke_default_dialplan]
Dialplan: sofia/gemeinschaft/[email protected] Action set(script=${lua(dialplan_default.lua)})
Dialplan: sofia/gemeinschaft/[email protected] parsing [default->transfer_loop] continue=false
Dialplan: sofia/gemeinschaft/[email protected] Regex (FAIL) [transfer_loop] endpoint_disposition() =~ /BLIND_TRANSFER/ break=on-false
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:167 (sofia/gemeinschaft/[email protected]) State Change CS_ROUTING -> CS_EXECUTE
2012-12-19 14:19:28.145670 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:470 (sofia/gemeinschaft/[email protected]) State ROUTING going to sleep
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_EXECUTE
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:477 (sofia/gemeinschaft/[email protected]) State EXECUTE
2012-12-19 14:19:28.145670 [DEBUG] mod_sofia.c:242 sofia/gemeinschaft/[email protected] SOFIA EXECUTE
2012-12-19 14:19:28.145670 [DEBUG] switch_core_state_machine.c:209 sofia/gemeinschaft/[email protected] Standard EXECUTE
2012-12-19 14:19:28.145670 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] AUTH_FIRST_STAGE - sipaccount autheticated by name/password: SipAccount=/b0976a20-2bac-0130-2b4f-000c29ac4bc3
2012-12-19 14:19:28.145670 [INFO] switch_cpp.cpp:1232 #E# [7e316c85-68a7-4b41-a00b-9887a15ebb9b] PRESENCE_INBOUND: account: e91e1047f66f3fbe8cd4, state: alerting
2012-12-19 14:19:28.145670 [DEBUG] switch_cpp.cpp:1232 #E# PRESENCE - account: [email protected], state: confirmed, direction: inbound, uid: 7e316c85-68a7-4b41-a00b-9887a15ebb9b
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] CALLER_DATA - auth account: sipaccount=2/b0976a20-2bac-0130-2b4f-000c29ac4bc3
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] CALLER_DATA - auth owner: user=1/6e973bf0-2b9f-0130-2b46-000c29ac4bc3
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] CALLER_DATA - caller account: sipaccount=2/b0976a20-2bac-0130-2b4f-000c29ac4bc3, phone_numbers: 1
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] CALLER_DATA - caller owner: user=1/6e973bf0-2b9f-0130-2b46-000c29ac4bc3
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] DESTINATION_NEW - sipaccount=5/@1, number: 21
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] DIALPLAN start - caller_id: 20 "Julian Pawlowski", number: 21
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] LOOP 1 - destination: sipaccount=5/@1, number: 21
EXECUTE sofia/gemeinschaft/[email protected] export(alert_info=http://amooma.de;info=Ringer1;x-line-id=0)
2012-12-19 14:19:28.156668 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [alert_info]=[http://amooma.de;info=Ringer1;x-line-id=0]
EXECUTE sofia/gemeinschaft/[email protected] export(effective_callee_id_number=21)
2012-12-19 14:19:28.156668 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [effective_callee_id_number]=[21]
EXECUTE sofia/gemeinschaft/[email protected] export(effective_callee_id_name=Julian Pawlowski)
2012-12-19 14:19:28.156668 [DEBUG] switch_channel.c:1135 EXPORT (export_vars) [effective_callee_id_name]=[Julian Pawlowski]
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] FORK 1/1 - sipaccount=5/@1, number: 21
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] CALL_WAITING - status: disabled, sip_account state:
2012-12-19 14:19:28.156668 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] FORK DIAL - destinations: 1, timeout: 120
2012-12-19 14:19:28.156668 [DEBUG] switch_channel.c:1089 sofia/gemeinschaft/[email protected] EXPORTING[export_vars] [alert_info]=[http://amooma.de;info=Ringer1;x-line-id=0] to event
2012-12-19 14:19:28.156668 [DEBUG] switch_channel.c:1089 sofia/gemeinschaft/[email protected] EXPORTING[export_vars] [effective_callee_id_number]=[21] to event
2012-12-19 14:19:28.156668 [DEBUG] switch_channel.c:1089 sofia/gemeinschaft/[email protected] EXPORTING[export_vars] [effective_callee_id_name]=[Julian Pawlowski] to event
2012-12-19 14:19:28.156668 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables
2012-12-19 14:19:28.156668 [DEBUG] switch_event.c:1608 Parsing variable [local_var_clobber]=[true]
2012-12-19 14:19:28.156668 [DEBUG] switch_ivr_originate.c:2442 Parsing session specific variables
2012-12-19 14:19:28.156668 [DEBUG] switch_event.c:1608 Parsing variable [gs_fork_index]=[1]
2012-12-19 14:19:28.156668 [DEBUG] switch_event.c:1608 Parsing variable [effective_callee_id_name]=[Julian Pawlowski]
2012-12-19 14:19:28.156668 [DEBUG] switch_event.c:1608 Parsing variable [effective_callee_id_number]=[21]
2012-12-19 14:19:28.167391 [DEBUG] switch_cpp.cpp:1232 #C# [192.168.6.60] DIRECTORY_SIP_ACCOUNT - auth_name: 7c9f585998cc343345b6, caller_name: Julian Pawlowski, domain: 192.168.6.60
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:99 sofia/gemeinschaft/[email protected] Standard DESTROY
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:569 (sofia/gemeinschaft/[email protected]) State DESTROY going to sleep
2012-12-19 14:19:28.167391 [DEBUG] switch_channel.c:1089 sofia/gemeinschaft/[email protected] EXPORTING[export_vars] [alert_info]=[http://amooma.de;info=Ringer1;x-line-id=0] to event
2012-12-19 14:19:28.167391 [DEBUG] switch_channel.c:1089 sofia/gemeinschaft/[email protected] EXPORTING[export_vars] [effective_callee_id_number]=[21] to event
2012-12-19 14:19:28.167391 [DEBUG] switch_channel.c:1089 sofia/gemeinschaft/[email protected] EXPORTING[export_vars] [effective_callee_id_name]=[Julian Pawlowski] to event
2012-12-19 14:19:28.167391 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables
2012-12-19 14:19:28.167391 [NOTICE] switch_channel.c:968 New Channel sofia/gemeinschaft/sip:[email protected] [862b3e43-a824-44af-ab4f-9f76fd35c4c6]
2012-12-19 14:19:28.167391 [DEBUG] mod_sofia.c:4970 (sofia/gemeinschaft/sip:[email protected]) State Change CS_NEW -> CS_INIT
2012-12-19 14:19:28.167391 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/sip:[email protected]) Running State Change CS_INIT
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:454 (sofia/gemeinschaft/sip:[email protected]) State INIT
2012-12-19 14:19:28.167391 [DEBUG] mod_sofia.c:86 sofia/gemeinschaft/sip:[email protected] SOFIA INIT
2012-12-19 14:19:28.167391 [DEBUG] sofia_glue.c:2647 Local SDP:
v=0
o=FreeSWITCH 1355894122 1355894123 IN IP4 192.168.6.60
s=FreeSWITCH
c=IN IP4 192.168.6.60
t=0 0
m=audio 29046 RTP/AVP 8 9 0 3 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2012-12-19 14:19:28.167391 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:28.167391 [DEBUG] mod_sofia.c:126 (sofia/gemeinschaft/sip:[email protected]) State Change CS_INIT -> CS_ROUTING
2012-12-19 14:19:28.167391 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:454 (sofia/gemeinschaft/sip:[email protected]) State INIT going to sleep
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/sip:[email protected]) Running State Change CS_ROUTING
2012-12-19 14:19:28.167391 [DEBUG] switch_channel.c:2003 (sofia/gemeinschaft/sip:[email protected]) Callstate Change DOWN -> RINGING
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:470 (sofia/gemeinschaft/sip:[email protected]) State ROUTING
2012-12-19 14:19:28.167391 [DEBUG] mod_sofia.c:149 sofia/gemeinschaft/sip:[email protected] SOFIA ROUTING
2012-12-19 14:19:28.167391 [DEBUG] switch_ivr_originate.c:67 (sofia/gemeinschaft/sip:[email protected]) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2012-12-19 14:19:28.167391 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:470 (sofia/gemeinschaft/sip:[email protected]) State ROUTING going to sleep
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/sip:[email protected]) Running State Change CS_CONSUME_MEDIA
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:489 (sofia/gemeinschaft/sip:[email protected]) State CONSUME_MEDIA
2012-12-19 14:19:28.167391 [DEBUG] switch_core_state_machine.c:489 (sofia/gemeinschaft/sip:[email protected]) State CONSUME_MEDIA going to sleep
2012-12-19 14:19:28.167391 [DEBUG] sofia.c:5599 Channel sofia/gemeinschaft/sip:[email protected] entering state [calling][0]
2012-12-19 14:19:28.167391 [INFO] switch_cpp.cpp:1232 #E# [862b3e43-a824-44af-ab4f-9f76fd35c4c6] PRESENCE_OUTBOUND: account: 7c9f585998cc343345b6, state: progressing, caller: 20
2012-12-19 14:19:28.167391 [DEBUG] switch_cpp.cpp:1232 #E# PRESENCE - account: [email protected], state: early, direction: outbound, uid: 862b3e43-a824-44af-ab4f-9f76fd35c4c6
2012-12-19 14:19:31.346489 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:31.346489 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:31.346489 [DEBUG] sofia.c:5599 Channel sofia/gemeinschaft/sip:[email protected] entering state [proceeding][180]
2012-12-19 14:19:31.346489 [NOTICE] sofia.c:5691 Ring-Ready sofia/gemeinschaft/sip:[email protected]!
2012-12-19 14:19:31.355636 [INFO] switch_ivr_originate.c:1185 Sending early media
2012-12-19 14:19:31.355636 [DEBUG] sofia_glue.c:3351 AUDIO RTP [sofia/gemeinschaft/[email protected]] 192.168.6.60 port 17400 -> 192.168.6.173 port 49902 codec: 8 ms: 20
2012-12-19 14:19:31.355636 [DEBUG] switch_rtp.c:1940 Starting timer [soft] 160 bytes per 20ms
2012-12-19 14:19:31.355636 [DEBUG] sofia_glue.c:3615 Set 2833 dtmf send payload to 101
2012-12-19 14:19:31.355636 [DEBUG] sofia_glue.c:3621 Set 2833 dtmf receive payload to 101
2012-12-19 14:19:31.355636 [DEBUG] sofia_glue.c:3648 sofia/gemeinschaft/[email protected] Set rtp dtmf delay to 40
2012-12-19 14:19:31.355636 [DEBUG] mod_sofia.c:2819 Ring SDP:
v=0
o=FreeSWITCH 1355905771 1355905772 IN IP4 192.168.6.60
s=FreeSWITCH
c=IN IP4 192.168.6.60
t=0 0
m=audio 17400 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
2012-12-19 14:19:31.355636 [NOTICE] mod_sofia.c:2822 Pre-Answer sofia/gemeinschaft/[email protected]!
2012-12-19 14:19:31.355636 [DEBUG] switch_channel.c:3136 (sofia/gemeinschaft/[email protected]) Callstate Change RINGING -> EARLY
2012-12-19 14:19:31.355636 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:31.355636 [DEBUG] sofia.c:5599 Channel sofia/gemeinschaft/[email protected] entering state [early][183]
2012-12-19 14:19:31.355636 [DEBUG] switch_core_session.c:830 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:31.355636 [DEBUG] switch_ivr_originate.c:1242 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2012-12-19 14:19:31.355636 [DEBUG] switch_core_codec.c:219 sofia/gemeinschaft/[email protected] Push codec L16:70
2012-12-19 14:19:31.355636 [DEBUG] switch_ivr_originate.c:1305 Play Ringback Tone [%(2000,4000,440.0,480.0)]
2012-12-19 14:19:31.426610 [DEBUG] switch_rtp.c:3659 Correct ip/port confirmed.
2012-12-19 14:19:32.865865 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:32.865865 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:32.875711 [DEBUG] sofia.c:5599 Channel sofia/gemeinschaft/sip:[email protected] entering state [completing][200]
2012-12-19 14:19:32.875711 [DEBUG] sofia.c:5610 Remote SDP:
v=0
o=user 8695088 8695088 IN IP4 192.168.6.30
s=FreeSWITCH
c=IN IP4 192.168.6.30
t=0 0
m=audio 7078 RTP/AVP 9 8 0 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:7079
a=ptime:30
2012-12-19 14:19:32.875711 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:32.875711 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:32.875711 [DEBUG] sofia.c:5599 Channel sofia/gemeinschaft/sip:[email protected] entering state [ready][200]
2012-12-19 14:19:32.875711 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [G722:9:8000:30:64000]/[PCMA:8:8000:20:64000]
2012-12-19 14:19:32.875711 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMA:8:8000:30:64000]/[PCMA:8:8000:20:64000]
2012-12-19 14:19:32.875711 [DEBUG] sofia_glue.c:5194 Substituting codec PCMA@30i@8000h
2012-12-19 14:19:32.875711 [DEBUG] sofia_glue.c:3093 Set Codec sofia/gemeinschaft/sip:[email protected] PCMA/8000 30 ms 240 samples 64000 bits
2012-12-19 14:19:32.875711 [DEBUG] switch_core_codec.c:111 sofia/gemeinschaft/sip:[email protected] Original read codec set to PCMA:8
2012-12-19 14:19:32.875711 [DEBUG] sofia_glue.c:5259 Set 2833 dtmf send payload to 101
2012-12-19 14:19:32.875711 [DEBUG] sofia_glue.c:3351 AUDIO RTP [sofia/gemeinschaft/sip:[email protected]] 192.168.6.60 port 29046 -> 192.168.6.30 port 7078 codec: 8 ms: 30
2012-12-19 14:19:32.875711 [DEBUG] switch_rtp.c:1940 Starting timer [soft] 240 bytes per 30ms
2012-12-19 14:19:32.886904 [DEBUG] sofia_glue.c:3615 Set 2833 dtmf send payload to 101
2012-12-19 14:19:32.886904 [DEBUG] sofia_glue.c:3621 Set 2833 dtmf receive payload to 101
2012-12-19 14:19:32.886904 [DEBUG] sofia_glue.c:3648 sofia/gemeinschaft/sip:[email protected] Set rtp dtmf delay to 40
2012-12-19 14:19:32.886904 [DEBUG] switch_channel.c:3395 (sofia/gemeinschaft/sip:[email protected]) Callstate Change RINGING -> ACTIVE
2012-12-19 14:19:32.886904 [DEBUG] switch_channel.c:3407 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:32.886904 [NOTICE] sofia.c:6328 Channel [sofia/gemeinschaft/sip:[email protected]] has been answered
2012-12-19 14:19:32.886904 [INFO] switch_cpp.cpp:1232 #E# [862b3e43-a824-44af-ab4f-9f76fd35c4c6] PRESENCE_OUTBOUND: account: 7c9f585998cc343345b6, state: active, caller: 20
2012-12-19 14:19:32.886904 [DEBUG] switch_cpp.cpp:1232 #E# [862b3e43-a824-44af-ab4f-9f76fd35c4c6] PRESENCE - retrieve account data - account: 7c9f585998cc343345b6
2012-12-19 14:19:32.886904 [DEBUG] switch_core_codec.c:244 sofia/gemeinschaft/[email protected] Restore previous codec PCMA:8.
2012-12-19 14:19:32.886904 [DEBUG] switch_cpp.cpp:1232 #E# PRESENCE - account: [email protected], state: confirmed, direction: outbound, uid: 862b3e43-a824-44af-ab4f-9f76fd35c4c6
2012-12-19 14:19:32.886904 [DEBUG] mod_sofia.c:856 Local SDP sofia/gemeinschaft/[email protected]:
v=0
o=FreeSWITCH 1355905771 1355905773 IN IP4 192.168.6.60
s=FreeSWITCH
c=IN IP4 192.168.6.60
t=0 0
m=audio 17400 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
2012-12-19 14:19:32.886904 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:32.886904 [DEBUG] sofia.c:5599 Channel sofia/gemeinschaft/[email protected] entering state [completed][200]
2012-12-19 14:19:32.886904 [DEBUG] switch_core_session.c:830 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:32.886904 [DEBUG] switch_channel.c:3395 (sofia/gemeinschaft/[email protected]) Callstate Change EARLY -> ACTIVE
2012-12-19 14:19:32.886904 [NOTICE] switch_ivr_originate.c:3378 Channel [sofia/gemeinschaft/[email protected]] has been answered
2012-12-19 14:19:32.886904 [DEBUG] switch_ivr_originate.c:3435 Originate Resulted in Success: [sofia/gemeinschaft/sip:[email protected]]
2012-12-19 14:19:32.886904 [DEBUG] switch_ivr_originate.c:3435 Originate Resulted in Success: [sofia/gemeinschaft/sip:[email protected]]
2012-12-19 14:19:32.886904 [DEBUG] switch_cpp.cpp:541 (sofia/gemeinschaft/sip:[email protected]) State Change CS_CONSUME_MEDIA -> CS_SOFT_EXECUTE
2012-12-19 14:19:32.886904 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:32.886904 [INFO] switch_cpp.cpp:1232 #E# [7e316c85-68a7-4b41-a00b-9887a15ebb9b] PRESENCE_INBOUND: account: e91e1047f66f3fbe8cd4, state: active
2012-12-19 14:19:32.895904 [DEBUG] switch_cpp.cpp:1232 #E# PRESENCE - account: [email protected], state: confirmed, direction: inbound, uid: 7e316c85-68a7-4b41-a00b-9887a15ebb9b
2012-12-19 14:19:32.895904 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/sip:[email protected]) Running State Change CS_SOFT_EXECUTE
2012-12-19 14:19:32.895904 [DEBUG] switch_core_state_machine.c:483 (sofia/gemeinschaft/sip:[email protected]) State SOFT_EXECUTE
2012-12-19 14:19:32.895904 [DEBUG] mod_sofia.c:687 SOFIA SOFT_EXECUTE
2012-12-19 14:19:32.895904 [DEBUG] switch_core_state_machine.c:483 (sofia/gemeinschaft/sip:[email protected]) State SOFT_EXECUTE going to sleep
2012-12-19 14:19:32.895904 [DEBUG] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] FORK SESSION_INIT - dial_time: 4
2012-12-19 14:19:32.895904 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] FORK ANSWER - status: SUCCESS, dial_time: 4
2012-12-19 14:19:32.895904 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] FORK 1 BRIDGE - destination: sipaccount=5/@1, number: 21, dial_time: 4
2012-12-19 14:19:32.895904 [DEBUG] switch_ivr_bridge.c:1268 (sofia/gemeinschaft/sip:[email protected]) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA
2012-12-19 14:19:32.895904 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:32.895904 [DEBUG] mod_sofia.c:1731 sofia/gemeinschaft/sip:[email protected] activate passthru 2833 mode.
2012-12-19 14:19:32.895904 [DEBUG] switch_core_session.c:830 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:32.895904 [DEBUG] mod_sofia.c:1731 sofia/gemeinschaft/[email protected] activate passthru 2833 mode.
2012-12-19 14:19:32.895904 [DEBUG] switch_core_session.c:830 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:32.895904 [DEBUG] switch_ivr_bridge.c:1370 (sofia/gemeinschaft/sip:[email protected]) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2012-12-19 14:19:32.895904 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:32.895904 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/sip:[email protected]) Running State Change CS_EXCHANGE_MEDIA
2012-12-19 14:19:32.895904 [DEBUG] switch_core_state_machine.c:480 (sofia/gemeinschaft/sip:[email protected]) State EXCHANGE_MEDIA
2012-12-19 14:19:32.895904 [DEBUG] mod_sofia.c:681 SOFIA EXCHANGE_MEDIA
2012-12-19 14:19:32.907795 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:32.907795 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:32.907795 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:32.925904 [WARNING] mod_sofia.c:1702 Pass 2833 mode may not work on a transcoded call.
2012-12-19 14:19:32.925904 [DEBUG] switch_core_io.c:1174 Engaging Write Buffer at 480 bytes to accommodate 320->480
2012-12-19 14:19:32.925904 [DEBUG] sofia.c:5599 Channel sofia/gemeinschaft/[email protected] entering state [ready][200]
2012-12-19 14:19:32.925904 [DEBUG] switch_core_session.c:892 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:32.925904 [DEBUG] switch_core_session.c:892 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:33.026297 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:47.406499 [DEBUG] switch_core_session.c:975 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:47.426289 [DEBUG] switch_channel.c:2994 (sofia/gemeinschaft/sip:[email protected]) Callstate Change ACTIVE -> HANGUP
2012-12-19 14:19:47.426289 [NOTICE] sofia.c:711 Hangup sofia/gemeinschaft/sip:[email protected] [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2012-12-19 14:19:47.426289 [DEBUG] switch_channel.c:3017 Send signal sofia/gemeinschaft/sip:[email protected] [KILL]
2012-12-19 14:19:47.426289 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:47.426289 [DEBUG] switch_ivr_bridge.c:597 BRIDGE THREAD DONE [sofia/gemeinschaft/sip:[email protected]]
2012-12-19 14:19:47.426289 [DEBUG] switch_ivr_bridge.c:622 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:47.426289 [DEBUG] switch_core_state_machine.c:480 (sofia/gemeinschaft/sip:[email protected]) State EXCHANGE_MEDIA going to sleep
2012-12-19 14:19:47.426289 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/sip:[email protected]) Running State Change CS_HANGUP
2012-12-19 14:19:47.426289 [DEBUG] switch_core_state_machine.c:667 (sofia/gemeinschaft/sip:[email protected]) State HANGUP
2012-12-19 14:19:47.426289 [DEBUG] mod_sofia.c:503 Channel sofia/gemeinschaft/sip:[email protected] hanging up, cause: NORMAL_CLEARING
2012-12-19 14:19:47.426289 [DEBUG] switch_ivr_bridge.c:510 sofia/gemeinschaft/sip:[email protected] ending bridge by request from write function
2012-12-19 14:19:47.426289 [DEBUG] switch_ivr_bridge.c:597 BRIDGE THREAD DONE [sofia/gemeinschaft/[email protected]]
2012-12-19 14:19:47.426289 [DEBUG] switch_ivr_bridge.c:622 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:47.426289 [DEBUG] switch_ivr_bridge.c:1468 sofia/gemeinschaft/sip:[email protected] skip receive message [UNBRIDGE] (channel is hungup already)
2012-12-19 14:19:47.426289 [DEBUG] mod_sofia.c:1809 sofia/gemeinschaft/[email protected] deactivate passthru 2833 mode.
2012-12-19 14:19:47.426289 [DEBUG] switch_core_session.c:830 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:47.426289 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] FORK EXIT - disposition: SUCCESS, cause: NORMAL_CLEARING, code: 200, phrase: OK, dial_time: 19
2012-12-19 14:19:47.426289 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] DIALPLAN end - caller_id: 20 "Julian Pawlowski", destination: sipaccount=5/@1, number: 21, result: 200 OK
EXECUTE sofia/gemeinschaft/[email protected] respond(200 OK)
2012-12-19 14:19:47.426289 [DEBUG] mod_sofia.c:2640 Responding with 200 [OK]
2012-12-19 14:19:47.426289 [WARNING] mod_sofia.c:2701 Cannot respond.
2012-12-19 14:19:47.426289 [DEBUG] switch_core_session.c:830 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:47.426289 [DEBUG] switch_cpp.cpp:635 CoreSession::hangup
2012-12-19 14:19:47.426289 [DEBUG] switch_channel.c:2994 (sofia/gemeinschaft/[email protected]) Callstate Change ACTIVE -> HANGUP
2012-12-19 14:19:47.426289 [NOTICE] switch_cpp.cpp:637 Hangup sofia/gemeinschaft/[email protected] [CS_EXECUTE] [NORMAL_CLEARING]
2012-12-19 14:19:47.426289 [DEBUG] switch_channel.c:3017 Send signal sofia/gemeinschaft/[email protected] [KILL]
2012-12-19 14:19:47.426289 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:47.426289 [INFO] switch_cpp.cpp:1232 ### [7e316c85-68a7-4b41-a00b-9887a15ebb9b] HANGUP_HOOK: hangup
2012-12-19 14:19:47.426289 [DEBUG] switch_cpp.cpp:1007 sofia/gemeinschaft/sip:[email protected] destroy/unlink session from object
2012-12-19 14:19:47.426289 [DEBUG] switch_cpp.cpp:1007 sofia/gemeinschaft/[email protected] destroy/unlink session from object
EXECUTE sofia/gemeinschaft/[email protected] set(script=)
2012-12-19 14:19:47.426289 [DEBUG] switch_core_session.c:2663 sofia/gemeinschaft/[email protected] skip receive message [APPLICATION_EXEC] (channel is hungup already)
2012-12-19 14:19:47.426289 [DEBUG] mod_dptools.c:1344 sofia/gemeinschaft/[email protected] SET [script]=[UNDEF]
2012-12-19 14:19:47.426289 [DEBUG] switch_core_session.c:2678 sofia/gemeinschaft/[email protected] skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2012-12-19 14:19:47.426289 [DEBUG] switch_core_state_machine.c:477 (sofia/gemeinschaft/[email protected]) State EXECUTE going to sleep
2012-12-19 14:19:47.426289 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_HANGUP
2012-12-19 14:19:47.426289 [DEBUG] switch_core_state_machine.c:667 (sofia/gemeinschaft/[email protected]) State HANGUP
2012-12-19 14:19:47.426289 [DEBUG] mod_sofia.c:497 sofia/gemeinschaft/[email protected] Overriding SIP cause 480 with 200 from the other leg
2012-12-19 14:19:47.426289 [DEBUG] mod_sofia.c:503 Channel sofia/gemeinschaft/[email protected] hanging up, cause: NORMAL_CLEARING
2012-12-19 14:19:47.435587 [DEBUG] mod_sofia.c:552 Sending BYE to sofia/gemeinschaft/[email protected]
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:48 sofia/gemeinschaft/[email protected] Standard HANGUP, cause: NORMAL_CLEARING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:667 (sofia/gemeinschaft/[email protected]) State HANGUP going to sleep
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:446 (sofia/gemeinschaft/[email protected]) State Change CS_HANGUP -> CS_REPORTING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/[email protected]) Running State Change CS_REPORTING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:749 (sofia/gemeinschaft/[email protected]) State REPORTING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:92 sofia/gemeinschaft/[email protected] Standard REPORTING, cause: NORMAL_CLEARING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:749 (sofia/gemeinschaft/[email protected]) State REPORTING going to sleep
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:440 (sofia/gemeinschaft/[email protected]) State Change CS_REPORTING -> CS_DESTROY
2012-12-19 14:19:47.435587 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/[email protected] [BREAK]
2012-12-19 14:19:47.435587 [DEBUG] switch_core_session.c:1488 Session 28 (sofia/gemeinschaft/[email protected]) Locked, Waiting on external entities
2012-12-19 14:19:47.435587 [NOTICE] switch_core_session.c:1506 Session 28 (sofia/gemeinschaft/[email protected]) Ended
2012-12-19 14:19:47.435587 [NOTICE] switch_core_session.c:1510 Close Channel sofia/gemeinschaft/[email protected] [CS_DESTROY]
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:556 (sofia/gemeinschaft/[email protected]) Callstate Change HANGUP -> DOWN
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:559 (sofia/gemeinschaft/[email protected]) Running State Change CS_DESTROY
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:569 (sofia/gemeinschaft/[email protected]) State DESTROY
2012-12-19 14:19:47.435587 [DEBUG] mod_sofia.c:396 sofia/gemeinschaft/[email protected] SOFIA DESTROY
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:99 sofia/gemeinschaft/[email protected] Standard DESTROY
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:569 (sofia/gemeinschaft/[email protected]) State DESTROY going to sleep
2012-12-19 14:19:47.435587 [INFO] switch_cpp.cpp:1232 #E# [7e316c85-68a7-4b41-a00b-9887a15ebb9b] PRESENCE_INBOUND: account: e91e1047f66f3fbe8cd4, state: idle
2012-12-19 14:19:47.435587 [DEBUG] switch_cpp.cpp:1232 #E# PRESENCE - account: [email protected], state: terminated, direction: inbound, uid: 7e316c85-68a7-4b41-a00b-9887a15ebb9b
2012-12-19 14:19:47.435587 [INFO] switch_cpp.cpp:1232 #E# [7e316c85-68a7-4b41-a00b-9887a15ebb9b] CDR_SAVE - account: "SipAccount"="2", caller: "20" "Julian Pawlowski", callee: "21" "Julian Pawlowski", cause: "NORMAL_CLEARING"
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:48 sofia/gemeinschaft/sip:[email protected] Standard HANGUP, cause: NORMAL_CLEARING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:667 (sofia/gemeinschaft/sip:[email protected]) State HANGUP going to sleep
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:446 (sofia/gemeinschaft/sip:[email protected]) State Change CS_HANGUP -> CS_REPORTING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:415 (sofia/gemeinschaft/sip:[email protected]) Running State Change CS_REPORTING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:749 (sofia/gemeinschaft/sip:[email protected]) State REPORTING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:92 sofia/gemeinschaft/sip:[email protected] Standard REPORTING, cause: NORMAL_CLEARING
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:749 (sofia/gemeinschaft/sip:[email protected]) State REPORTING going to sleep
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:440 (sofia/gemeinschaft/sip:[email protected]) State Change CS_REPORTING -> CS_DESTROY
2012-12-19 14:19:47.435587 [DEBUG] switch_core_session.c:1283 Send signal sofia/gemeinschaft/sip:[email protected] [BREAK]
2012-12-19 14:19:47.435587 [DEBUG] switch_core_session.c:1488 Session 29 (sofia/gemeinschaft/sip:[email protected]) Locked, Waiting on external entities
2012-12-19 14:19:47.435587 [NOTICE] switch_core_session.c:1506 Session 29 (sofia/gemeinschaft/sip:[email protected]) Ended
2012-12-19 14:19:47.435587 [NOTICE] switch_core_session.c:1510 Close Channel sofia/gemeinschaft/sip:[email protected] [CS_DESTROY]
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:556 (sofia/gemeinschaft/sip:[email protected]) Callstate Change HANGUP -> DOWN
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:559 (sofia/gemeinschaft/sip:[email protected]) Running State Change CS_DESTROY
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:569 (sofia/gemeinschaft/sip:[email protected]) State DESTROY
2012-12-19 14:19:47.435587 [DEBUG] mod_sofia.c:396 sofia/gemeinschaft/sip:[email protected] SOFIA DESTROY
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:99 sofia/gemeinschaft/sip:[email protected] Standard DESTROY
2012-12-19 14:19:47.435587 [DEBUG] switch_core_state_machine.c:569 (sofia/gemeinschaft/sip:[email protected]) State DESTROY going to sleep
2012-12-19 14:19:47.435587 [INFO] switch_cpp.cpp:1232 #E# [7e316c85-68a7-4b41-a00b-9887a15ebb9b] CALL_HISTORY_SAVE dialed - account: SipAccount=2, caller: "20" "Julian Pawlowski", callee: "21" "Julian Pawlowski", result: "NORMAL_CLEARING"
2012-12-19 14:19:47.435587 [INFO] switch_cpp.cpp:1232 #E# [7e316c85-68a7-4b41-a00b-9887a15ebb9b] CALL_HISTORY_SAVE received - account: sipaccount=5, caller: "20" "Julian Pawlowski", callee: "21" "Julian Pawlowski", result: "NORMAL_CLEARING"
2012-12-19 14:19:47.445770 [INFO] switch_cpp.cpp:1232 #E# [862b3e43-a824-44af-ab4f-9f76fd35c4c6] PRESENCE_OUTBOUND: account: 7c9f585998cc343345b6, state: idle, caller: 20
2012-12-19 14:19:47.445770 [DEBUG] switch_cpp.cpp:1232 #E# PRESENCE - account: [email protected], state: terminated, direction: outbound, uid: 862b3e43-a824-44af-ab4f-9f76fd35c4c6
2012-12-19 14:19:47.445770 [DEBUG] switch_cpp.cpp:1232 #E# [862b3e43-a824-44af-ab4f-9f76fd35c4c6] CDR_SAVE - event: CHANNEL_DESTROY, direction: outbound, save_cdr: false
2012-12-19 14:19:47.445770 [DEBUG] switch_cpp.cpp:1232 #E# [862b3e43-a824-44af-ab4f-9f76fd35c4c6] CALL_HISTORY_SAVE - event: CHANNEL_DESTROY, direction: outbound, save_cdr: false