Hello list,

I cannot get the presence module to work. It seems like the pa module does not like Windows Messenger 5 SUBSCRIBE requests. ser is 0.8.14. Any help???

Cheers Morten

The SIP dump looks as follows:

U 192.168.1.10:33831 -> 192.168.1.1:5060 SUBSCRIBE sip:usera@mck.li SIP/2.0..Via: SIP/2.0/UDP 192.168.1.20:8148..From: "Morten.Kuehl" sip:userb@mck.li;tag=60111e38-e275-4e2d-8c30-9e0c25850673..To: <si p:usera@mck.li>..Call-ID: 5158ee48-5662-4a93-9994-11ed9d24e70f@192.168.1.20..CSeq: 1 SUBSCRIBE..Contact: sip:192.168.1.20:8148..User-Agent: Windows RTC/1.0..Expires: 1 800..Content-Length: 0....

U 192.168.1.1:5060 -> 192.168.1.10:8148 SIP/2.0 400 Bad Request..Via: SIP/2.0/UDP 192.168.1.20:8148;received=192.168.1.10..From: "Morten.Kuehl" sip:userb@mck.li;tag=60111e38-e275-4e2d-8c30-9e0c25850673..To: sip:usera@mck.li;tag=0889e34f0f1bf5e510f3e8bae6892393-bfb5..Call-ID: 5158ee48-5662-4a93-9994-11ed9d24e70f@192.168.1.20..CSeq: 1 SUBSCRIBE..Error while parsing headersS erver: Sip EXpress router (0.8.14 (i386/linux))..Content-Length: 0..Warning: 392 192.168.1.1:5060 "Noisy feedback tells: pid=6118 req_src_ip=192.168.1.10 req_src_port=33831 in_uri=sip:usera@mck.li out_uri=sip:usera@mck.li via_cnt==1"....

Debug from ser looks like this:

Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: SIP Request: Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: method: <SUBSCRIBE> Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: uri: sip:usera@mck.li Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: version: <SIP/2.0> Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_headers: flags=1 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: end of header reached, state=5 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_headers: Via found, flags=1 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_headers: this is the first via Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: After parse_msg... Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: preparing to run routing scripts... Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG : is_maxfwd_present: searching for max_forwards header Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_headers: flags=128 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: end of header reached, state=9 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: get_hdr_field: <To> [29]; uri=[sip:usera@mck.li] Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: to body [sip:usera@mck.li^M ] Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: get_hdr_field: cseq <CSeq>: <2> <SUBSCRIBE> Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: get_hdr_body : content_length=0 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: found end of header Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: is_maxfwd_present: max_forwards header not found! Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: add_param: tag=60eb5347-331a-4548-9761-e5ca8818e699 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: end of header reached, state=29 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_headers: flags=256 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: find_first_route(): No Route headers found Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: loose_route(): There is no Route HF Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: check_self - checking if host==us: 7==15 && [whu.edu] == [192.168.1.1] Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: check_self - checking if port 5060 matches port 5060 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: Subscribe Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: t_addifnew: msg id=3 , global msg id=2 , T on entrance=0xffffffff Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_headers: flags=-1 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_headers: flags=60 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: t_lookup_request: start searching: hash=18509, isACK=0 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: proceeding to pre-RFC3261 transaction matching Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: t_lookup_request: no transaction found Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: Registrar Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_headers: flags=1581064 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_hfs(): Error while parsing headers Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: handle_subscription(): Error while parsing message header Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: t_check: msg id=3 global id=3 T start=0x402d1d28 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: t_check: T alredy found! Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: parse_headers: flags=-1 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: WARNING:vqm_resize: resize(0) called Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: cleanup_uacs: RETR/FR timers reset Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: add_to_tail_of_timer[2]: 0x402d1d70 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: reply sent out. buf=0x80dd610: SIP/2.0 4..., shmem=0x402d32b0: SIP/2.0 4 Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG: t_reply: finished Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: DEBUG:destroy_avp_list: destroing list (nil) Sep 25 16:00:39 sip01 /usr/sbin/ser[6117]: receive_msg: cleaning up

ser.cfg part looks like this:

if (method=="SUBSCRIBE") { log(1, "Subscribe\n"); if (t_newtran()) { log(1, "Registrar\n"); handle_subscription("registrar"); }; break;

};