<-- SIP read from 80.95.96.101:5063:
INVITE sip:226549022@82.100.52.2:5060;user=phone SIP/2.0
Allow: UPDATE,REFER
Call-ID: 02-01985-001c004e-12c234a20@sip.broadnet.cz
Call-Info: <sip:sip.broadnet.cz:5063>;purpose=void;answer-after=0
Contact: <sip:80.95.96.101:5063>
CSeq: 1695458 INVITE
From: <sip:271035846@sip.broadnet.cz;user=phone>;tag=02-01985-001c004f-37a59b893
Max-Forwards: 31
P-Asserted-Identity: <sip:271035846@82.100.52.2;user=phone>
To: <sip:226549022@82.100.52.2;user=phone>
User-Agent: Cirpack/v4.40 (gw_sip)
Via: SIP/2.0/UDP 80.95.96.101:5063;branch=z9hG4bK-20E9-3D243
Content-Length: 0


Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 0: INVITE sip:226549022@82.100.52.2:5060;user=phone SIP/2.0 (56)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 1: Allow: UPDATE,REFER (19)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 2: Call-ID: 02-01985-001c004e-12c234a20@sip.broadnet.cz (52)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 3: Call-Info: <sip:sip.broadnet.cz:5063>;purpose=void;answer-after=0 (65)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 4: Contact: <sip:80.95.96.101:5063> (32)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 5: CSeq: 1695458 INVITE (20)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 6: From: <sip:271035846@sip.broadnet.cz;user=phone>;tag=02-01985-001c004f-37a59b893 (80)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 7: Max-Forwards: 31 (16)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 8: P-Asserted-Identity: <sip:271035846@82.100.52.2;user=phone> (59)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 9: To: <sip:226549022@82.100.52.2;user=phone> (42)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 10: User-Agent: Cirpack/v4.40 (gw_sip) (34)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 11: Via: SIP/2.0/UDP 80.95.96.101:5063;branch=z9hG4bK-20E9-3D243 (60)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 12: Content-Length: 0 (17)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 13:  (0)
--- (13 headers 0 lines)---
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3174 sip_alloc: Allocating new SIP dialog for 02-01985-001c004e-12c234a20@sip.broadnet.cz - INVITE (With RTP)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:11191 handle_request: **** Received INVITE (5) - Command in SIP INVITE
Using INVITE request as basis request - 02-01985-001c004e-12c234a20@sip.broadnet.cz
Sending to 80.95.96.101 : 5063 (non-NAT)
Found no matching peer or user for '80.95.96.101:5063'
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:10533 handle_request_invite: Hm....  No sdp for the moment
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:10542 handle_request_invite: Checking SIP call limits for device
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:2208 update_call_counter: Updating call counter for incoming call
Looking for 226549022 in default (domain 82.100.52.2)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:6205 build_route: build_route: Contact hop: <sip:80.95.96.101:5063>
list_route: hop: <sip:80.95.96.101:5063>
Transmitting (no NAT) to 80.95.96.101:5063:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 80.95.96.101:5063;branch=z9hG4bK-20E9-3D243;received=80.95.96.101
From: <sip:271035846@sip.broadnet.cz;user=phone>;tag=02-01985-001c004f-37a59b893
To: <sip:226549022@82.100.52.2;user=phone>
Call-ID: 02-01985-001c004e-12c234a20@sip.broadnet.cz
CSeq: 1695458 INVITE
User-Agent: Komoush's Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:226549022@82.100.52.2>
Content-Length: 0


---
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:11722 sip_devicestate: Checking device state for peer sip.broadnet.cz
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1677 pbx_extension_helper: Launching 'Dial'
    -- Executing Dial("SIP/sip.broadnet.cz-086b5000", "SIP/226549022|5|t") in new stack
Aug 18 16:09:29 WARNING[12544]: channel.c:2494 ast_request: Channel SIP does not support requested formats ()
Aug 18 16:09:29 NOTICE[12544]: app_dial.c:1040 dial_exec_full: Unable to create channel of type 'SIP' (cause 0 - Unknown)
  == Everyone is busy/congested at this time (1:0/0/1)
Aug 18 16:09:29 DEBUG[12544]: app_dial.c:1619 dial_exec_full: Exiting with DIALSTATUS=CHANUNAVAIL.
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1677 pbx_extension_helper: Launching 'VoiceMail'
    -- Executing VoiceMail("SIP/sip.broadnet.cz-086b5000", "u226549022") in new stack
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:2545 sip_answer: sip_answer(SIP/sip.broadnet.cz-086b5000)
We're at 82.100.52.2 port 15188
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (no NAT) to 80.95.96.101:5063:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 80.95.96.101:5063;branch=z9hG4bK-20E9-3D243;received=80.95.96.101
From: <sip:271035846@sip.broadnet.cz;user=phone>;tag=02-01985-001c004f-37a59b893
To: <sip:226549022@82.100.52.2;user=phone>;tag=as2cb5f84e
Call-ID: 02-01985-001c004e-12c234a20@sip.broadnet.cz
CSeq: 1695458 INVITE
User-Agent: Komoush's Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:226549022@82.100.52.2>
Content-Type: application/sdp
Content-Length: 190

