INVITE sip:97572381@moip.oc2 SIP/2.0 Via: SIP/2.0/UDP 10.196.21.46:5060;branch=z9hG4bK49b527ae;rport From: "68482213" ;tag=as6d88fcc6 To: Contact: Call-ID: 4beb32806f718b406261ffec673f2fbd@10.196.21.46 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Thu, 01 Jan 1970 00:03:22 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 263 v=0 o=root 14305 14305 IN IP4 10.196.21.46 s=session c=IN IP4 10.196.21.46 t=0 0 m=audio 47338 RTP/AVP 0 3 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- OpenWrt*CLI> -- Called 97572381@my-SIP-server OpenWrt*CLI> Jan 1 00:03:22 WARNING[14305]: channel.c:2739 ast_channel_make_compatible: No path to translate from S IP/my-SIP-server-0c6d(0) to MSPD/phone1-a917(4) OpenWrt*CLI> -- MSPD|mspd_new_ast_channel(slic:1,incoming): PBX started on MSPD/phone1-a917 OpenWrt*CLI> <-- SIP read from 10.161.129.68:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.196.21.46:5060;branch=z9hG4bK49b527ae;rport=5060 Call-ID: 4beb32806f718b406261ffec673f2fbd@10.196.21.46 From: "68482213";tag=as6d88fcc6 To: CSeq: 102 INVITE Content-Length: 0 --- (7 headers 0 lines)--- OpenWrt*CLI> <-- SIP read from 10.161.129.68:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.196.21.46:5060;branch=z9hG4bK49b527ae;rport=5060 Call-ID: 4beb32806f718b406261ffec673f2fbd@10.196.21.46 From: "68482213";tag=as6d88fcc6 To: ;tag=3f3eb40a CSeq: 102 INVITE Proxy-Authenticate: Digest realm="SIP",nonce="12:10:18:5039", stale=false,algorithm=MD5 Reason: Q.850;cause=0;text="unknown" Content-Length: 0 --- (9 headers 0 lines)--- Transmitting (no NAT) to 10.161.129.68:5060: ACK sip:97572381@moip.oc2 SIP/2.0 Via: SIP/2.0/UDP 10.196.21.46:5060;branch=z9hG4bK49b527ae;rport From: "68482213" ;tag=as6d88fcc6 To: ;tag=3f3eb40a Contact: Call-ID: 4beb32806f718b406261ffec673f2fbd@10.196.21.46 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- We're at 10.196.21.46 port 47338 == SIP|add_sdp(SIP/my-SIP-server-0c6d): channel: 0xe (gsm|ulaw|alaw); redir: 0.0.0.0:0 0x0 (nothi ng) == SIP|add_sdp(SIP/my-SIP-server-0c6d): using prefcodec=0x4 (ulaw) as root capability Adding codec 0x4 (ulaw) to SDP == SIP|add_sdp(SIP/my-SIP-server-0c6d): adding capabilities=0xe (gsm|ulaw|alaw) with prefs=(gsm|u law|alaw) Adding codec 0x2 (gsm) to SDP Adding codec 0x8 (alaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP Reliably Transmitting (no NAT) to 10.161.129.68:5060: INVITE sip:97572381@moip.oc2 SIP/2.0 Via: SIP/2.0/UDP 10.196.21.46:5060;branch=z9hG4bK0d80e972;rport From: "68482213" ;tag=as6d88fcc6 To: Contact: Call-ID: 4beb32806f718b406261ffec673f2fbd@10.196.21.46 CSeq: 103 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Proxy-Authorization: Digest username="68482213", realm="SIP", algorithm=MD5, uri="sip:97572381@moip .oc2", nonce="12:10:18:5039", response="08d9538d6758f054c2dba6a4ef6cc333", opaque="" Date: Thu, 01 Jan 1970 00:03:22 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 263 v=0 o=root 14305 14306 IN IP4 10.196.21.46 s=session c=IN IP4 10.196.21.46 t=0 0 m=audio 47338 RTP/AVP 0 3 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- <-- SIP read from 10.161.129.68:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.196.21.46:5060;branch=z9hG4bK0d80e972;rport=5060 Call-ID: 4beb32806f718b406261ffec673f2fbd@10.196.21.46 From: "68482213";tag=as6d88fcc6 To: CSeq: 103 INVITE Content-Length: 0 --- (7 headers 0 lines)--- <-- SIP read from 10.161.129.68:5060: SIP/2.0 500 Server Internal Error Via: SIP/2.0/UDP 10.196.21.46:5060;branch=z9hG4bK0d80e972;rport=5060 Call-ID: 4beb32806f718b406261ffec673f2fbd@10.196.21.46 From: "68482213";tag=as6d88fcc6 To: ;tag=12a727a3 CSeq: 103 INVITE Reason: Q.850;cause=98;text="Message not compatible with call state or message type non-existent or not implemented" Content-Length: 0 --- (8 headers 0 lines)--- -- Got SIP response 500 "Server Internal Error" back from 10.161.129.68 Transmitting (no NAT) to 10.161.129.68:5060: ACK sip:97572381@moip.oc2 SIP/2.0 Via: SIP/2.0/UDP 10.196.21.46:5060;branch=z9hG4bK0d80e972;rport From: "68482213" ;tag=as6d88fcc6 To: ;tag=12a727a3 Contact: Call-ID: 4beb32806f718b406261ffec673f2fbd@10.196.21.46 CSeq: 103 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- -- SIP/my-SIP-server-0c6d is circuit-busy == Everyone is busy/congested at this time (1:0/1/0) -- Executing SetVar("MSPD/phone1-a917", "VMBOX=997572381") in new stack Jan 1 00:03:22 WARNING[14305]: pbx.c:6018 pbx_builtin_setvar_old: SetVar is deprecated, please use Set instead. -- Executing NoOp("MSPD/phone1-a917", "997572381") in new stack -- Executing NoOp("MSPD/phone1-a917", "997572381") in new stack -- Executing Goto("MSPD/phone1-a917", "s-CONGESTION|1") in new stack -- Goto (macro-stdexten,s-CONGESTION,1) -- Executing VoiceMail("MSPD/phone1-a917", "u997572381") in new stack == MSPD|mspd_answer(MSPD/phone1-a917|Ring,slic:1|CALL_PROCEEDING) -- MSPD|dc_enable(,ulaw) -- MSPD|dc_set_tone(): set OFF -- MSPD|dc_start_tx() -- mspd_answer: MSPD/phone1-a917 formats: read=0x4 (ulaw) (raw 0x4 (ulaw)), write=0x4 (ulaw) (raw 0 x4 (ulaw)), native=0x4 (ulaw) Jan 1 00:03:22 WARNING[14305]: app_voicemail.c:2411 leave_voicemail: No entry in voicemail config file for '997572381' -- Executing Hangup("MSPD/phone1-a917", "") in new stack == Spawn extension (macro-stdexten, s-CONGESTION, 2) exited non-zero on 'MSPD/phone1-a917' in macro ' stdexten' == Spawn extension (macro-stdexten, s-CONGESTION, 2) exited non-zero on 'MSPD/phone1-a917' Jan 1 00:03:22 NOTICE[14305]: chan_mspd.c:2007 mspd_hangup: MSPD|mspd_hangup: Staring hangup on MSPD c hannel == MSPD|mspd_hangup(MSPD/phone1-a917|Up,slic:1|CONNECTED) -- MSPD|dc_stop_tx() -- MSPD|dc_enable(,ulaw) -- MSPD|dc_set_tone(): set BUSY == MSPD|sl_reset_line(slic:1/DISCONNECTING): usrid=0x1, linkid=0xc2a7ba80 -- MSPD|mspd_hangup(MSPD/phone1-a917|Down,slic:1|DISCONNECTING): hungup OpenWrt*CLI> Destroying call '4beb32806f718b406261ffec673f2fbd@10.196.21.46' OpenWrt*CLI> Jan 1 00:03:32 NOTICE[11434]: mspd_sti-lines.c:1214 sl_producer: MSPD|sl_producer:read event successfu lly (0x1) Jan 1 00:03:32 NOTICE[11434]: mspd_sti-lines.c:1251 sl_producer: MSPD|sl_producer:********* Got event DISCONNECT_CALL on line = 0xed9d4 slic:1 tdm=0 timeslot=0 -- MSPD|sl_producer: event DISCONNECT_CALL for line slic:1 (linkid=0xc2a7ba80) OpenWrt*CLI> -- MSPD|sl_consumer(slic:1/DISCONNECTING): event DISCONNECT_CALL MSPD|sl_handle_disconnect_call(slic:1/DISCONNECTING): usrid=0x1, linkid=0x0 == MSPD|sl_reset_line(slic:1/DISCONNECTING): usrid=0x1, linkid=0x0 -- MSPD|dc_destroy_channel(,0): i=1, slic:1 -- MSPD|dc_disable() OpenWrt*CLI> -- MSPD|dc_delete_channel(): i=1, ts=1 OpenWrt*CLI> == MSPD|dc_consumer(): stopping thread 15375, i=1 OpenWrt*CLI> MSPD|dc_destroy_channel(): done, slic:1, i=1 Jan 1 00:03:32 NOTICE[11428]: mspd_sti-lines.c:857 sl_handle_event: MSPD|sl_handle_event: hangup_owner =1, owner=0, wait=0