--- (9 headers 0 lines) --- -- Got SIP response 480 "Temporarily Unavailable" back from PUBLICIPPHONE Transmitting (NAT) to PUBLICIPPHONE:15990: ACK sip:9205@PUBLICIPPHONE:15990;rinstance=7755a19cdab8dc0a SIP/2.0 Via: SIP/2.0/UDP PUBLICIPPBX:5060;branch=z9hG4bK5ac10ace;rport Max-Forwards: 70 From: "Unavailable " ;tag=as009cd9c7 To: ;tag=300b160c Contact: Call-ID: 4ad6fee76eb8f19b0c799b8375b6552f@PUBLICIPPBX CSeq: 102 ACK User-Agent: Asterisk PBX 1.6.2.7 Content-Length: 0 --- -- SIP/9205-00000005 is circuit-busy == Everyone is busy/congested at this time (1:0/1/0) -- Executing [s@macro-dial:8] Set("SIP/DefaultOut-00000004", "DIALSTATUS=CONGESTION") in new stack -- Executing [s@macro-dial:9] GosubIf("SIP/DefaultOut-00000004", "0?CONGESTION,1") in new stack -- Executing [s@macro-exten-vm:10] GotoIf("SIP/DefaultOut-00000004", "0?exit,return") in new stack -- Executing [s@macro-exten-vm:11] Set("SIP/DefaultOut-00000004", "SV_DIALSTATUS=CONGESTION") in new stack -- Executing [s@macro-exten-vm:12] GosubIf("SIP/DefaultOut-00000004", "0?docfu,1") in new stack -- Executing [s@macro-exten-vm:13] GosubIf("SIP/DefaultOut-00000004", "0?docfb,1") in new stack -- Executing [s@macro-exten-vm:14] Set("SIP/DefaultOut-00000004", "DIALSTATUS=CONGESTION") in new stack -- Executing [s@macro-exten-vm:15] NoOp("SIP/DefaultOut-00000004", "Voicemail is '903'") in new stack -- Executing [s@macro-exten-vm:16] GotoIf("SIP/DefaultOut-00000004", "0?s-CONGESTION,1") in new stack -- Executing [s@macro-exten-vm:17] NoOp("SIP/DefaultOut-00000004", "Sending to Voicemail box 903") in new stack -- Executing [s@macro-exten-vm:18] Macro("SIP/DefaultOut-00000004", "vm,903,CONGESTION,") in new stack -- Executing [s@macro-vm:1] Macro("SIP/DefaultOut-00000004", "user-callerid,SKIPTTL") in new stack -- Executing [s@macro-user-callerid:1] Set("SIP/DefaultOut-00000004", "AMPUSER=3032425036") in new stack -- Executing [s@macro-user-callerid:2] GotoIf("SIP/DefaultOut-00000004", "0?report") in new stack -- Executing [s@macro-user-callerid:3] ExecIf("SIP/DefaultOut-00000004", "0?Set(REALCALLERIDNUM=3032425036)") in new stack -- Executing [s@macro-user-callerid:4] Set("SIP/DefaultOut-00000004", "AMPUSER=") in new stack -- Executing [s@macro-user-callerid:5] Set("SIP/DefaultOut-00000004", "AMPUSERCIDNAME=") in new stack -- Executing [s@macro-user-callerid:6] GotoIf("SIP/DefaultOut-00000004", "1?report") in new stack -- Goto (macro-user-callerid,s,10) -- Executing [s@macro-user-callerid:10] GotoIf("SIP/DefaultOut-00000004", "1?continue") in new stack -- Goto (macro-user-callerid,s,19) -- Executing [s@macro-user-callerid:19] NoOp("SIP/DefaultOut-00000004", "Using CallerID "Unavailable " <3032425036>") in new stack -- Executing [s@macro-vm:2] Set("SIP/DefaultOut-00000004", "VMGAIN=""") in new stack -- Executing [s@macro-vm:3] GotoIf("SIP/DefaultOut-00000004", "1?vmx,1") in new stack -- Goto (macro-vm,vmx,1) -- Executing [vmx@macro-vm:1] Set("SIP/DefaultOut-00000004", "MEXTEN=903") in new stack -- Executing [vmx@macro-vm:2] Set("SIP/DefaultOut-00000004", "MMODE=CONGESTION") in new stack -- Executing [vmx@macro-vm:3] Set("SIP/DefaultOut-00000004", "RETVM=") in new stack -- Executing [vmx@macro-vm:4] Set("SIP/DefaultOut-00000004", "MODE=unavail") in new stack -- Executing [vmx@macro-vm:5] GotoIf("SIP/DefaultOut-00000004", "1?chknomsg") in new stack -- Goto (macro-vm,vmx,7) -- Executing [vmx@macro-vm:7] GotoIf("SIP/DefaultOut-00000004", "0?s-CONGESTION,1") in new stack -- Executing [vmx@macro-vm:8] GotoIf("SIP/DefaultOut-00000004", "1?notdirect") in new stack -- Goto (macro-vm,vmx,10) -- Executing [vmx@macro-vm:10] NoOp("SIP/DefaultOut-00000004", "Checking if ext 903 is enabled: ") in new stack -- Executing [vmx@macro-vm:11] GotoIf("SIP/DefaultOut-00000004", "1?s-CONGESTION,1") in new stack -- Goto (macro-vm,s-CONGESTION,1) -- Executing [s-CONGESTION@macro-vm:1] Macro("SIP/DefaultOut-00000004", "get-vmcontext,903") in new stack -- Executing [s@macro-get-vmcontext:1] Set("SIP/DefaultOut-00000004", "VMCONTEXT=default") in new stack -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/DefaultOut-00000004", "0?200:300") in new stack -- Goto (macro-get-vmcontext,s,300) -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/DefaultOut-00000004", "") in new stack -- Executing [s-CONGESTION@macro-vm:2] VoiceMail("SIP/DefaultOut-00000004", "903@default,u""") in new stack -- Playing 'vm-theperson.slin' (language 'en') Really destroying SIP dialog '4ad6fee76eb8f19b0c799b8375b6552f@PUBLICIPPBX' Method: INVITE -- Playing 'digits/9.slin' (language 'en') -- Playing 'digits/0.slin' (language 'en') -- Playing 'digits/3.slin' (language 'en') -- Playing 'vm-isunavail.slin' (language 'en') -- Playing 'vm-intro.slin' (language 'en') Reliably Transmitting (NAT) to PUBLICIPPHONE:15990: OPTIONS sip:9205@PUBLICIPPHONE:15990;rinstance=7755a19cdab8dc0a SIP/2.0 Via: SIP/2.0/UDP PUBLICIPPBX:5060;branch=z9hG4bK3914cd72;rport Max-Forwards: 70 From: "Unknown" ;tag=as43f16928 To: Contact: Call-ID: 6305fdab66d5b76d2d78ecb017c29528@PUBLICIPPBX CSeq: 102 OPTIONS User-Agent: Asterisk PBX 1.6.2.7 Date: Wed, 26 May 2010 18:39:02 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- testpbx*CLI> <--- SIP read from UDP:PUBLICIPPHONE:15990 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP PUBLICIPPBX:5060;branch=z9hG4bK3914cd72;rport=5060 Contact: To: ;tag=b2023f78 From: "Unknown";tag=as43f16928 Call-ID: 6305fdab66d5b76d2d78ecb017c29528@PUBLICIPPBX CSeq: 102 OPTIONS Accept: application/sdp Accept-Language: en Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1104o stamp 56125 Content-Length: 0 <-------------> --- (12 headers 0 lines) --- Really destroying SIP dialog '6305fdab66d5b76d2d78ecb017c29528@PUBLICIPPBX' Method: OPTIONS testpbx*CLI> <--- SIP read from UDP:PUBLICIPPHONE:15990 ---> <-------------> testpbx*CLI> <--- SIP read from UDP:PUBLICIPPHONE:15990 ---> SUBSCRIBE sip:Unknown@PUBLICIPPBX SIP/2.0 Via: SIP/2.0/UDP 10.0.0.120:49742;branch=z9hG4bK-d8754z-d1199e1bb362c54a-1---d8754z-;rport Max-Forwards: 70 Contact: To: "Rob Bosch";tag=as2b8945d2 From: "Rob Bosch";tag=c4341e42 Call-ID: ZWUxNmRlZWY1Yjc2MGQ0NDNiZWY3YTUwZTg1NTIzZGQ. CSeq: 4 SUBSCRIBE Expires: 300 User-Agent: X-Lite release 1104o stamp 56125 Authorization: Digest username="9205",realm="asterisk",nonce="0b4bbc4d",uri="sip:Unknown@PUBLICIPPBX",response="83dc7f458e5a71727fad367af0843189",algorithm=MD5 Event: message-summary Content-Length: 0 <-------------> --- (13 headers 0 lines) --- Found peer '9205' for '9205' from PUBLICIPPHONE:15990 testpbx*CLI> <--- Transmitting (NAT) to PUBLICIPPHONE:15990 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.0.0.120:49742;branch=z9hG4bK-d8754z-d1199e1bb362c54a-1---d8754z-;received=PUBLICIPPHONE;rport=15990 From: "Rob Bosch";tag=c4341e42 To: "Rob Bosch";tag=as2b8945d2 Call-ID: ZWUxNmRlZWY1Yjc2MGQ0NDNiZWY3YTUwZTg1NTIzZGQ. CSeq: 4 SUBSCRIBE Server: Asterisk PBX 1.6.2.7 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="60b3ba71", stale=true Content-Length: 0 <------------> Scheduling destruction of SIP dialog 'ZWUxNmRlZWY1Yjc2MGQ0NDNiZWY3YTUwZTg1NTIzZGQ.' in 9024 ms (Method: SUBSCRIBE) testpbx*CLI> <--- SIP read from UDP:PUBLICIPPHONE:15990 ---> SUBSCRIBE sip:Unknown@PUBLICIPPBX SIP/2.0 Via: SIP/2.0/UDP 10.0.0.120:49742;branch=z9hG4bK-d8754z-7b16e323ab08dc7e-1---d8754z-;rport Max-Forwards: 70 Contact: To: "Rob Bosch";tag=as2b8945d2 From: "Rob Bosch";tag=c4341e42 Call-ID: ZWUxNmRlZWY1Yjc2MGQ0NDNiZWY3YTUwZTg1NTIzZGQ. CSeq: 5 SUBSCRIBE Expires: 300 User-Agent: X-Lite release 1104o stamp 56125 Authorization: Digest username="9205",realm="asterisk",nonce="60b3ba71",uri="sip:Unknown@PUBLICIPPBX",response="3ea6dc35de75c88f57c136d8f91cedfb",algorithm=MD5 Event: message-summary Content-Length: 0 <-------------> --- (13 headers 0 lines) --- Found peer '9205' for '9205' from PUBLICIPPHONE:15990 Scheduling destruction of SIP dialog 'ZWUxNmRlZWY1Yjc2MGQ0NDNiZWY3YTUwZTg1NTIzZGQ.' in 310000 ms (Method: SUBSCRIBE) <--- Transmitting (NAT) to PUBLICIPPHONE:15990 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.0.0.120:49742;branch=z9hG4bK-d8754z-7b16e323ab08dc7e-1---d8754z-;received=PUBLICIPPHONE;rport=15990 From: "Rob Bosch";tag=c4341e42 To: "Rob Bosch";tag=as2b8945d2 Call-ID: ZWUxNmRlZWY1Yjc2MGQ0NDNiZWY3YTUwZTg1NTIzZGQ. CSeq: 5 SUBSCRIBE Server: Asterisk PBX 1.6.2.7 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Expires: 300 Contact: ;expires=300 Content-Length: 0 <------------> Reliably Transmitting (NAT) to PUBLICIPPHONE:15990: NOTIFY sip:9205@PUBLICIPPHONE:15990 SIP/2.0 Via: SIP/2.0/UDP PUBLICIPPBX:5060;branch=z9hG4bK2b23fa71;rport Max-Forwards: 70 Route: From: "Unknown" ;tag=as2b8945d2 To: ;tag=c4341e42 Contact: Call-ID: ZWUxNmRlZWY1Yjc2MGQ0NDNiZWY3YTUwZTg1NTIzZGQ. CSeq: 103 NOTIFY User-Agent: Asterisk PBX 1.6.2.7 Event: message-summary Content-Type: application/simple-message-summary Subscription-State: active Content-Length: 87 Messages-Waiting: yes Message-Account: sip:*97@PUBLICIPPBX Voice-Message: 1/0 (0/0) --- testpbx*CLI> <--- SIP read from UDP:PUBLICIPPHONE:15990 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP PUBLICIPPBX:5060;branch=z9hG4bK2b23fa71;rport=5060 Contact: To: ;tag=c4341e42 From: "Unknown";tag=as2b8945d2 Call-ID: ZWUxNmRlZWY1Yjc2MGQ0NDNiZWY3YTUwZTg1NTIzZGQ. CSeq: 103 NOTIFY User-Agent: X-Lite release 1104o stamp 56125 Content-Length: 0 <-------------> --- (9 headers 0 lines) --- -- Playing 'beep.slin' (language 'en') -- Recording the message -- x=0, open writing: /var/spool/asterisk/voicemail/default/903/tmp/jdJLRl format: wav49, 0x169eb28 -- x=1, open writing: /var/spool/asterisk/voicemail/default/903/tmp/jdJLRl format: gsm, 0x170d458 -- x=2, open writing: /var/spool/asterisk/voicemail/default/903/tmp/jdJLRl format: wav, 0x16f1978 -- User hung up == Spawn extension (macro-vm, s-CONGESTION, 2) exited non-zero on 'SIP/DefaultOut-00000004' in macro 'vm' == Spawn extension (macro-exten-vm, s, 18) exited non-zero on 'SIP/DefaultOut-00000004' in macro 'exten-vm' == Spawn extension (from-did-direct, 903, 1) exited non-zero on 'SIP/DefaultOut-00000004' testpbx*CLI> <--- SIP read from UDP:PUBLICIPPHONE:15990 ---> <-------------> testpbx*CLI> sip set debug off SIP Debugging Disabled testpbx*CLI>