cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
Announcements

Welcome to the Cisco Small Business Community

Have a question? Click on a topic board below to get started in the community.

1612
Views
6
Helpful
18
Replies
hanefi.turkoz
Beginner

Platform independant cti integration

Dear,

i have fully integrate :

- cisco spa 50X 

- CRM

- Extension mobility

- IPPBX

And all "not dependant platform", it's work on linux, windows, mac, ... over VPN or over internet ...

but my only problem is BLF and "subscribe" .. with spa500S console.

My question is : is there a way to control CISCO SPA 500S (attendant console) remotely ?  by script, php.. ?

my issue is : cisco spa500S don't retry to subscribe with a new call transaction but always with a old... As a see in this forum, many persoon have problem with "subscribe" on spa500S. It's not possible to user to see 30 led blink yellow.. and all phone are online and registered.

Witch setting must i configure for that ?

Cisco Spa500S have some programming sample somewhere i can download ?

PS : i see, i have only this sip error : 481 : call transaction/leg don't exist ..

Regards.

18 REPLIES 18

Subscription-State: terminated;reason=noresource

And after the 203 go power on, somebody forget the SUBSCRIBE and do not send the NOTIFY to 394.

394 should send SUBSCRIBE peridically (when subscription terminated). If it will not do it, then it seems to be Cisco's bug.

Try to shorten Sub Max Expires to something like 30 second or so. May be the phone is using expire timer dedicated to active subscriptions instead the one for failed subscriptions in our case ...

View solution in original post

So, most relevant part are here:

Tue Jan 07 2014 14:28:53 GMT+0100 (CET)::SUBSCRIBE sip:203@10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-8ddd94e4

From: "394" <394>;tag=32daf143ee67c542

To: <203>

Call-ID: 53c4e17-8c2fee7e@10.0.0.84

CSeq: 33996 SUBSCRIBE

Max-Forwards: 70

Contact: "394" <394>

Accept: application/dialog-info+xml

Expires: 1800

Event: dialog

User-Agent: Cisco/SPA504G-7.5.5

Content-Length: 0

Tue Jan 07 2014 14:28:53 GMT+0100 (CET)::NOTIFY sip:394@10.0.0.84:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-Mlb-3409;rport

From: <203>;tag=21419

To: "394" <394>;tag=32daf143ee67c542

Call-ID: 53c4e17-8c2fee7e@10.0.0.84

CSeq: 100269 NOTIFY

Subscription-State: terminated;reason=noresource

Max-Forwards: 70

User-Agent: Draytek Mars 1.0.0

Event: dialog

Content-Type: application/dialog-info+xml

Content-Length:  207

terminated

Tue Jan 07 2014 14:28:53 GMT+0100 (CET)::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-8ddd94e4

From: "394" <394>;tag=32daf143ee67c542

To: <203>;tag=21419

Call-ID: 53c4e17-8c2fee7e@10.0.0.84

CSeq: 33996 SUBSCRIBE

Contact: <203>

Server: Draytek Mars 1.0.0

Event: dialog

Expires: 1800

Content-Length: 0

Tue Jan 07 2014 14:38:38 GMT+0100 (CET)::NOTIFY sip:394@10.0.0.84:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-XUT-5011;rport

From: <203>;tag=21419

To: "394" <394>;tag=32daf143ee67c542

Call-ID: 53c4e17-8c2fee7e@10.0.0.84

CSeq: 100453 NOTIFY

Subscription-State: terminated;reason=noresource

Max-Forwards: 70

User-Agent: Draytek Mars 1.0.0

Event: dialog

Content-Type: application/dialog-info+xml

Content-Length:  207

terminated

Tue Jan 07 2014 14:38:38 GMT+0100 (CET)::SIP/2.0 481 Call Leg/Transaction Does Not Exist

To: "394" <394>;tag=32daf143ee67c542

From: <203>;tag=21419

Call-ID: 53c4e17-8c2fee7e@10.0.0.84

CSeq: 100453 NOTIFY

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-XUT-5011

Server: Cisco/SPA504G-7.5.5

Content-Length: 0

Lets analyze.

[1] is the SUBSCRIBE packet from 394 to Draytek

[2] is NOTIFYcation from Draytek related to subscription with Subscription-State: terminated

[3] is the response to SUBSCRIBE

[4] is another NOTIFY with Subscription-State: terminated

Taken from RFC3265:

A subscription is destroyed when a notifier sends a NOTIFY request
with a "Subscription-State" of "terminated".

So, subscription of 203 state request is accepted [3] by Draytek, but terminated [2] immediately. The further NOTIFY, like [4] are not part of of valid subscription, so they are rejected with 481 error code.

Conclusion ? 394 is not able to subscribe the 203 state, so button blink orange.

Problem is on Draytek side. I assume it doesn't distinguish between extension that doesn't exist and and extension that's not reachable just now. Describing it in Q.850 terms - it treat code 20 (subscriber absent) the same way as code 1 (unassigned number).

On the other side, I'm not sure what state you wish to see here. All at all, Draytek's behavior related to SUBSCRIPTION to unregistered extension needs to be configured on Draytek.

And rest seems to be obvious. The 203 will start and register to Draytek sometime. Next subscription from 394 will not be terminated anymore, so button become solid green.  394 should retry terminated subscription within 10 second (unless Sub Retry Intvl configured to non-default value by you).  Of course, you may not wait 10 second, you may reboot 394. It will trigger new subscription as well.

View solution in original post

Dear Dan,

Problem is on Draytek side. I assume it doesn't distinguish between extension that doesn't exist and and extension that's not reachable just now. Describing it in Q.850 terms - it treat code 20 (subscriber absent) the same way as code 1 (unassigned number).

If someone from cisco and draytek "engineer" can confirm that

cisco spa don't SUBSCRIBE if "noressource" ?

Here another log : 203 and 394 ..each monitor the other

each : 

Sub Max Expires: 180

Sub Retry Intvl:   60

And always same, who first boot, have the blink orange

I know now too why i must "hard reboot like power off power on".

When i reboot the phone by http://10.0.0.86/admin/reboot   ..the registration of the extension is "directly removed" from draytek.

if i hard reboot like "power off, power on", the registration of the extension still in "draytek"..because registration time of 300s.

Thanks Dan, your help is many apprecied and for me you have give the "bug" and the "solution"

I hope cisco and draytek can find a solution for me.

Regards

server listening 0.0.0.0:2514

Wed Jan 08 2014 14:42:47 GMT+0100 (CET)::10.0.0.86::<159>fu:0:0dd90, 6.2.16 6.2.17 1

Wed Jan 08 2014 14:42:51 GMT+0100 (CET)::10.0.0.84::<159>fu:0:0e45a, 6.2.16 6.2.17 1

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>request reboot type=4 reason=User Triggered(10)

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>request reboot type=4 reason=User Triggered(10)

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{422)

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{422)

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::SUBSCRIBE sip:394@10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-2f695735

From: "203" <203>;tag=942c2b0aa482fdf9

To: <394>;tag=3741

Call-ID: cf41291e-ea9f6f5@10.0.0.86

CSeq: 32532 SUBSCRIBE

Max-Forwards: 70

Contact: "203" <203>

Accept: application/dialog-info+xml

Expires: 0

Event: dialog

User-Agent: Cisco/SPA501G-7.5.5

Content-Length: 0

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{687)

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{687)

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::REGISTER sip:10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-965a821e

From: "203" <203>;tag=fcbee75a2e4eede1o0

To: "203" <203>

Call-ID: 9b036c0b-8d8441b@10.0.0.86

CSeq: 52046 REGISTER

Max-Forwards: 70

Authorization: Digest username="203",realm="abcd.be",nonce="c939ff72e8f7cb18811bc891dac87a89",uri="sip:10.0.0.254",algorithm=MD5,response="71aff0c67e7cf7c35fcd164bfee41429",opaque="66be48cdcc0397b1219cf76582678f12"

Contact: "203" <203>;expires=0

User-Agent: Cisco/SPA501G-7.5.5

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE, MESSAGE

Supported: replaces

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::NOTIFY sip:203@10.0.0.86:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-aHV-28866;rport

From: <394>;tag=3741

To: "203" <203>;tag=942c2b0aa482fdf9

Call-ID: cf41291e-ea9f6f5@10.0.0.86

CSeq: 114786 NOTIFY

Subscription-State: terminated;reason=timeout

Max-Forwards: 70

User-Agent: Draytek Mars 1.0.0

Event: dialog

Content-Type: application/dialog-info+xml

Content-Length:  207

terminated

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{278)

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{278)

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::SIP/2.0 200 OK

To: "203" <203>;tag=942c2b0aa482fdf9

From: <394>;tag=3741

Call-ID: cf41291e-ea9f6f5@10.0.0.86

CSeq: 114786 NOTIFY

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-aHV-28866

Server: Cisco/SPA501G-7.5.5

Content-Length: 0

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-2f695735

From: "203" <203>;tag=942c2b0aa482fdf9

To: <394>;tag=3741

Call-ID: cf41291e-ea9f6f5@10.0.0.86

CSeq: 32532 SUBSCRIBE

Contact: <394>

Server: Draytek Mars 1.0.0

Event: dialog

Expires: 0

Content-Length: 0

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<151>[0]SubOK. NextSub in 1 (1)

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-965a821e

From: "203" <203>;tag=fcbee75a2e4eede1o0

To: "203" <203>;tag=3683

Call-ID: 9b036c0b-8d8441b@10.0.0.86

CSeq: 52046 REGISTER

Server: Draytek Mars 1.0.0

Content-Length: 0

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<151>[0]UnRegOK

Wed Jan 08 2014 14:43:10 GMT+0100 (CET)::10.0.0.86::<159>fu:0:0de13, 4.71 1

Wed Jan 08 2014 14:43:12 GMT+0100 (CET)::10.0.0.86::<159>first_rule is .

Wed Jan 08 2014 14:43:12 GMT+0100 (CET)::10.0.0.86::<159>first type is 0.

Wed Jan 08 2014 14:43:12 GMT+0100 (CET)::10.0.0.86::<159>fu:0:0df50, 5.1.12 5.1.14 5.1.19 6.8 7.13 1

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>request reboot type=4 reason=User Triggered(10)

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>request reboot type=4 reason=User Triggered(10)

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{422)

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{422)

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::SUBSCRIBE sip:203@10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-6df838d9

From: "394" <394>;tag=67d674e76e57760a

To: <203>;tag=7940

Call-ID: a44f5150-14359d1a@10.0.0.84

CSeq: 1001 SUBSCRIBE

Max-Forwards: 70

Contact: "394" <394>

Accept: application/dialog-info+xml

Expires: 0

Event: dialog

User-Agent: Cisco/SPA504G-7.5.5

Content-Length: 0

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{679)

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{679)

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::REGISTER sip:10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-e39ea229

From: "394" <394>;tag=d053d0d84b8be2c3o0

To: "394" <394>

Call-ID: 8a1cfb96-149f81d8@10.0.0.84

CSeq: 57486 REGISTER

Max-Forwards: 70

Authorization: Digest username="394",realm="abcd.be",nonce="2f612d4d2e6890c43f04bcc8754fcc32",uri="sip:10.0.0.254",algorithm=MD5,response="c0995104aabe168581998a243aa06647",opaque="a0b3688c5cea32f7d8f37fbbe653d4b4"

Contact: "394" <394>;expires=0

User-Agent: Cisco/SPA504G-7.5.5

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE

Supported: replaces

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::NOTIFY sip:394@10.0.0.84:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-fkf-22299;rport

From: <203>;tag=7940

To: "394" <394>;tag=67d674e76e57760a

Call-ID: a44f5150-14359d1a@10.0.0.84

CSeq: 114787 NOTIFY

Subscription-State: terminated;reason=noresource

Max-Forwards: 70

User-Agent: Draytek Mars 1.0.0

Event: dialog

Content-Type: application/dialog-info+xml

Content-Length:  207

terminated

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{279)

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{279)

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::SIP/2.0 200 OK

To: "394" <394>;tag=67d674e76e57760a

From: <203>;tag=7940

Call-ID: a44f5150-14359d1a@10.0.0.84

CSeq: 114787 NOTIFY

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-fkf-22299

Server: Cisco/SPA504G-7.5.5

Content-Length: 0

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-6df838d9

From: "394" <394>;tag=67d674e76e57760a

To: <203>;tag=7940

Call-ID: a44f5150-14359d1a@10.0.0.84

CSeq: 1001 SUBSCRIBE

Contact: <203>

Server: Draytek Mars 1.0.0

Event: dialog

Expires: 0

Content-Length: 0

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<151>[0]SubOK. NextSub in 1 (1)

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-e39ea229

From: "394" <394>;tag=d053d0d84b8be2c3o0

To: "394" <394>;tag=16787

Call-ID: 8a1cfb96-149f81d8@10.0.0.84

CSeq: 57486 REGISTER

Server: Draytek Mars 1.0.0

Content-Length: 0

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<151>[0]UnRegOK

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::<159>fu:0:0e4dd, 4.71 1

Wed Jan 08 2014 14:43:17 GMT+0100 (CET)::10.0.0.84::<159>first_rule is .

Wed Jan 08 2014 14:43:17 GMT+0100 (CET)::10.0.0.84::<159>first type is 0.

Wed Jan 08 2014 14:43:17 GMT+0100 (CET)::10.0.0.84::<159>fu:0:0e5bd, 5.1.10 5.1.12 5.1.14 5.1.19 6.8 7.13 1

Wed Jan 08 2014 14:43:50 GMT+0100 (CET)::10.0.0.86::<143>          gateway ip:          10.0.0.254

Wed Jan 08 2014 14:43:50 GMT+0100 (CET)::10.0.0.86::<134>SCCPTCP:ERR:Can't bind to a000056:5060 errno=49

Wed Jan 08 2014 14:43:50 GMT+0100 (CET)::10.0.0.86::<134>SCCPTCP:ERR:Can't bind to a000056:5060 errno=49

Wed Jan 08 2014 14:43:50 GMT+0100 (CET)::10.0.0.86::<159>IDBG: LS, 270-4d8

Wed Jan 08 2014 14:43:50 GMT+0100 (CET)::10.0.0.86::<159>IDBG: SOK

Wed Jan 08 2014 14:43:50 GMT+0100 (CET)::10.0.0.86::<159>IDBG: st-0

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<151>Resolving 10.0.0.254

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86:::          save param: 547 = 0 548

save param: 548 = 0 548

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<159>[BKpic]Loading text background image

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<159>[BKpic]Loading text background image

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0]Reg Addr Change(0) 0:0->a0000fe:5060

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0]Reg Addr Change(0) 0:0->a0000fe:5060

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{464)

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{464)

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::REGISTER sip:10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-2ebe91f

From: "203" <203>;tag=615ea9a8a1aa604fo0

To: "203" <203>

Call-ID: 9b036c0b-8d8441b@10.0.0.86

CSeq: 52047 REGISTER

Max-Forwards: 70

Contact: "203" <203>;expires=3600

User-Agent: Cisco/SPA501G-7.5.5

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE, MESSAGE

Supported: replaces

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-2ebe91f

From: "203" <203>;tag=615ea9a8a1aa604fo0

To: "203" <203>;tag=32119

Call-ID: 9b036c0b-8d8441b@10.0.0.86

CSeq: 52047 REGISTER

WWW-Authenticate: Digest realm="abcd.be", nonce="653f2401e5a8ba0cd4e704df6a8cf22a", opaque="9a3f97ffe4bce0827f2f601184ba1a9e"

Server: Draytek Mars 1.0.0

Content-Length: 0

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{690)

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{690)

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::REGISTER sip:10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-a7107734

From: "203" <203>;tag=615ea9a8a1aa604fo0

To: "203" <203>

Call-ID: 9b036c0b-8d8441b@10.0.0.86

CSeq: 52048 REGISTER

Max-Forwards: 70

Authorization: Digest username="203",realm="abcd.be",nonce="653f2401e5a8ba0cd4e704df6a8cf22a",uri="sip:10.0.0.254",algorithm=MD5,response="379b077565f5c8c1f5fed96794fa76dc",opaque="9a3f97ffe4bce0827f2f601184ba1a9e"

Contact: "203" <203>;expires=3600

User-Agent: Cisco/SPA501G-7.5.5

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE, MESSAGE

Supported: replaces

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-a7107734

From: "203" <203>;tag=615ea9a8a1aa604fo0

To: "203" <203>;tag=5365

Call-ID: 9b036c0b-8d8441b@10.0.0.86

CSeq: 52048 REGISTER

Contact: "203" <203>;expires=300

Server: Draytek Mars 1.0.0

Content-Length: 0

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<151>[0]RegOK. NextReg in 299 (1)

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<159>[BKpic]Download picture from http://10.0.0.1/cisco/spa/rivagelogo.bmp

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<159>[create_tcp_netstrm1] use async to create tcp connection

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<159>connect succeed

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<159>[create_tcp_netstrm1] connect SUCCEED

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>content len (hdr) =990

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<134>content len (hdr) =990

Wed Jan 08 2014 14:43:51 GMT+0100 (CET)::10.0.0.86::<159>[BKpic]Same URL, not load

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{416)

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{416)

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::SUBSCRIBE sip:394@10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-6fd93061

From: "203" <203>;tag=99757e316935ed60

To: <394>

Call-ID: 190a1745-a5ea075c@10.0.0.86

CSeq: 4154 SUBSCRIBE

Max-Forwards: 70

Contact: "203" <203>

Accept: application/dialog-info+xml

Expires: 1800

Event: dialog

User-Agent: Cisco/SPA501G-7.5.5

Content-Length: 0

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::NOTIFY sip:203@10.0.0.86:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-opA-18459;rport

From: <394>;tag=26261

To: "203" <203>;tag=99757e316935ed60

Call-ID: 190a1745-a5ea075c@10.0.0.86

CSeq: 114799 NOTIFY

Subscription-State: terminated;reason=noresource

Max-Forwards: 70

User-Agent: Draytek Mars 1.0.0

Event: dialog

Content-Type: application/dialog-info+xml

Content-Length:  207

terminated

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{280)

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{280)

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::SIP/2.0 200 OK

To: "203" <203>;tag=99757e316935ed60

From: <394>;tag=26261

Call-ID: 190a1745-a5ea075c@10.0.0.86

CSeq: 114799 NOTIFY

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-opA-18459

Server: Cisco/SPA501G-7.5.5

Content-Length: 0

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-6fd93061

From: "203" <203>;tag=99757e316935ed60

To: <394>;tag=26261

Call-ID: 190a1745-a5ea075c@10.0.0.86

CSeq: 4154 SUBSCRIBE

Contact: <394>

Server: Draytek Mars 1.0.0

Event: dialog

Expires: 1800

Content-Length: 0

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:52 GMT+0100 (CET)::10.0.0.86::<151>[0]SubOK. NextSub in 1799 (1)

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fs:052982:053230:262144

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fls:fafuffffff:9:5907:61200

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fbr:0:3000:3000:0df50:0034:0033:7.5.5

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:01:0:002e:upg:app:2:7.5.2b

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:02:0:002f:upg:app:0:7.5.3

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:03:0:0030:upg:app:1:7.5.3

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:04:0:0031:upg:app:2:7.5.3

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:05:0:0032:upg:app:0:7.5.2b

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:06:0:0033:upg:app:1:7.5.2b

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:07:0:0034:upg:app:2:7.5.2b

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:08:0:0035:upg:app:0:7.5.3

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:09:0:0036:upg:app:1:7.5.3

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:0a:0:0037:upg:app:2:7.5.3

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:0b:0:0038:upg:app:0:7.5.4

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:0c:0:0039:upg:app:1:7.5.4

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:0d:0:003a:upg:app:2:7.5.4

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:0e:0:003b:upg:app:0:7.5.5

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:0f:0:003c:upg:app:1:7.5.5

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fhs:10:0:003d:upg:app:2:7.5.5

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>dhcp opt 66: ""

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>dhcp opt 160: ""

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>dhcp opt 159: ""

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>dhcp opt 150: ""

Wed Jan 08 2014 14:43:54 GMT+0100 (CET)::10.0.0.86::<159>fu:0:0df8a, 5.1.1 1

Wed Jan 08 2014 14:43:57 GMT+0100 (CET)::10.0.0.84::<143>          gateway ip:          10.0.0.254

Wed Jan 08 2014 14:43:57 GMT+0100 (CET)::10.0.0.84::<134>SCCPTCP:ERR:Can't bind to a000054:5060 errno=49

Wed Jan 08 2014 14:43:57 GMT+0100 (CET)::10.0.0.84::<134>SCCPTCP:ERR:Can't bind to a000054:5060 errno=49

Wed Jan 08 2014 14:43:57 GMT+0100 (CET)::10.0.0.84::<159>IDBG: LS, 270-4d8

Wed Jan 08 2014 14:43:57 GMT+0100 (CET)::10.0.0.84::<159>IDBG: SOK

Wed Jan 08 2014 14:43:57 GMT+0100 (CET)::10.0.0.84::<159>IDBG: st-0

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<151>Resolving 10.0.0.254

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84:::          save param: 547 = 0 548

save param: 548 = 0 548

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<159>[BKpic]Loading text background image

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<159>[BKpic]Loading text background image

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Idle key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Idle key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Idle key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Idle key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Progressing key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Progressing key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Progressing key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Progressing key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Releasing key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Releasing key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Releasing key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Releasing key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Hold key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Hold key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Hold key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Hold key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Shared Active key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Shared Active key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Shared Active key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Shared Active key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Shared Held key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkcfwd in Shared Held key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Shared Held key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<3>PGSK: chkdnd in Shared Held key list is not defined

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0]Reg Addr Change(0) 0:0->a0000fe:5060

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0]Reg Addr Change(0) 0:0->a0000fe:5060

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{457)

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{457)

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::REGISTER sip:10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-c806008a

From: "394" <394>;tag=b88026a68ed7d74eo0

To: "394" <394>

Call-ID: 8a1cfb96-149f81d8@10.0.0.84

CSeq: 57487 REGISTER

Max-Forwards: 70

Contact: "394" <394>;expires=3600

User-Agent: Cisco/SPA504G-7.5.5

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE

Supported: replaces

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-c806008a

From: "394" <394>;tag=b88026a68ed7d74eo0

To: "394" <394>;tag=4279

Call-ID: 8a1cfb96-149f81d8@10.0.0.84

CSeq: 57487 REGISTER

WWW-Authenticate: Digest realm="abcd.be", nonce="6f573885be97de82f39b5b12259e2184", opaque="fa2577ba9c59cb03c7ea95278b958ced"

Server: Draytek Mars 1.0.0

Content-Length: 0

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{682)

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{682)

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::REGISTER sip:10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-76ceae0e

From: "394" <394>;tag=b88026a68ed7d74eo0

To: "394" <394>

Call-ID: 8a1cfb96-149f81d8@10.0.0.84

CSeq: 57488 REGISTER

Max-Forwards: 70

Authorization: Digest username="394",realm="abcd.be",nonce="6f573885be97de82f39b5b12259e2184",uri="sip:10.0.0.254",algorithm=MD5,response="86819b55dfd0c4b511fb9142977d13df",opaque="fa2577ba9c59cb03c7ea95278b958ced"

Contact: "394" <394>;expires=3600

User-Agent: Cisco/SPA504G-7.5.5

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE

Supported: replaces

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-76ceae0e

From: "394" <394>;tag=b88026a68ed7d74eo0

To: "394" <394>;tag=7836

Call-ID: 8a1cfb96-149f81d8@10.0.0.84

CSeq: 57488 REGISTER

Contact: "394" <394>;expires=300

Server: Draytek Mars 1.0.0

Content-Length: 0

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<151>[0]RegOK. NextReg in 299 (1)

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::NOTIFY sip:203@10.0.0.86:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-gta-27899;rport

From: <394>;tag=26261

To: "203" <203>;tag=99757e316935ed60

Call-ID: 190a1745-a5ea075c@10.0.0.86

CSeq: 114801 NOTIFY

Subscription-State: active

Max-Forwards: 70

User-Agent: Draytek Mars 1.0.0

Event: dialog

Content-Type: application/dialog-info+xml

Content-Length:  207

terminated

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{313)

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{313)

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::SIP/2.0 481 Call Leg/Transaction Does Not Exist

To: "203" <203>;tag=99757e316935ed60

From: <394>;tag=26261

Call-ID: 190a1745-a5ea075c@10.0.0.86

CSeq: 114801 NOTIFY

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-gta-27899

Server: Cisco/SPA501G-7.5.5

Content-Length: 0

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<159>[BKpic]Download picture from http://10.0.0.1/cisco/spa/rivagelogo.bmp

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<159>[create_tcp_netstrm1] use async to create tcp connection

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<159>connect succeed

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<159>[create_tcp_netstrm1] connect SUCCEED

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>content len (hdr) =990

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<134>content len (hdr) =990

Wed Jan 08 2014 14:43:58 GMT+0100 (CET)::10.0.0.84::<159>[BKpic]Same URL, not load

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{417)

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{417)

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::SUBSCRIBE sip:203@10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-d5a05538

From: "394" <394>;tag=8fef9fdc6738bf96

To: <203>

Call-ID: c0c1e1d4-bc5ea5fe@10.0.0.84

CSeq: 46861 SUBSCRIBE

Max-Forwards: 70

Contact: "394" <394>

Accept: application/dialog-info+xml

Expires: 1800

Event: dialog

User-Agent: Cisco/SPA504G-7.5.5

Content-Length: 0

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::NOTIFY sip:394@10.0.0.84:5060 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-CeF-5520;rport

From: <203>;tag=15527

To: "394" <394>;tag=8fef9fdc6738bf96

Call-ID: c0c1e1d4-bc5ea5fe@10.0.0.84

CSeq: 114802 NOTIFY

Subscription-State: active

Max-Forwards: 70

User-Agent: Draytek Mars 1.0.0

Event: dialog

Content-Type: application/dialog-info+xml

Content-Length:  207

terminated

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{279)

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{279)

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::SIP/2.0 200 OK

To: "394" <394>;tag=8fef9fdc6738bf96

From: <203>;tag=15527

Call-ID: c0c1e1d4-bc5ea5fe@10.0.0.84

CSeq: 114802 NOTIFY

Via: SIP/2.0/UDP 10.0.0.254:5060;branch=z9hG4bK-CeF-5520

Server: Cisco/SPA504G-7.5.5

Content-Length: 0

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-d5a05538

From: "394" <394>;tag=8fef9fdc6738bf96

To: <203>;tag=15527

Call-ID: c0c1e1d4-bc5ea5fe@10.0.0.84

CSeq: 46861 SUBSCRIBE

Contact: <203>

Server: Draytek Mars 1.0.0

Event: dialog

Expires: 1800

Content-Length: 0

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::<151>[0]SubOK. NextSub in 1799 (1)

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fs:048239:048378:262144

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fls:fuafffffff:2:150:58192

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fbr:0:3000:3000:0e5bd:0016:0015:7.5.5

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:01:0:0001:upg:app:M:7.4.9c

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:02:0:0002:upg:app:0:7.5.2b

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:03:0:0003:upg:app:1:7.5.2b

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:04:0:0004:upg:app:2:7.5.2b

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:05:0:0005:upg:app:0:7.5.4

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:06:0:0006:upg:app:1:7.5.4

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:07:0:0007:upg:app:2:7.5.4

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:08:0:0008:upg:app:0:7.5.5

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:09:0:0009:upg:app:1:7.5.5

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fhs:0a:0:000a:upg:app:2:7.5.5

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>dhcp opt 66: ""

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>dhcp opt 160: ""

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>dhcp opt 159: ""

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>dhcp opt 150: ""

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<134>resync rule:  /spa504G.cfg

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<134>resync rule:  /spa504G.cfg

Wed Jan 08 2014 14:44:01 GMT+0100 (CET)::10.0.0.84::<159>fu:0:0e5f7, 5.1.1 1

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{690)

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]->10.0.0.254:5060{690)

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::REGISTER sip:10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-8edab108

From: "203" <203>;tag=615ea9a8a1aa604fo0

To: "203" <203>

Call-ID: 9b036c0b-8d8441b@10.0.0.86

CSeq: 52049 REGISTER

Max-Forwards: 70

Authorization: Digest username="203",realm="abcd.be",nonce="653f2401e5a8ba0cd4e704df6a8cf22a",uri="sip:10.0.0.254",algorithm=MD5,response="379b077565f5c8c1f5fed96794fa76dc",opaque="9a3f97ffe4bce0827f2f601184ba1a9e"

Contact: "203" <203>;expires=3600

User-Agent: Cisco/SPA501G-7.5.5

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE, MESSAGE

Supported: replaces

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.86:5060;branch=z9hG4bK-8edab108

From: "203" <203>;tag=615ea9a8a1aa604fo0

To: "203" <203>;tag=8261

Call-ID: 9b036c0b-8d8441b@10.0.0.86

CSeq: 52049 REGISTER

Contact: "203" <203>;expires=300

Server: Draytek Mars 1.0.0

Content-Length: 0

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::<134>

Wed Jan 08 2014 14:47:45 GMT+0100 (CET)::10.0.0.86::<151>[0]RegOK. NextReg in 299 (1)

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{682)

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]->10.0.0.254:5060{682)

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::REGISTER sip:10.0.0.254 SIP/2.0

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-801f790d

From: "394" <394>;tag=b88026a68ed7d74eo0

To: "394" <394>

Call-ID: 8a1cfb96-149f81d8@10.0.0.84

CSeq: 57489 REGISTER

Max-Forwards: 70

Authorization: Digest username="394",realm="abcd.be",nonce="6f573885be97de82f39b5b12259e2184",uri="sip:10.0.0.254",algorithm=MD5,response="86819b55dfd0c4b511fb9142977d13df",opaque="fa2577ba9c59cb03c7ea95278b958ced"

Contact: "394" <394>;expires=3600

User-Agent: Cisco/SPA504G-7.5.5

Content-Length: 0

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE

Supported: replaces

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::<134>[0:5060]<<10.0.0.254:5060

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::SIP/2.0 200 OK

Via: SIP/2.0/UDP 10.0.0.84:5060;branch=z9hG4bK-801f790d

From: "394" <394>;tag=b88026a68ed7d74eo0

To: "394" <394>;tag=13339

Call-ID: 8a1cfb96-149f81d8@10.0.0.84

CSeq: 57489 REGISTER

Contact: "394" <394>;expires=300

Server: Draytek Mars 1.0.0

Content-Length: 0

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::<134>

Wed Jan 08 2014 14:47:52 GMT+0100 (CET)::10.0.0.84::<151>[0]RegOK. NextReg in 299 (1)

Problem is on Draytek side ... it treat code 20 (subscriber absent) the same way as code 1 (unassigned number).

If someone from cisco and draytek "engineer" can confirm that

"Problem" is not the same as "bug". It may be intended behavior. It's questionable what status should be reported in the case the extension is not reachable. It is case-by-case decision.

cisco spa don't SUBSCRIBE if "noressource" ?

It subscribe. But such subscription is terminated immediately by Draytek. Client can do not so much with it.

I know now too why i must "hard reboot like power off power on".

When i reboot the phone by http://10.0.0.86/admin/reboot   ..the registration of the extension is "directly removed" from draytek.

if i hard reboot like "power off, power on", the registration of the extension still in "draytek"..because registration time of 300s.

It's expected behavior. Phone should unregister whenever possible before before it go offline. PBX can recognize missing phone by timeout, but it's so late.

I hope cisco and draytek can find a solution for me.

According your log:

Wed Jan 08 2014 14:43:16 GMT+0100 (CET)::10.0.0.84::SUBSCRIBE
...
Wed Jan 08 2014 14:43:59 GMT+0100 (CET)::10.0.0.84::SUBSCRIBE

... the Cisco will try to subscribe periodically. Not sure what solution you are wishing for. If you want the Draytek not to terminate subscription despite the extension unavaiable, then ask Draytek. I'm not sure how the Cisco can help you ...

View solution in original post