freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> freeswitch@localhost.localdomain> recv 951 bytes from udp/[192.168.1.104]:5060 at 11:49:56.930364: ------------------------------------------------------------------------ INVITE sip:90964111@192.168.1.102 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport From: ;tag=2563216860 To: Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104 CSeq: 17 INVITE Contact: Content-Type: application/sdp Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE, UPDATE Max-Forwards: 70 Supported: 100rel, replaces User-Agent: SIPPER for PhonerLite Content-Length: 396 v=0 o=- 478760567 0 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.104 t=0 0 m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101 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=sendrecv ------------------------------------------------------------------------ send 351 bytes to udp/[192.168.1.104]:5060 at 11:49:56.930781: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport=5060 From: ;tag=2563216860 To: Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104 CSeq: 17 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Content-Length: 0 ------------------------------------------------------------------------ 2009-12-02 19:49:56.930444 [NOTICE] switch_channel.c:613 New Channel sofia/internal/PhonerLite@192.168.1.102 [85042f75-de46-4fe5-b8d8-53e4ca0d26e1] 2009-12-02 19:49:56.930444 [DEBUG] sofia.c:3359 Channel sofia/internal/PhonerLite@192.168.1.102 entering state [received][100] 2009-12-02 19:49:56.930444 [DEBUG] sofia.c:3366 Remote SDP: v=0 o=- 478760567 0 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.104 t=0 0 m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101 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 2009-12-02 19:49:56.930444 [DEBUG] sofia.c:3490 (sofia/internal/PhonerLite@192.168.1.102) State Change CS_NEW -> CS_INIT 2009-12-02 19:49:56.930444 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite@192.168.1.102) Running State Change CS_INIT 2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/PhonerLite@192.168.1.102) State INIT 2009-12-02 19:49:56.931815 [DEBUG] mod_sofia.c:83 sofia/internal/PhonerLite@192.168.1.102 SOFIA INIT 2009-12-02 19:49:56.931815 [DEBUG] mod_sofia.c:111 (sofia/internal/PhonerLite@192.168.1.102) State Change CS_INIT -> CS_ROUTING 2009-12-02 19:49:56.931815 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/PhonerLite@192.168.1.102) State INIT going to sleep 2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite@192.168.1.102) Running State Change CS_ROUTING 2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/PhonerLite@192.168.1.102) State ROUTING 2009-12-02 19:49:56.931815 [DEBUG] mod_sofia.c:130 sofia/internal/PhonerLite@192.168.1.102 SOFIA ROUTING 2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:78 sofia/internal/PhonerLite@192.168.1.102 Standard ROUTING 2009-12-02 19:49:56.946388 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/PhonerLite@192.168.1.102) State Change CS_ROUTING -> CS_EXECUTE 2009-12-02 19:49:56.946388 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:49:56.946388 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/PhonerLite@192.168.1.102) State ROUTING going to sleep 2009-12-02 19:49:56.946388 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite@192.168.1.102) Running State Change CS_EXECUTE 2009-12-02 19:49:56.947392 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/PhonerLite@192.168.1.102) State EXECUTE 2009-12-02 19:49:56.947392 [DEBUG] mod_sofia.c:173 sofia/internal/PhonerLite@192.168.1.102 SOFIA EXECUTE 2009-12-02 19:49:56.947392 [DEBUG] switch_core_state_machine.c:151 sofia/internal/PhonerLite@192.168.1.102 Standard EXECUTE EXECUTE sofia/internal/PhonerLite@192.168.1.102 set(sip_contact_user=PhonerLite) EXECUTE sofia/internal/PhonerLite@192.168.1.102 info() 2009-12-02 19:49:56.957384 [INFO] mod_dptools.c:955 CHANNEL_DATA: Channel-State: [CS_EXECUTE] Channel-State-Number: [4] Channel-Name: [sofia/internal/PhonerLite@192.168.1.102] Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1] Call-Direction: [inbound] Presence-Call-Direction: [inbound] Answer-State: [ringing] Caller-Username: [PhonerLite] Caller-Dialplan: [class4] Caller-Caller-ID-Name: [PhonerLite] Caller-Caller-ID-Number: [PhonerLite] Caller-Network-Addr: [192.168.1.104] Caller-Destination-Number: [90964111] Caller-Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1] Caller-Source: [mod_sofia] Caller-Context: [default] Caller-Channel-Name: [sofia/internal/PhonerLite@192.168.1.102] Caller-Profile-Index: [1] Caller-Profile-Created-Time: [1259754596930444] Caller-Channel-Created-Time: [1259754596930444] 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-Screen-Bit: [true] Caller-Privacy-Hide-Name: [false] Caller-Privacy-Hide-Number: [false] variable_sip_received_ip: [192.168.1.104] variable_sip_received_port: [5060] variable_sip_via_protocol: [udp] variable_sip_from_user: [PhonerLite] variable_sip_from_uri: [PhonerLite@192.168.1.102] variable_sip_from_host: [192.168.1.102] variable_sip_from_user_stripped: [PhonerLite] variable_sip_from_tag: [2563216860] variable_sofia_profile_name: [internal] variable_sip_req_user: [90964111] variable_sip_req_uri: [90964111@192.168.1.102] variable_sip_req_host: [192.168.1.102] variable_sip_to_user: [90964111] variable_sip_to_uri: [90964111@192.168.1.102] variable_sip_to_host: [192.168.1.102] variable_sip_contact_port: [5060] variable_sip_contact_uri: [PhonerLite@192.168.1.104:5060] variable_sip_contact_host: [192.168.1.104] variable_channel_name: [sofia/internal/PhonerLite@192.168.1.102] variable_sip_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104] variable_sip_user_agent: [SIPPER for PhonerLite] variable_sip_via_host: [192.168.1.104] variable_sip_via_port: [5060] variable_sip_via_rport: [5060] variable_max_forwards: [70] variable_switch_r_sdp: [v=0 o=- 478760567 0 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.104 t=0 0 m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101 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 ] variable_ep_codec_string: [PCMA@8000h,PCMU@8000h] variable_endpoint_disposition: [RECEIVED_NOMEDIA] variable_effective_caller_id_number: [PhonerLite] variable_effective_caller_id_name: [PhonerLite] variable_ CS_INIT 2009-12-02 19:49:56.959386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9390 [BREAK] 2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9390) Running State Change CS_INIT 2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111@192.168.1.116:9390) State INIT 2009-12-02 19:49:56.959386 [DEBUG] mod_sofia.c:83 sofia/internal/90964111@192.168.1.116:9390 SOFIA INIT 2009-12-02 19:49:56.959386 [DEBUG] mod_sofia.c:111 (sofia/internal/90964111@192.168.1.116:9390) State Change CS_INIT -> CS_ROUTING 2009-12-02 19:49:56.959386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9390 [BREAK] 2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111@192.168.1.116:9390) State INIT going to sleep 2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9390) Running State Change CS_ROUTING 2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111@192.168.1.116:9390) State ROUTING 2009-12-02 19:49:56.959386 [DEBUG] mod_sofia.c:130 sofia/internal/90964111@192.168.1.116:9390 SOFIA ROUTING 2009-12-02 19:49:56.959386 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/90964111@192.168.1.116:9390) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-12-02 19:49:56.959386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9390 [BREAK] 2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111@192.168.1.116:9390) State ROUTING going to sleep 2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9390) Running State Change CS_CONSUME_MEDIA 2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111@192.168.1.116:9390) State CONSUME_MEDIA 2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111@192.168.1.116:9390) State CONSUME_MEDIA going to sleep send 1156 bytes to udp/[192.168.1.116]:9390 at 11:49:56.960484: ------------------------------------------------------------------------ INVITE sip:90964111@192.168.1.116:9390 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKS9Qm26ZS4U93m Max-Forwards: 69 From: "PhonerLite" ;tag=r0pv05c0848ae To: Call-ID: a76ed230-59db-122d-ff84-0022190e9476 CSeq: 123758962 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 401 Remote-Party-ID: "PhonerLite" ;party=calling;screen=yes;privacy=off v=0 o=- 478760567 523740055483911509 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.104 t=0 0 m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101 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 ------------------------------------------------------------------------ 2009-12-02 19:49:56.960392 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111@192.168.1.116:9390 entering state [calling][0] recv 342 bytes from udp/[192.168.1.116]:9390 at 11:49:56.962288: ------------------------------------------------------------------------ SIP/2.0 403 Because Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKS9Qm26ZS4U93m From: "PhonerLite" ;tag=r0pv05c0848ae To: ;tag=6 Call-ID: a76ed230-59db-122d-ff84-0022190e9476 CSeq: 123758962 INVITE Contact: Content-Length: 0 ------------------------------------------------------------------------ send 332 bytes to udp/[192.168.1.116]:9390 at 11:49:56.962463: ------------------------------------------------------------------------ ACK sip:90964111@192.168.1.116:9390 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKS9Qm26ZS4U93m Max-Forwards: 69 From: "PhonerLite" ;tag=r0pv05c0848ae To: ;tag=6 Call-ID: a76ed230-59db-122d-ff84-0022190e9476 CSeq: 123758962 ACK Content-Length: 0 ------------------------------------------------------------------------ 2009-12-02 19:49:56.962396 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111@192.168.1.116:9390 entering state [terminated][403] 2009-12-02 19:49:56.962396 [NOTICE] sofia.c:3925 Hangup sofia/internal/90964111@192.168.1.116:9390 [CS_CONSUME_MEDIA] [CALL_REJECTED] 2009-12-02 19:49:56.962396 [DEBUG] switch_channel.c:1726 Send signal sofia/internal/90964111@192.168.1.116:9390 [KILL] 2009-12-02 19:49:56.962396 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9390 [BREAK] 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler. 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9390) Running State Change CS_HANGUP 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111@192.168.1.116:9390) State HANGUP 2009-12-02 19:49:56.962396 [DEBUG] mod_sofia.c:306 sofia/internal/90964111@192.168.1.116:9390 Overriding SIP cause 603 with 403 from the other leg 2009-12-02 19:49:56.962396 [DEBUG] mod_sofia.c:338 Channel sofia/internal/90964111@192.168.1.116:9390 hanging up, cause: CALL_REJECTED 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:46 sofia/internal/90964111@192.168.1.116:9390 Standard HANGUP, cause: CALL_REJECTED 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111@192.168.1.116:9390) State HANGUP going to sleep 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/90964111@192.168.1.116:9390) State Change CS_HANGUP -> CS_REPORTING 2009-12-02 19:49:56.962396 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9390 [BREAK] 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9390) Running State Change CS_REPORTING 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111@192.168.1.116:9390) State REPORTING 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:53 sofia/internal/90964111@192.168.1.116:9390 Standard REPORTING, cause: CALL_REJECTED 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111@192.168.1.116:9390) State REPORTING going to sleep 2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/90964111@192.168.1.116:9390) State Change CS_REPORTING -> CS_DESTROY 2009-12-02 19:49:56.962396 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9390 [BREAK] 2009-12-02 19:49:56.962396 [DEBUG] switch_core_session.c:1069 Session 11 (sofia/internal/90964111@192.168.1.116:9390) Locked, Waiting on external entities 2009-12-02 19:49:56.963391 [DEBUG] switch_ivr_originate.c:2273 Originate Resulted in Error Cause: 21 [CALL_REJECTED] 2009-12-02 19:49:56.963391 [INFO] mod_dptools.c:2106 Originate Failed. Cause: CALL_REJECTED 2009-12-02 19:49:56.963391 [DEBUG] mod_dptools.c:2128 Continue on fail [true]: Cause: CALL_REJECTED 2009-12-02 19:49:56.963391 [INFO] mod_dptools.c:955 CHANNEL_DATA: Channel-State: [CS_EXECUTE] Channel-State-Number: [4] Channel-Name: [sofia/internal/PhonerLite@192.168.1.102] Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1] Call-Direction: [inbound] Presence-Call-Direction: [inbound] Answer-State: [ringing] Caller-Username: [PhonerLite] Caller-Dialplan: [class4] Caller-Caller-ID-Name: [PhonerLite] Caller-Caller-ID-Number: [PhonerLite] Caller-Network-Addr: [192.168.1.104] Caller-Destination-Number: [90964111] Caller-Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1] Caller-Source: [mod_sofia] Caller-Context: [default] Caller-Channel-Name: [sofia/internal/PhonerLite@192.168.1.102] Caller-Profile-Index: [1] Caller-Profile-Created-Time: [1259754596930444] Caller-Channel-Created-Time: [1259754596930444] 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-Screen-Bit: [true] Caller-Privacy-Hide-Name: [false] Caller-Privacy-Hide-Number: [false] Other-Leg-Username: [PhonerLite] Other-Leg-Caller-ID-Name: [PhonerLite] Other-Leg-Caller-ID-Number: [PhonerLite] Other-Leg-Network-Addr: [192.168.1.104] Other-Leg-Destination-Number: [90964111@192.168.1.116:9390] Other-Leg-Unique-ID: [aca42f97-803b-40b4-93b5-79531cdd47e7] Other-Leg-Source: [mod_sofia] Other-Leg-Channel-Name: [sofia/internal/90964111@192.168.1.116:9390] Other-Leg-Screen-Bit: [true] Other-Leg-Privacy-Hide-Name: [false] Other-Leg-Privacy-Hide-Number: [false] variable_sip_received_ip: [192.168.1.104] variable_sip_received_port: [5060] variable_sip_via_protocol: [udp] variable_sip_from_user: [PhonerLite] variable_sip_from_uri: [PhonerLite@192.168.1.102] variable_sip_from_host: [192.168.1.102] variable_sip_from_user_stripped: [PhonerLite] variable_sip_from_tag: [2563216860] variable_sofia_profile_name: [internal] variable_sip_req_user: [90964111] variable_sip_req_uri: [90964111@192.168.1.102] variable_sip_req_host: [192.168.1.102] variable_sip_to_user: [90964111] variable_sip_to_uri: [90964111@192.168.1.102] variable_sip_to_host: [192.168.1.102] variable_sip_contact_port: [5060] variable_sip_contact_uri: [PhonerLite@192.168.1.104:5060] variable_sip_contact_host: [192.168.1.104] variable_channel_name: [sofia/internal/PhonerLite@192.168.1.102] variable_sip_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104] variable_sip_user_agent: [SIPPER for PhonerLite] variable_sip_via_host: [192.168.1.104] variable_sip_via_port: [5060] variable_sip_via_rport: [5060] variable_max_forwards: [70] variable_switch_r_sdp: [v=0 o=- 478760567 0 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.104 t=0 0 m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101 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 ] variable_ep_codec_string: [PCMA@8000h,PCMU@8000h] variable_endpoint_disposition: [RECEIVED_NOMEDIA] variable_effective_caller_id_number: [PhonerLite] variable_effective_caller_id_name: [PhonerLite] variable_ CS_INIT 2009-12-02 19:49:56.973380 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9391 [BREAK] 2009-12-02 19:49:56.974397 [NOTICE] switch_core_session.c:1087 Session 11 (sofia/internal/90964111@192.168.1.116:9390) Ended 2009-12-02 19:49:56.974397 [NOTICE] switch_core_session.c:1089 Close Channel sofia/internal/90964111@192.168.1.116:9390 [CS_DESTROY] 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/90964111@192.168.1.116:9390) Running State Change CS_DESTROY 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111@192.168.1.116:9390) State DESTROY 2009-12-02 19:49:56.974397 [DEBUG] mod_sofia.c:255 sofia/internal/90964111@192.168.1.116:9390 SOFIA DESTROY 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:60 sofia/internal/90964111@192.168.1.116:9390 Standard DESTROY 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111@192.168.1.116:9390) State DESTROY going to sleep 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9391) Running State Change CS_INIT 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111@192.168.1.116:9391) State INIT 2009-12-02 19:49:56.974397 [DEBUG] mod_sofia.c:83 sofia/internal/90964111@192.168.1.116:9391 SOFIA INIT 2009-12-02 19:49:56.974397 [DEBUG] mod_sofia.c:111 (sofia/internal/90964111@192.168.1.116:9391) State Change CS_INIT -> CS_ROUTING 2009-12-02 19:49:56.974397 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9391 [BREAK] 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111@192.168.1.116:9391) State INIT going to sleep 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9391) Running State Change CS_ROUTING 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111@192.168.1.116:9391) State ROUTING 2009-12-02 19:49:56.974397 [DEBUG] mod_sofia.c:130 sofia/internal/90964111@192.168.1.116:9391 SOFIA ROUTING 2009-12-02 19:49:56.974397 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/90964111@192.168.1.116:9391) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-12-02 19:49:56.974397 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9391 [BREAK] 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111@192.168.1.116:9391) State ROUTING going to sleep 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9391) Running State Change CS_CONSUME_MEDIA 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111@192.168.1.116:9391) State CONSUME_MEDIA 2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111@192.168.1.116:9391) State CONSUME_MEDIA going to sleep send 1157 bytes to udp/[192.168.1.116]:9391 at 11:49:56.980816: ------------------------------------------------------------------------ INVITE sip:90964111@192.168.1.116:9391 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKtjHD41gX14Zpg Max-Forwards: 69 From: "PhonerLite" ;tag=S9FN20X35DZXS To: Call-ID: a771eccc-59db-122d-ff84-0022190e9476 CSeq: 123758962 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 402 Remote-Party-ID: "PhonerLite" ;party=calling;screen=yes;privacy=off v=0 o=- 478760567 5392773558290384508 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.104 t=0 0 m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101 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 ------------------------------------------------------------------------ 2009-12-02 19:49:56.980394 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111@192.168.1.116:9391 entering state [calling][0] recv 342 bytes from udp/[192.168.1.116]:9391 at 11:49:56.990534: ------------------------------------------------------------------------ SIP/2.0 403 Because Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKtjHD41gX14Zpg From: "PhonerLite" ;tag=S9FN20X35DZXS To: ;tag=6 Call-ID: a771eccc-59db-122d-ff84-0022190e9476 CSeq: 123758962 INVITE Contact: Content-Length: 0 ------------------------------------------------------------------------ send 332 bytes to udp/[192.168.1.116]:9391 at 11:49:56.990729: ------------------------------------------------------------------------ ACK sip:90964111@192.168.1.116:9391 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKtjHD41gX14Zpg Max-Forwards: 69 From: "PhonerLite" ;tag=S9FN20X35DZXS To: ;tag=6 Call-ID: a771eccc-59db-122d-ff84-0022190e9476 CSeq: 123758962 ACK Content-Length: 0 ------------------------------------------------------------------------ 2009-12-02 19:49:56.990410 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111@192.168.1.116:9391 entering state [terminated][403] 2009-12-02 19:49:56.990410 [NOTICE] sofia.c:3925 Hangup sofia/internal/90964111@192.168.1.116:9391 [CS_CONSUME_MEDIA] [CALL_REJECTED] 2009-12-02 19:49:56.990410 [DEBUG] switch_channel.c:1726 Send signal sofia/internal/90964111@192.168.1.116:9391 [KILL] 2009-12-02 19:49:56.990410 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9391 [BREAK] 2009-12-02 19:49:56.990410 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler. 2009-12-02 19:49:56.990410 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9391) Running State Change CS_HANGUP 2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111@192.168.1.116:9391) State HANGUP 2009-12-02 19:49:56.991392 [DEBUG] mod_sofia.c:306 sofia/internal/90964111@192.168.1.116:9391 Overriding SIP cause 603 with 403 from the other leg 2009-12-02 19:49:56.991392 [DEBUG] mod_sofia.c:338 Channel sofia/internal/90964111@192.168.1.116:9391 hanging up, cause: CALL_REJECTED 2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:46 sofia/internal/90964111@192.168.1.116:9391 Standard HANGUP, cause: CALL_REJECTED 2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111@192.168.1.116:9391) State HANGUP going to sleep 2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/90964111@192.168.1.116:9391) State Change CS_HANGUP -> CS_REPORTING 2009-12-02 19:49:56.991392 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9391 [BREAK] 2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.116:9391) Running State Change CS_REPORTING 2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111@192.168.1.116:9391) State REPORTING 2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:53 sofia/internal/90964111@192.168.1.116:9391 Standard REPORTING, cause: CALL_REJECTED 2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111@192.168.1.116:9391) State REPORTING going to sleep 2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/90964111@192.168.1.116:9391) State Change CS_REPORTING -> CS_DESTROY 2009-12-02 19:49:56.991392 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.116:9391 [BREAK] 2009-12-02 19:49:56.991392 [DEBUG] switch_core_session.c:1069 Session 12 (sofia/internal/90964111@192.168.1.116:9391) Locked, Waiting on external entities 2009-12-02 19:49:56.991392 [DEBUG] switch_ivr_originate.c:2273 Originate Resulted in Error Cause: 21 [CALL_REJECTED] 2009-12-02 19:49:56.991392 [INFO] mod_dptools.c:2106 Originate Failed. Cause: CALL_REJECTED 2009-12-02 19:49:56.991392 [DEBUG] mod_dptools.c:2128 Continue on fail [true]: Cause: CALL_REJECTED 2009-12-02 19:49:56.992388 [INFO] mod_dptools.c:955 CHANNEL_DATA: Channel-State: [CS_EXECUTE] Channel-State-Number: [4] Channel-Name: [sofia/internal/PhonerLite@192.168.1.102] Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1] Call-Direction: [inbound] Presence-Call-Direction: [inbound] Answer-State: [ringing] Caller-Username: [PhonerLite] Caller-Dialplan: [class4] Caller-Caller-ID-Name: [PhonerLite] Caller-Caller-ID-Number: [PhonerLite] Caller-Network-Addr: [192.168.1.104] Caller-Destination-Number: [90964111] Caller-Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1] Caller-Source: [mod_sofia] Caller-Context: [default] Caller-Channel-Name: [sofia/internal/PhonerLite@192.168.1.102] Caller-Profile-Index: [1] Caller-Profile-Created-Time: [1259754596930444] Caller-Channel-Created-Time: [1259754596930444] 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-Screen-Bit: [true] Caller-Privacy-Hide-Name: [false] Caller-Privacy-Hide-Number: [false] Other-Leg-Username: [PhonerLite] Other-Leg-Caller-ID-Name: [PhonerLite] Other-Leg-Caller-ID-Number: [PhonerLite] Other-Leg-Network-Addr: [192.168.1.104] Other-Leg-Destination-Number: [90964111@192.168.1.116:9391] Other-Leg-Unique-ID: [990cc7dc-e7ea-4b82-850c-9c8c254136f9] Other-Leg-Source: [mod_sofia] Other-Leg-Channel-Name: [sofia/internal/90964111@192.168.1.116:9391] Other-Leg-Screen-Bit: [true] Other-Leg-Privacy-Hide-Name: [false] Other-Leg-Privacy-Hide-Number: [false] variable_sip_received_ip: [192.168.1.104] variable_sip_received_port: [5060] variable_sip_via_protocol: [udp] variable_sip_from_user: [PhonerLite] variable_sip_from_uri: [PhonerLite@192.168.1.102] variable_sip_from_host: [192.168.1.102] variable_sip_from_user_stripped: [PhonerLite] variable_sip_from_tag: [2563216860] variable_sofia_profile_name: [internal] variable_sip_req_user: [90964111] variable_sip_req_uri: [90964111@192.168.1.102] variable_sip_req_host: [192.168.1.102] variable_sip_to_user: [90964111] variable_sip_to_uri: [90964111@192.168.1.102] variable_sip_to_host: [192.168.1.102] variable_sip_contact_port: [5060] variable_sip_contact_uri: [PhonerLite@192.168.1.104:5060] variable_sip_contact_host: [192.168.1.104] variable_channel_name: [sofia/internal/PhonerLite@192.168.1.102] variable_sip_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104] variable_sip_user_agent: [SIPPER for PhonerLite] variable_sip_via_host: [192.168.1.104] variable_sip_via_port: [5060] variable_sip_via_rport: [5060] variable_max_forwards: [70] variable_switch_r_sdp: [v=0 o=- 478760567 0 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.104 t=0 0 m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101 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 ] variable_ep_codec_string: [PCMA@8000h,PCMU@8000h] variable_endpoint_disposition: [RECEIVED_NOMEDIA] variable_effective_caller_id_number: [PhonerLite] variable_effective_caller_id_name: [PhonerLite] variable_ CS_INIT 2009-12-02 19:49:57.003383 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.13:5060 [BREAK] 2009-12-02 19:49:57.004386 [NOTICE] switch_core_session.c:1087 Session 12 (sofia/internal/90964111@192.168.1.116:9391) Ended 2009-12-02 19:49:57.004386 [NOTICE] switch_core_session.c:1089 Close Channel sofia/internal/90964111@192.168.1.116:9391 [CS_DESTROY] 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/90964111@192.168.1.116:9391) Running State Change CS_DESTROY 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111@192.168.1.116:9391) State DESTROY 2009-12-02 19:49:57.004386 [DEBUG] mod_sofia.c:255 sofia/internal/90964111@192.168.1.116:9391 SOFIA DESTROY 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:60 sofia/internal/90964111@192.168.1.116:9391 Standard DESTROY 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111@192.168.1.116:9391) State DESTROY going to sleep 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.13:5060) Running State Change CS_INIT 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111@192.168.1.13:5060) State INIT 2009-12-02 19:49:57.004386 [DEBUG] mod_sofia.c:83 sofia/internal/90964111@192.168.1.13:5060 SOFIA INIT 2009-12-02 19:49:57.004386 [DEBUG] mod_sofia.c:111 (sofia/internal/90964111@192.168.1.13:5060) State Change CS_INIT -> CS_ROUTING 2009-12-02 19:49:57.004386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.13:5060 [BREAK] 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111@192.168.1.13:5060) State INIT going to sleep 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.13:5060) Running State Change CS_ROUTING 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111@192.168.1.13:5060) State ROUTING 2009-12-02 19:49:57.004386 [DEBUG] mod_sofia.c:130 sofia/internal/90964111@192.168.1.13:5060 SOFIA ROUTING 2009-12-02 19:49:57.004386 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/90964111@192.168.1.13:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-12-02 19:49:57.004386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.13:5060 [BREAK] 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111@192.168.1.13:5060) State ROUTING going to sleep 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.13:5060) Running State Change CS_CONSUME_MEDIA 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111@192.168.1.13:5060) State CONSUME_MEDIA 2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111@192.168.1.13:5060) State CONSUME_MEDIA going to sleep send 1155 bytes to udp/[192.168.1.13]:5060 at 11:49:57.012792: ------------------------------------------------------------------------ INVITE sip:90964111@192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B Max-Forwards: 69 From: "PhonerLite" ;tag=tj9D4Ue72pNgN To: Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758962 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 402 Remote-Party-ID: "PhonerLite" ;party=calling;screen=yes;privacy=off v=0 o=- 478760567 3248233194293522444 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.104 t=0 0 m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101 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 ------------------------------------------------------------------------ 2009-12-02 19:49:57.012392 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111@192.168.1.13:5060 entering state [calling][0] recv 283 bytes from udp/[192.168.1.13]:5060 at 11:49:57.219574: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B From: PhonerLite ;tag=tj9D4Ue72pNgN To: Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758962 INVITE Content-Length: 0 ------------------------------------------------------------------------ recv 333 bytes from udp/[192.168.1.13]:5060 at 11:49:57.234550: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B From: PhonerLite ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758962 INVITE Contact: Content-Length: 0 ------------------------------------------------------------------------ 2009-12-02 19:49:57.233383 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111@192.168.1.13:5060 entering state [proceeding][180] 2009-12-02 19:49:57.233383 [NOTICE] sofia.c:3423 Ring-Ready sofia/internal/90964111@192.168.1.13:5060! 2009-12-02 19:49:57.233383 [DEBUG] sofia.c:3432 sofia/internal/PhonerLite@192.168.1.102 receive message [RINGING] 2009-12-02 19:49:57.233383 [NOTICE] mod_sofia.c:1461 Ring-Ready sofia/internal/PhonerLite@192.168.1.102! 2009-12-02 19:49:57.233383 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] send 618 bytes to udp/[192.168.1.104]:5060 at 11:49:57.235092: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport=5060 From: ;tag=2563216860 To: ;tag=QQX3yavvBvjrj Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104 CSeq: 17 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Length: 0 ------------------------------------------------------------------------ 2009-12-02 19:49:57.233383 [DEBUG] sofia.c:3359 Channel sofia/internal/PhonerLite@192.168.1.102 entering state [early][180] 2009-12-02 19:49:57.233383 [DEBUG] switch_ivr_originate.c:1931 sofia/internal/PhonerLite@192.168.1.102 receive message [RINGING] 2009-12-02 19:49:57.233383 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:49:57.233383 [NOTICE] switch_ivr_originate.c:1931 Ring Ready sofia/internal/PhonerLite@192.168.1.102! recv 725 bytes from udp/[192.168.1.13]:5060 at 11:49:59.906802: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B From: PhonerLite ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758962 INVITE Contact: Supported: replaces Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Type: application/sdp Content-Length: 256 v=0 o=sdp_admin 59711527 21326134 IN IP4 192.168.1.13 s=A conversation c=IN IP4 192.168.1.13 t=0 0 m=audio 10098 RTP/AVP 8 0 9 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:9 G722/16000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ send 376 bytes to udp/[192.168.1.13]:5060 at 11:49:59.907203: ------------------------------------------------------------------------ ACK sip:192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKv43y7Qj4UpcvQ Max-Forwards: 70 From: "PhonerLite" ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758962 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2009-12-02 19:49:59.907094 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111@192.168.1.13:5060 entering state [ready][200] 2009-12-02 19:49:59.907094 [DEBUG] sofia.c:3366 Remote SDP: v=0 o=sdp_admin 59711527 21326134 IN IP4 192.168.1.13 s=A conversation c=IN IP4 192.168.1.13 t=0 0 m=audio 10098 RTP/AVP 8 0 9 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:9 G722/16000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 2009-12-02 19:49:59.907094 [DEBUG] switch_channel.c:1935 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:49:59.907094 [NOTICE] sofia.c:3834 Channel [sofia/internal/90964111@192.168.1.13:5060] has been answered 2009-12-02 19:49:59.907094 [DEBUG] switch_channel.c:1971 sofia/internal/90964111@192.168.1.13:5060 execute on answer: incre_call_stat(203 621 201 256 25 2591585 1) EXECUTE sofia/internal/90964111@192.168.1.13:5060 incre_call_stat(203 621 201 256 25 2591585 1) [1259754599] 2009-12-02 19:49:59.907094 [DEBUG] sofia.c:3847 sofia/internal/PhonerLite@192.168.1.102 receive message [ANSWER] 2009-12-02 19:49:59.907094 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:49:59.907911 [NOTICE] sofia.c:3847 Channel [sofia/internal/PhonerLite@192.168.1.102] has been answered 2009-12-02 19:49:59.907911 [DEBUG] switch_ivr_originate.c:2196 Originate Resulted in Success: [sofia/internal/90964111@192.168.1.13:5060] 2009-12-02 19:49:59.907911 [DEBUG] switch_ivr_bridge.c:806 (sofia/internal/PhonerLite@192.168.1.102) State Change CS_EXECUTE -> CS_HIBERNATE 2009-12-02 19:49:59.907911 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:49:59.907911 [DEBUG] switch_ivr_bridge.c:807 (sofia/internal/90964111@192.168.1.13:5060) State Change CS_CONSUME_MEDIA -> CS_HIBERNATE 2009-12-02 19:49:59.907911 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.13:5060 [BREAK] send 858 bytes to udp/[192.168.1.104]:5060 at 11:49:59.908359: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport=5060 From: ;tag=2563216860 To: ;tag=QQX3yavvBvjrj Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104 CSeq: 17 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 207 v=0 o=sdp_admin 59711527 21326134 IN IP4 192.168.1.13 s=A conversation c=IN IP4 192.168.1.13 t=0 0 m=audio 10098 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ EXECUTE sofia/internal/PhonerLite@192.168.1.102 get_next_route() 2009-12-02 19:49:59.907911 [DEBUG] sofia.c:3359 Channel sofia/internal/PhonerLite@192.168.1.102 entering state [completed][200] EXECUTE sofia/internal/PhonerLite@192.168.1.102 info() 2009-12-02 19:49:59.907911 [INFO] mod_dptools.c:955 CHANNEL_DATA: Channel-State: [CS_HIBERNATE] Channel-State-Number: [8] Channel-Name: [sofia/internal/PhonerLite@192.168.1.102] Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1] Call-Direction: [inbound] Presence-Call-Direction: [inbound] Answer-State: [answered] Caller-Username: [PhonerLite] Caller-Dialplan: [class4] Caller-Caller-ID-Name: [PhonerLite] Caller-Caller-ID-Number: [PhonerLite] Caller-Network-Addr: [192.168.1.104] Caller-Destination-Number: [90964111] Caller-Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1] Caller-Source: [mod_sofia] Caller-Context: [default] Caller-Channel-Name: [sofia/internal/PhonerLite@192.168.1.102] Caller-Profile-Index: [1] Caller-Profile-Created-Time: [1259754596930444] Caller-Channel-Created-Time: [1259754596930444] Caller-Channel-Answered-Time: [1259754599907094] Caller-Channel-Progress-Time: [1259754597233383] Caller-Channel-Progress-Media-Time: [0] Caller-Channel-Hangup-Time: [0] Caller-Channel-Transfer-Time: [0] Caller-Screen-Bit: [true] Caller-Privacy-Hide-Name: [false] Caller-Privacy-Hide-Number: [false] Other-Leg-Username: [PhonerLite] Other-Leg-Caller-ID-Name: [PhonerLite] Other-Leg-Caller-ID-Number: [PhonerLite] Other-Leg-Network-Addr: [192.168.1.104] Other-Leg-Destination-Number: [90964111@192.168.1.13:5060] Other-Leg-Unique-ID: [e73fbd5b-a2ca-4685-bc6e-e1f848e8c7e5] Other-Leg-Source: [mod_sofia] Other-Leg-Channel-Name: [sofia/internal/90964111@192.168.1.13:5060] Other-Leg-Screen-Bit: [true] Other-Leg-Privacy-Hide-Name: [false] Other-Leg-Privacy-Hide-Number: [false] variable_sip_received_ip: [192.168.1.104] variable_sip_received_port: [5060] variable_sip_via_protocol: [udp] variable_sip_from_user: [PhonerLite] variable_sip_from_uri: [PhonerLite@192.168.1.102] variable_sip_from_host: [192.168.1.102] variable_sip_from_user_stripped: [PhonerLite] variable_sip_from_tag: [2563216860] variable_sofia_profile_name: [internal] variable_sip_req_user: [90964111] variable_sip_req_uri: [90964111@192.168.1.102] variable_sip_req_host: [192.168.1.102] variable_sip_to_user: [90964111] variable_sip_to_uri: [90964111@192.168.1.102] variable_sip_to_host: [192.168.1.102] variable_sip_contact_port: [5060] variable_sip_contact_uri: [PhonerLite@192.168.1.104:5060] variable_sip_contact_host: [192.168.1.104] variable_channel_name: [sofia/internal/PhonerLite@192.168.1.102] variable_sip_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104] variable_sip_user_agent: [SIPPER for PhonerLite] variable_sip_via_host: [192.168.1.104] variable_sip_via_port: [5060] variable_sip_via_rport: [5060] variable_max_forwards: [70] variable_switch_r_sdp: [v=0 o=- 478760567 0 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.104 t=0 0 m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101 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 ] variable_ep_codec_string: [PCMA@8000h,PCMU@8000h] variable_effective_caller_id_number: [PhonerLite] variable_effective_caller_id_name: [PhonerLite] variable_;tag=2563216860 To: ;tag=QQX3yavvBvjrj Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104 CSeq: 17 ACK Contact: Max-Forwards: 70 Content-Length: 0 ------------------------------------------------------------------------ 2009-12-02 19:49:59.907911 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite@192.168.1.102 SET [egress_alias]=[9392] 2009-12-02 19:49:59.907911 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.13:5060) Running State Change CS_HIBERNATE 2009-12-02 19:49:59.907911 [DEBUG] switch_core_state_machine.c:355 (sofia/internal/90964111@192.168.1.13:5060) State HIBERNATE 2009-12-02 19:49:59.907911 [DEBUG] mod_sofia.c:160 sofia/internal/90964111@192.168.1.13:5060 SOFIA HIBERNATE 2009-12-02 19:49:59.907911 [DEBUG] switch_core_state_machine.c:212 sofia/internal/90964111@192.168.1.13:5060 Standard HIBERNATE 2009-12-02 19:49:59.907911 [DEBUG] switch_core_state_machine.c:355 (sofia/internal/90964111@192.168.1.13:5060) State HIBERNATE going to sleep 2009-12-02 19:49:59.907911 [DEBUG] sofia.c:3359 Channel sofia/internal/PhonerLite@192.168.1.102 entering state [ready][200] 2009-12-02 19:49:59.907911 [DEBUG] switch_ivr.c:1159 sofia/internal/PhonerLite@192.168.1.102 receive message [MEDIA] 2009-12-02 19:49:59.917033 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:49:59.917033 [CRIT] switch_core_io.c:115 sofia/internal/PhonerLite@192.168.1.102 reading on a session with no media! 2009-12-02 19:49:59.918616 [DEBUG] switch_ivr.c:1174 sofia/internal/90964111@192.168.1.13:5060 receive message [MEDIA] 2009-12-02 19:49:59.918616 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/90964111@192.168.1.13:5060 [BREAK] send 955 bytes to udp/[192.168.1.13]:5060 at 11:49:59.922755: ------------------------------------------------------------------------ INVITE sip:192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK Max-Forwards: 69 From: "PhonerLite" ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758963 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 223 Remote-Party-ID: "PhonerLite" ;party=calling;screen=yes;privacy=off v=0 o=- 478760567 3248233194293522445 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.102 t=0 0 m=audio 33352 RTP/AVP 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - ------------------------------------------------------------------------ 2009-12-02 19:49:59.922846 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111@192.168.1.13:5060 entering state [calling][0] send 955 bytes to udp/[192.168.1.13]:5060 at 11:50:00.423821: ------------------------------------------------------------------------ INVITE sip:192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK Max-Forwards: 69 From: "PhonerLite" ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758963 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 223 Remote-Party-ID: "PhonerLite" ;party=calling;screen=yes;privacy=off v=0 o=- 478760567 3248233194293522445 IN IP4 192.168.1.104 s=SIPPER for PhonerLite c=IN IP4 192.168.1.102 t=0 0 m=audio 33352 RTP/AVP 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - ------------------------------------------------------------------------ recv 337 bytes from udp/[192.168.1.13]:5060 at 11:50:00.444374: ------------------------------------------------------------------------ SIP/2.0 400 Bad Request Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK From: PhonerLite ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758963 INVITE Contact: Content-Length: 0 ------------------------------------------------------------------------ send 330 bytes to udp/[192.168.1.13]:5060 at 11:50:00.444561: ------------------------------------------------------------------------ ACK sip:192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK Max-Forwards: 69 From: "PhonerLite" ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758963 ACK Content-Length: 0 ------------------------------------------------------------------------ 2009-12-02 19:50:00.445918 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111@192.168.1.13:5060 entering state [ready][400] 2009-12-02 19:50:00.445918 [NOTICE] sofia.c:3891 Hangup sofia/internal/90964111@192.168.1.13:5060 [CS_HIBERNATE] [INCOMPATIBLE_DESTINATION] 2009-12-02 19:50:00.445918 [DEBUG] switch_channel.c:1726 Send signal sofia/internal/90964111@192.168.1.13:5060 [KILL] 2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.13:5060 [BREAK] 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler. 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.13:5060) Running State Change CS_HANGUP 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111@192.168.1.13:5060) State HANGUP 2009-12-02 19:50:00.445918 [DEBUG] mod_sofia.c:338 Channel sofia/internal/90964111@192.168.1.13:5060 hanging up, cause: INCOMPATIBLE_DESTINATION 2009-12-02 19:50:00.445918 [DEBUG] mod_sofia.c:376 Sending BYE to sofia/internal/90964111@192.168.1.13:5060 2009-12-02 19:50:00.445918 [NOTICE] switch_ivr_bridge.c:727 Hangup sofia/internal/PhonerLite@192.168.1.102 [CS_HIBERNATE] [INCOMPATIBLE_DESTINATION] 2009-12-02 19:50:00.445918 [DEBUG] switch_channel.c:1726 Send signal sofia/internal/PhonerLite@192.168.1.102 [KILL] 2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler. 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:46 sofia/internal/90964111@192.168.1.13:5060 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111@192.168.1.13:5060) State HANGUP going to sleep 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:494 Hangup Command decre_call_stat(203 621 201 256 25 2591585 1): 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/90964111@192.168.1.13:5060) State Change CS_HANGUP -> CS_REPORTING 2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.13:5060 [BREAK] 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111@192.168.1.13:5060) Running State Change CS_REPORTING 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111@192.168.1.13:5060) State REPORTING 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:53 sofia/internal/90964111@192.168.1.13:5060 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111@192.168.1.13:5060) State REPORTING going to sleep 2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/90964111@192.168.1.13:5060) State Change CS_REPORTING -> CS_DESTROY 2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111@192.168.1.13:5060 [BREAK] 2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:1069 Session 13 (sofia/internal/90964111@192.168.1.13:5060) Locked, Waiting on external entities 2009-12-02 19:50:00.447416 [ERR] switch_core_io.c:120 sofia/internal/90964111@192.168.1.13:5060 has no read codec. 2009-12-02 19:50:00.447416 [DEBUG] switch_ivr_bridge.c:1210 originator uuid 85042f75-de46-4fe5-b8d8-53e4ca0d26e1 is not present 2009-12-02 19:50:00.447416 [NOTICE] switch_core_session.c:1087 Session 13 (sofia/internal/90964111@192.168.1.13:5060) Ended 2009-12-02 19:50:00.447416 [NOTICE] switch_core_session.c:1089 Close Channel sofia/internal/90964111@192.168.1.13:5060 [CS_DESTROY] 2009-12-02 19:50:00.447416 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/90964111@192.168.1.13:5060) Running State Change CS_DESTROY 2009-12-02 19:50:00.447416 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111@192.168.1.13:5060) State DESTROY 2009-12-02 19:50:00.447416 [DEBUG] mod_sofia.c:255 sofia/internal/90964111@192.168.1.13:5060 SOFIA DESTROY 2009-12-02 19:50:00.447416 [DEBUG] switch_core_state_machine.c:60 sofia/internal/90964111@192.168.1.13:5060 Standard DESTROY 2009-12-02 19:50:00.447416 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111@192.168.1.13:5060) State DESTROY going to sleep 2009-12-02 19:50:00.449755 [DEBUG] switch_ivr_originate.c:2273 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 2009-12-02 19:50:00.449755 [INFO] mod_dptools.c:2106 Originate Failed. Cause: INCOMPATIBLE_DESTINATION 2009-12-02 19:50:00.449755 [DEBUG] mod_dptools.c:2128 Continue on fail [true]: Cause: INCOMPATIBLE_DESTINATION 2009-12-02 19:50:00.449755 [DEBUG] switch_core_session.c:1371 Channel is hungup, aborting execution of application: get_next_route 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/PhonerLite@192.168.1.102) State EXECUTE going to sleep 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite@192.168.1.102) Running State Change CS_HANGUP 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/PhonerLite@192.168.1.102) State HANGUP 2009-12-02 19:50:00.449755 [DEBUG] mod_sofia.c:306 sofia/internal/PhonerLite@192.168.1.102 Overriding SIP cause 488 with 403 from the other leg 2009-12-02 19:50:00.449755 [DEBUG] mod_sofia.c:338 Channel sofia/internal/PhonerLite@192.168.1.102 hanging up, cause: INCOMPATIBLE_DESTINATION 2009-12-02 19:50:00.449755 [DEBUG] mod_sofia.c:376 Sending BYE to sofia/internal/PhonerLite@192.168.1.102 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:46 sofia/internal/PhonerLite@192.168.1.102 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/PhonerLite@192.168.1.102) State HANGUP going to sleep 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/PhonerLite@192.168.1.102) State Change CS_HANGUP -> CS_REPORTING 2009-12-02 19:50:00.449755 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite@192.168.1.102) Running State Change CS_REPORTING 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/PhonerLite@192.168.1.102) State REPORTING 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:53 sofia/internal/PhonerLite@192.168.1.102 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/PhonerLite@192.168.1.102) State REPORTING going to sleep 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/PhonerLite@192.168.1.102) State Change CS_REPORTING -> CS_DESTROY 2009-12-02 19:50:00.449755 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite@192.168.1.102 [BREAK] 2009-12-02 19:50:00.449755 [DEBUG] switch_core_session.c:1069 Session 10 (sofia/internal/PhonerLite@192.168.1.102) Locked, Waiting on external entities 2009-12-02 19:50:00.449755 [NOTICE] switch_core_session.c:1087 Session 10 (sofia/internal/PhonerLite@192.168.1.102) Ended 2009-12-02 19:50:00.449755 [NOTICE] switch_core_session.c:1089 Close Channel sofia/internal/PhonerLite@192.168.1.102 [CS_DESTROY] 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/PhonerLite@192.168.1.102) Running State Change CS_DESTROY 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/PhonerLite@192.168.1.102) State DESTROY 2009-12-02 19:50:00.449755 [DEBUG] mod_sofia.c:255 sofia/internal/PhonerLite@192.168.1.102 SOFIA DESTROY 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:60 sofia/internal/PhonerLite@192.168.1.102 Standard DESTROY 2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/PhonerLite@192.168.1.102) State DESTROY going to sleep send 620 bytes to udp/[192.168.1.13]:5060 at 11:50:00.452239: ------------------------------------------------------------------------ BYE sip:192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKyppgBemBp8r1e Max-Forwards: 70 From: "PhonerLite" ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758964 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION" Content-Length: 0 ------------------------------------------------------------------------ send 641 bytes to udp/[192.168.1.104]:5060 at 11:50:00.452431: ------------------------------------------------------------------------ BYE sip:PhonerLite@192.168.1.104:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKZZF9c94eKHFma Max-Forwards: 70 From: ;tag=QQX3yavvBvjrj To: ;tag=2563216860 Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104 CSeq: 123758964 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION" Content-Length: 0 ------------------------------------------------------------------------ recv 376 bytes from udp/[192.168.1.104]:5060 at 11:50:00.453188: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.102;rport=5060;branch=z9hG4bKZZF9c94eKHFma From: ;tag=QQX3yavvBvjrj To: ;tag=2563216860 Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5@192.168.1.104 CSeq: 123758964 BYE Contact: User-Agent: SIPPER for PhonerLite Content-Length: 0 ------------------------------------------------------------------------ recv 337 bytes from udp/[192.168.1.13]:5060 at 11:50:00.530378: ------------------------------------------------------------------------ SIP/2.0 400 Bad Request Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK From: PhonerLite ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758963 INVITE Contact: Content-Length: 0 ------------------------------------------------------------------------ send 330 bytes to udp/[192.168.1.13]:5060 at 11:50:00.530547: ------------------------------------------------------------------------ ACK sip:192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK Max-Forwards: 69 From: "PhonerLite" ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758963 ACK Content-Length: 0 ------------------------------------------------------------------------ recv 325 bytes from udp/[192.168.1.13]:5060 at 11:50:00.821952: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKyppgBemBp8r1e From: PhonerLite ;tag=tj9D4Ue72pNgN To: ;tag=2900712955 Call-ID: a776ce22-59db-122d-ff84-0022190e9476 CSeq: 123758964 BYE Contact: Content-Length: 0 ------------------------------------------------------------------------