v=0
o=root 12544 12544 IN IP4 82.100.52.2
s=session
c=IN IP4 82.100.52.2
t=0 0
m=audio 15188 RTP/AVP 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

---
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:1294 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id  #16
Aug 18 16:09:29 WARNING[12544]: channel.c:2320 set_format: Unable to find a codec translation path from 0x0 (nothing) to gsm
Aug 18 16:09:29 DEBUG[12544]: channel.c:2334 set_format: Set channel SIP/sip.broadnet.cz-086b5000 to write format gsm
Aug 18 16:09:29 WARNING[12544]: translate.c:88 powerof: Powerof 0: No power??
Aug 18 16:09:29 WARNING[12544]: translate.c:88 powerof: Powerof 0: No power??
Aug 18 16:09:29 WARNING[12544]: translate.c:133 ast_translator_build_path: No translator path from g723 to unknown
Aug 18 16:09:29 WARNING[12544]: chan_sip.c:2560 sip_write: Asked to transmit frame type 2, while native formats is 0 (read/write = 0/2)
    -- Playing '/var/spool/asterisk/voicemail/default/226549022/unavail' (language 'en')

<-- SIP read from 80.95.96.101:5063:
ACK sip:226549022@82.100.52.2 SIP/2.0
Call-ID: 02-01985-001c004e-12c234a20@sip.broadnet.cz
Contact: <sip:80.95.96.101:5063>
Content-Type: application/sdp
CSeq: 1695458 ACK
From: <sip:271035846@sip.broadnet.cz;user=phone>;tag=02-01985-001c004f-37a59b893
Max-Forwards: 31
To: <sip:226549022@82.100.52.2;user=phone>;tag=as2cb5f84e
Via: SIP/2.0/UDP 80.95.96.101:5063;branch=z9hG4bK-7F79-3D244
Content-Length: 173

v=0
o=cp10 115591015286 115591015287 IN IP4 0.0.0.0
s=SIP Call
c=IN IP4 0.0.0.0
t=0 0
m=audio 65534 RTP/AVP 8
b=AS:64
a=rtpmap:8 PCMA/8000/1
a=ptime:20
a=inactive

Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 0: ACK sip:226549022@82.100.52.2 SIP/2.0 (37)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 1: Call-ID: 02-01985-001c004e-12c234a20@sip.broadnet.cz (52)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 2: Contact: <sip:80.95.96.101:5063> (32)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 3: Content-Type: application/sdp (29)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 4: CSeq: 1695458 ACK (17)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 5: From: <sip:271035846@sip.broadnet.cz;user=phone>;tag=02-01985-001c004f-37a59b893 (80)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 6: Max-Forwards: 31 (16)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 7: To: <sip:226549022@82.100.52.2;user=phone>;tag=as2cb5f84e (57)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 8: Via: SIP/2.0/UDP 80.95.96.101:5063;branch=z9hG4bK-7F79-3D244 (60)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 9: Content-Length: 173 (19)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 10:  (0)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: v=0 (3)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: o=cp10 115591015286 115591015287 IN IP4 0.0.0.0 (47)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: s=SIP Call (10)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: c=IN IP4 0.0.0.0 (16)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: t=0 0 (5)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: m=audio 65534 RTP/AVP 8 (23)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: b=AS:64 (7)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: a=rtpmap:8 PCMA/8000/1 (22)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: a=ptime:20 (10)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3422 parse_request: Line: a=inactive (10)
--- (10 headers 10 lines)---
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:11191 handle_request: **** Received ACK (6) - Command in SIP ACK
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:1391 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #16
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:1402 __sip_ack: Stopping retransmission on '02-01985-001c004e-12c234a20@sip.broadnet.cz' of Response 1695458: Match Found
Found RTP audio format 8
Peer audio RTP is at port 0.0.0.0:65534
Aug 18 16:09:29 DEBUG[12544]: devicestate.c:187 do_state_change: Changing state for SIP/sip.broadnet.cz - state 4 (Invalid)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3641 process_sdp: Peer audio RTP is at port 0.0.0.0:65534
Found description format PCMA
Capabilities: us - 0x40c (ulaw|alaw|ilbc), peer - audio=0x8 (alaw)/video=0x0 (nothing), combined - (alaw)
Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing)

<-- SIP read from 80.95.96.101:5063:
BYE sip:226549022@82.100.52.2 SIP/2.0
Call-ID: 02-01985-001c004e-12c234a20@sip.broadnet.cz
CSeq: 1695459 BYE
From: <sip:271035846@sip.broadnet.cz;user=phone>;tag=02-01985-001c004f-37a59b893
Max-Forwards: 31
To: <sip:226549022@82.100.52.2;user=phone>;tag=as2cb5f84e
Via: SIP/2.0/UDP 80.95.96.101:5063;branch=z9hG4bK-3A97-3D245
Content-Length: 0


Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:11722 sip_devicestate: Checking device state for peer sip.broadnet.cz
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 0: BYE sip:226549022@82.100.52.2 SIP/2.0 (37)
Aug 18 16:09:29 DEBUG[12544]: app_queue.c:523 changethread: Device 'SIP/sip.broadnet.cz' changed to state '4' (Invalid) but we don't care because they're not a member of any queue.
Aug 18 16:09:29 WARNING[12544]: translate.c:88 powerof: Powerof 0: No power??
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 1: Call-ID: 02-01985-001c004e-12c234a20@sip.broadnet.cz (52)
Aug 18 16:09:29 WARNING[12544]: translate.c:88 powerof: Powerof 0: No power??
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 2: CSeq: 1695459 BYE (17)
Aug 18 16:09:29 WARNING[12544]: translate.c:133 ast_translator_build_path: No translator path from g723 to unknown
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 3: From: <sip:271035846@sip.broadnet.cz;user=phone>;tag=02-01985-001c004f-37a59b893 (80)
Aug 18 16:09:29 WARNING[12544]: chan_sip.c:2560 sip_write: Asked to transmit frame type 2, while native formats is 8 (read/write = 0/2)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 4: Max-Forwards: 31 (16)
Aug 18 16:09:29 DEBUG[12544]: sched.c:218 sched_settime: Request to schedule in the past?!?!
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 5: To: <sip:226549022@82.100.52.2;user=phone>;tag=as2cb5f84e (57)
Aug 18 16:09:29 WARNING[12544]: translate.c:88 powerof: Powerof 0: No power??
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 6: Via: SIP/2.0/UDP 80.95.96.101:5063;branch=z9hG4bK-3A97-3D245 (60)
Aug 18 16:09:29 WARNING[12544]: translate.c:88 powerof: Powerof 0: No power??
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 7: Content-Length: 0 (17)
Aug 18 16:09:29 WARNING[12544]: translate.c:133 ast_translator_build_path: No translator path from g723 to unknown
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:3390 parse_request: Header 8:  (0)
--- (8 headers 0 lines)---
Aug 18 16:09:29 WARNING[12544]: chan_sip.c:2560 sip_write: Asked to transmit frame type 2, while native formats is 8 (read/write = 0/2)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:11368 sipsock_read: Failed to grab lock, trying again...
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:11191 handle_request: **** Received BYE (8) - Command in SIP BYE
Sending to 80.95.96.101 : 5063 (non-NAT)
Transmitting (no NAT) to 80.95.96.101:5063:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 80.95.96.101:5063;branch=z9hG4bK-3A97-3D245;received=80.95.96.101
From: <sip:271035846@sip.broadnet.cz;user=phone>;tag=02-01985-001c004f-37a59b893
To: <sip:226549022@82.100.52.2;user=phone>;tag=as2cb5f84e
Call-ID: 02-01985-001c004e-12c234a20@sip.broadnet.cz
CSeq: 1695459 BYE
User-Agent: Komoush's Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:226549022@82.100.52.2>
Content-Length: 0


---
Aug 18 16:09:29 DEBUG[12544]: devicestate.c:187 do_state_change: Changing state for SIP/sip.broadnet.cz - state 4 (Invalid)
Aug 18 16:09:29 DEBUG[12544]: app_voicemail.c:2474 leave_voicemail: Hang up during prefile playback
Aug 18 16:09:29 DEBUG[12544]: pbx.c:2316 __ast_pbx_run: Spawn extension (default,226549022,2) exited non-zero on 'SIP/sip.broadnet.cz-086b5000'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '271035846'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '271035846'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '226549022'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'default'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'SIP/sip.broadnet.cz-086b5000'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'VoiceMail'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'u226549022'
Aug 18 16:09:29 DEBUG[12544]: app_queue.c:523 changethread: Device 'SIP/sip.broadnet.cz' changed to state '4' (Invalid) but we don't care because they're not a member of any queue.
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-08-18 16:09:29'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-08-18 16:09:29'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-08-18 16:09:29'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '0'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '0'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'ANSWERED'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '1155910169.0'
Aug 18 16:09:29 DEBUG[12544]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)'
Aug 18 16:09:29 DEBUG[12544]: channel.c:1315 ast_hangup: Hanging up channel 'SIP/sip.broadnet.cz-086b5000'
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:2417 sip_hangup: Hangup call SIP/sip.broadnet.cz-086b5000, SIP callid 02-01985-001c004e-12c234a20@sip.broadnet.cz)
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:2425 sip_hangup: update_call_counter() - decrement call limit counter
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:2208 update_call_counter: Updating call counter for incoming call
Aug 18 16:09:29 DEBUG[12544]: chan_sip.c:11722 sip_devicestate: Checking device state for peer sip.broadnet.cz
Aug 18 16:09:29 DEBUG[12544]: devicestate.c:187 do_state_change: Changing state for SIP/sip.broadnet.cz - state 4 (Invalid)
Aug 18 16:09:29 DEBUG[12544]: app_queue.c:523 changethread: Device 'SIP/sip.broadnet.cz' changed to state '4' (Invalid) but we don't care because they're not a member of any queue.

<-- SIP read from 82.142.87.45:32775: