SIP notify before subscribe 200OK with chan_pjsip.so

Hello everybody,

I would like to know if someone is facing the same issue as me :

during subscribtion refresh process I am receiving from asterisk a notify before the subscribe 200OK.

here is the flow :

May 15 17:04:21.561 UAC [192.168.1.100:5060] -> UAS [192.168.1.10:5060] REGISTER call-id:OA5112F6FE04447720032B8A8481@100.SIP.UDP (UDP)
May 15 17:04:21.565 UAC [192.168.1.100:5060] <- UAS [192.168.1.10:5060] REGISTER 401 Unauthorized call-id:OA5112F6FE04447720032B8A8481@100.SIP.UDP (UDP)
May 15 17:04:21.582 UAC [192.168.1.100:5060] -> UAS [192.168.1.10:5060] REGISTER call-id:OA5112F6FE04447720032B8A8481@100.SIP.UDP (UDP)
May 15 17:04:21.696 UAC [192.168.1.100:5060] <- UAS [192.168.1.10:5060] REGISTER 200 OK call-id:OA5112F6FE04447720032B8A8481@100.SIP.UDP (UDP)
May 15 17:04:21.713 UAC [192.168.1.100:5060] -> UAS [192.168.1.10:5060] SUBSCRIBE call-id:OA3029344E0444772003565BC8E8@100.SIP.UDP (UDP)
May 15 17:04:21.717 UAC [192.168.1.100:5060] <- UAS [192.168.1.10:5060] SUBSCRIBE 401 Unauthorized call-id:OA3029344E0444772003565BC8E8@100.SIP.UDP (UDP)
May 15 17:04:21.734 UAC [192.168.1.100:5060] -> UAS [192.168.1.10:5060] SUBSCRIBE call-id:OA211887B804447720034060415E@100.SIP.UDP (UDP)
May 15 17:04:21.739 UAC [192.168.1.100:5060] <- UAS [192.168.1.10:5060] SUBSCRIBE 200 OK call-id:OA211887B804447720034060415E@100.SIP.UDP (UDP)
May 15 17:04:21.741 UAC [192.168.1.100:5060] <- UAS [192.168.1.10:5060] NOTIFY call-id:OA211887B804447720034060415E@100.SIP.UDP (UDP)
May 15 17:04:21.756 UAC [192.168.1.100:5060] -> UAS [192.168.1.10:5060] NOTIFY 200 OK call-id:OA211887B804447720034060415E@100.SIP.UDP (UDP)

May 15 17:04:44.418 UAC [192.168.1.100:5060] -> UAS [192.168.1.10:5060] REGISTER call-id:OA5112F6FE04447720032B8A8481@100.SIP.UDP (UDP)
May 15 17:04:44.422 UAC [192.168.1.100:5060] <- UAS [192.168.1.10:5060] REGISTER 401 Unauthorized call-id:OA5112F6FE04447720032B8A8481@100.SIP.UDP (UDP)
May 15 17:04:44.439 UAC [192.168.1.100:5060] -> UAS [192.168.1.10:5060] REGISTER call-id:OA5112F6FE04447720032B8A8481@100.SIP.UDP (UDP)
May 15 17:04:44.546 UAC [192.168.1.100:5060] <- UAS [192.168.1.10:5060] REGISTER 200 OK call-id:OA5112F6FE04447720032B8A8481@100.SIP.UDP (UDP)
May 15 17:04:44.562 UAC [192.168.1.100:5060] -> UAS [192.168.1.10:5060] SUBSCRIBE call-id:OA211887B804447720034060415E@100.SIP.UDP (UDP)
May 15 17:04:44.567 UAC [192.168.1.100:5060] <- UAS [192.168.1.10:5060] NOTIFY call-id:OA211887B804447720034060415E@100.SIP.UDP (UDP)
May 15 17:04:44.568 UAC [192.168.1.100:5060] <- UAS [192.168.1.10:5060] SUBSCRIBE 200 OK call-id:OA211887B804447720034060415E@100.SIP.UDP (UDP)

And here is the Asterisk SIP debug :

[2014-05-15 17:03:46.982] codec_g722.so => (ITU G.722-64kbps G722 Transcoder)
[2014-05-15 17:03:46.982] Loading func_volume.so.
[2014-05-15 17:03:46.983] func_volume.so => (Technology independent volume control)
[2014-05-15 17:03:46.983] Loading app_waitforring.so.
[2014-05-15 17:03:46.983] app_waitforring.so => (Waits until first ring after time)
[2014-05-15 17:03:46.983] Loading app_image.so.
[2014-05-15 17:03:46.984] app_image.so => (Image Transmission Application)
[2014-05-15 17:03:46.986] Loading app_system.so.
[2014-05-15 17:03:46.988] app_system.so => (Generic System() application)
[2014-05-15 17:03:46.988] Loading app_exec.so.
[2014-05-15 17:03:46.990] app_exec.so => (Executes dialplan applications)
[2014-05-15 17:03:46.993] Loading app_amd.so.
[2014-05-15 17:03:46.995] app_amd.so => (Answering Machine Detection Application)
[2014-05-15 17:03:46.995] Loading app_bridgewait.so.
[2014-05-15 17:03:46.995] app_bridgewait.so => (Place the channel into a holding bridge application)
[2014-05-15 17:03:46.995] Loading bridge_softmix.so.
[2014-05-15 17:03:46.995] bridge_softmix.so => (Multi-party software based channel mixing)
[2014-05-15 17:03:46.995] Loading codec_g726.so.
[2014-05-15 17:03:47.017] codec_g726.so => (ITU G.726-32kbps G726 Transcoder)
[2014-05-15 17:03:47.017] Loading bridge_builtin_interval_features.so.
[2014-05-15 17:03:47.017] bridge_builtin_interval_features.so => (Built in bridging interval features)
[2014-05-15 17:03:47.017] Loading func_global.so.
[2014-05-15 17:03:47.018] func_global.so => (Variable dialplan functions)
[2014-05-15 17:03:47.018] Loading func_iconv.so.
[2014-05-15 17:03:47.019] func_iconv.so => (Charset conversions)
[2014-05-15 17:03:47.019] Loading func_enum.so.
[2014-05-15 17:03:47.021] func_enum.so => (ENUM related dialplan functions)
[2014-05-15 17:03:47.021] Loading app_waitforsilence.so.
[2014-05-15 17:03:47.023] app_waitforsilence.so => (Wait For Silence)
[2014-05-15 17:03:47.023] Loading app_talkdetect.so.
[2014-05-15 17:03:47.023] app_talkdetect.so => (Playback with Talk Detection)
[2014-05-15 17:03:47.027] Loading func_sha1.so.
[2014-05-15 17:03:47.027] func_sha1.so => (SHA-1 computation dialplan function)
[2014-05-15 17:03:47.028] Loading app_originate.so.
[2014-05-15 17:03:47.028] app_originate.so => (Originate call)
[2014-05-15 17:03:47.028] Loading func_lock.so.
[2014-05-15 17:03:47.030] func_lock.so => (Dialplan mutexes)
[2014-05-15 17:03:47.031] Loading func_groupcount.so.
[2014-05-15 17:03:47.038] func_groupcount.so => (Channel group dialplan functions)
[2014-05-15 17:03:47.038] Loading codec_ulaw.so.
[2014-05-15 17:03:47.040] codec_ulaw.so => (mu-Law Coder/Decoder)
[2014-05-15 17:03:47.040] Loading app_nbscat.so.
[2014-05-15 17:03:47.041] app_nbscat.so => (Silly NBS Stream Application)
[2014-05-15 17:03:47.041] Loading app_playtones.so.
[2014-05-15 17:03:47.042] app_playtones.so => (Playtones Application)
[2014-05-15 17:03:47.045] Loading app_dial.so.
[2014-05-15 17:03:47.051] app_dial.so => (Dialing Application)
[2014-05-15 17:03:47.057] Loading chan_oss.so.
[2014-05-15 17:03:47.058] chan_oss.so => (OSS Console Channel Driver)
[2014-05-15 17:03:47.058] Loading func_uri.so.
[2014-05-15 17:03:47.059] func_uri.so => (URI encode/decode dialplan functions)
[2014-05-15 17:03:47.059] Loading codec_ilbc.so.
[2014-05-15 17:03:47.089] codec_ilbc.so => (iLBC Coder/Decoder)
[2014-05-15 17:03:47.089] Loading app_while.so.
[2014-05-15 17:03:47.091] app_while.so => (While Loops and Conditional Execution)
[2014-05-15 17:03:47.097] Loading app_milliwatt.so.
[2014-05-15 17:03:47.098] app_milliwatt.so => (Digital Milliwatt (mu-law) Test Application)
[2014-05-15 17:03:47.098] Loading func_realtime.so.
[2014-05-15 17:03:47.101] func_realtime.so => (Read/Write/Store/Destroy values from a RealTime repository)
[2014-05-15 17:03:47.102] Loading func_env.so.
[2014-05-15 17:03:47.105] func_env.so => (Environment/filesystem dialplan functions)
[2014-05-15 17:03:47.107] Loading app_queue.so.
[2014-05-15 17:03:47.138] app_queue.so => (True Call Queueing)
[2014-05-15 17:03:47.139] Asterisk Ready.
linux92CLI>
linux92
CLI> pjsip set logger on
PJSIP Logging enabled
linux92CLI>
linux92
CLI>
linux92CLI>
[2014-05-15 17:04:21.564] <— Received SIP request (820 bytes) from UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.564] REGISTER sip:192.168.1.10 SIP/2.0
[2014-05-15 17:04:21.564] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8
[2014-05-15 17:04:21.564] Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,MESSAGE,OPTIONS,INFO,SUBSCRIBE
[2014-05-15 17:04:21.564] Allow-Events: presence,kpml
[2014-05-15 17:04:21.564] Call-ID: OA5112F6FE04447720032B8A8481@100.SIP.UDP
[2014-05-15 17:04:21.564] Contact: sip:0444772003@192.168.1.100:5060;transport=udp;user=phone;methods=“INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER”
[2014-05-15 17:04:21.564] CSeq: 26 REGISTER
[2014-05-15 17:04:21.564] Expires: 3600
[2014-05-15 17:04:21.564] From: sip:0444772003@192.168.1.100:5060;user=phone;tag=175F
[2014-05-15 17:04:21.564] Max-Forwards: 69
[2014-05-15 17:04:21.564] Supported: replaces,norefersub,extended-refer,X-cisco-serviceuri
[2014-05-15 17:04:21.564] To: sip:0444772003@192.168.1.10;user=phone
[2014-05-15 17:04:21.564] User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.3.1.1561
[2014-05-15 17:04:21.564] Via: SIP/2.0/UDP 192.168.1.100:5060;branch=z9hG4bK-52AE-E,SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-6
[2014-05-15 17:04:21.564] Content-Length: 0
[2014-05-15 17:04:21.564]
[2014-05-15 17:04:21.564]
[2014-05-15 17:04:21.565] <— Transmitting SIP response (541 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.565] SIP/2.0 401 Unauthorized
[2014-05-15 17:04:21.565] Via: SIP/2.0/UDP 192.168.1.100:5060;rport;received=192.168.1.100;branch=z9hG4bK-52AE-E
[2014-05-15 17:04:21.565] Via: SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-6
[2014-05-15 17:04:21.565] Call-ID: OA5112F6FE04447720032B8A8481@100.SIP.UDP
[2014-05-15 17:04:21.565] From: sip:0444772003@192.168.1.100;user=phone;tag=175F
[2014-05-15 17:04:21.565] To: sip:0444772003@192.168.1.10;user=phone;tag=z9hG4bK-52AE-E
[2014-05-15 17:04:21.565] CSeq: 26 REGISTER
[2014-05-15 17:04:21.565] WWW-Authenticate: Digest realm=“XXXX”,nonce=“1400166261/9ece0429aa5fed9d072659569b0a90da”,opaque=“2bfb2791472d1f58”,algorithm=md5,qop=“auth”
[2014-05-15 17:04:21.565] Content-Length: 0
[2014-05-15 17:04:21.565]
[2014-05-15 17:04:21.565]
[2014-05-15 17:04:21.585] <— Received SIP request (1088 bytes) from UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.585] REGISTER sip:192.168.1.10 SIP/2.0
[2014-05-15 17:04:21.585] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8
[2014-05-15 17:04:21.585] Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,MESSAGE,OPTIONS,INFO,SUBSCRIBE
[2014-05-15 17:04:21.585] Allow-Events: presence,kpml
[2014-05-15 17:04:21.585] Authorization: Digest username=“0444772003”,realm=“XXXX”,nonce=“1400166261/9ece0429aa5fed9d072659569b0a90da”,uri=“sip:93.20.94.1:5060”,response=“2d8dfa252965f9f0ba8312fb98aab28b”,algorithm=MD5,cnonce=“6b8b4567”,opaque=“2bfb2791472d1f58”,qop=auth,nc=00000001
[2014-05-15 17:04:21.585] Call-ID: OA5112F6FE04447720032B8A8481@100.SIP.UDP
[2014-05-15 17:04:21.585] Contact: sip:0444772003@192.168.1.100:5060;transport=udp;user=phone;methods=“INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER”
[2014-05-15 17:04:21.585] CSeq: 28 REGISTER
[2014-05-15 17:04:21.585] Expires: 3600
[2014-05-15 17:04:21.585] From: sip:0444772003@192.168.1.100:5060;user=phone;tag=175F
[2014-05-15 17:04:21.585] Max-Forwards: 69
[2014-05-15 17:04:21.585] Supported: replaces,norefersub,extended-refer,X-cisco-serviceuri
[2014-05-15 17:04:21.585] To: sip:0444772003@192.168.1.10;user=phone
[2014-05-15 17:04:21.585] User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.3.1.1561
[2014-05-15 17:04:21.585] Via: SIP/2.0/UDP 192.168.1.100:5060;branch=z9hG4bK-5BB7-F,SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-24
[2014-05-15 17:04:21.585] Content-Length: 0
[2014-05-15 17:04:21.585]
[2014-05-15 17:04:21.585]
[2014-05-15 17:04:21.695] <— Transmitting SIP response (502 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.695] SIP/2.0 200 OK
[2014-05-15 17:04:21.695] Via: SIP/2.0/UDP 192.168.1.100:5060;rport;received=192.168.1.100;branch=z9hG4bK-5BB7-F
[2014-05-15 17:04:21.695] Via: SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-24
[2014-05-15 17:04:21.695] Call-ID: OA5112F6FE04447720032B8A8481@100.SIP.UDP
[2014-05-15 17:04:21.695] From: sip:0444772003@192.168.1.100;user=phone;tag=175F
[2014-05-15 17:04:21.695] To: sip:0444772003@192.168.1.10;user=phone;tag=z9hG4bK-5BB7-F
[2014-05-15 17:04:21.695] CSeq: 28 REGISTER
[2014-05-15 17:04:21.695] Date: Thu, 15 May 2014 15:04:21 GMT
[2014-05-15 17:04:21.695] Contact: sip:0444772003@192.168.1.100:5060;user=phone;transport=udp;expires=3599
[2014-05-15 17:04:21.695] Content-Length: 0
[2014-05-15 17:04:21.695]
[2014-05-15 17:04:21.695]
[2014-05-15 17:04:21.716] <— Received SIP request (795 bytes) from UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.716] SUBSCRIBE sip:multiblfs@192.168.1.10 SIP/2.0
[2014-05-15 17:04:21.716] Accept: application/pidf+xml,application/xpidf+xml
[2014-05-15 17:04:21.716] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8
[2014-05-15 17:04:21.716] Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER
[2014-05-15 17:04:21.716] Call-ID: OA3029344E0444772003565BC8E8@100.SIP.UDP
[2014-05-15 17:04:21.716] Contact: “0444772003” sip:0444772003@192.168.1.100:5060;user=phone
[2014-05-15 17:04:21.716] CSeq: 30 SUBSCRIBE
[2014-05-15 17:04:21.716] Event: presence
[2014-05-15 17:04:21.716] Expires: 3600
[2014-05-15 17:04:21.716] From: “0444772003” sip:0444772003@192.168.1.100:5060;user=phone;tag=7C59
[2014-05-15 17:04:21.716] Max-Forwards: 69
[2014-05-15 17:04:21.716] Supported: replaces,norefsub,extended-refer,X-cisco-serviceuri,eventlist
[2014-05-15 17:04:21.716] To: sip:multiblfs@192.168.1.10
[2014-05-15 17:04:21.716] User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.3.1.1561
[2014-05-15 17:04:21.716] Via: SIP/2.0/UDP 192.168.1.100:5060;branch=z9hG4bK-6AD1-10,SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-47
[2014-05-15 17:04:21.716] Content-Length: 0
[2014-05-15 17:04:21.716]
[2014-05-15 17:04:21.716]
[2014-05-15 17:04:21.717] <— Transmitting SIP response (546 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.717] SIP/2.0 401 Unauthorized
[2014-05-15 17:04:21.717] Via: SIP/2.0/UDP 192.168.1.100:5060;rport;received=192.168.1.100;branch=z9hG4bK-6AD1-10
[2014-05-15 17:04:21.717] Via: SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-47
[2014-05-15 17:04:21.717] Call-ID: OA3029344E0444772003565BC8E8@100.SIP.UDP
[2014-05-15 17:04:21.717] From: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=7C59
[2014-05-15 17:04:21.717] To: sip:multiblfs@192.168.1.10;tag=z9hG4bK-6AD1-10
[2014-05-15 17:04:21.717] CSeq: 30 SUBSCRIBE
[2014-05-15 17:04:21.717] WWW-Authenticate: Digest realm=“XXXX”,nonce=“1400166261/9ece0429aa5fed9d072659569b0a90da”,opaque=“2800e78334484d91”,algorithm=md5,qop=“auth”
[2014-05-15 17:04:21.717] Content-Length: 0
[2014-05-15 17:04:21.717]
[2014-05-15 17:04:21.717]
[2014-05-15 17:04:21.736] <— Received SIP request (1062 bytes) from UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.736] SUBSCRIBE sip:multiblfs@192.168.1.10 SIP/2.0
[2014-05-15 17:04:21.736] Accept: application/pidf+xml,application/xpidf+xml
[2014-05-15 17:04:21.736] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8
[2014-05-15 17:04:21.736] Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER
[2014-05-15 17:04:21.736] Authorization: Digest username=“0444772003”,realm=“XXXX”,nonce=“1400166261/9ece0429aa5fed9d072659569b0a90da”,uri=“sip:93.20.94.1:5060”,response=“2701fbb95744977bee909ed1b33aa8aa”,algorithm=MD5,cnonce=“327b23c6”,opaque=“2800e78334484d91”,qop=auth,nc=00000001
[2014-05-15 17:04:21.736] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:21.736] Contact: “0444772003” sip:0444772003@192.168.1.100:5060;user=phone
[2014-05-15 17:04:21.736] CSeq: 32 SUBSCRIBE
[2014-05-15 17:04:21.736] Event: presence
[2014-05-15 17:04:21.736] Expires: 3600
[2014-05-15 17:04:21.736] From: “0444772003” sip:0444772003@192.168.1.100:5060;user=phone;tag=1010
[2014-05-15 17:04:21.736] Max-Forwards: 69
[2014-05-15 17:04:21.736] Supported: replaces,norefsub,extended-refer,X-cisco-serviceuri,eventlist
[2014-05-15 17:04:21.736] To: sip:multiblfs@192.168.1.10
[2014-05-15 17:04:21.736] User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.3.1.1561
[2014-05-15 17:04:21.736] Via: SIP/2.0/UDP 192.168.1.100:5060;branch=z9hG4bK-4DCB-11,SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-72
[2014-05-15 17:04:21.736] Content-Length: 0
[2014-05-15 17:04:21.736]
[2014-05-15 17:04:21.736]
[2014-05-15 17:04:21.738] <— Transmitting SIP response (614 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.738] SIP/2.0 200 OK
[2014-05-15 17:04:21.738] Via: SIP/2.0/UDP 192.168.1.100:5060;rport;received=192.168.1.100;branch=z9hG4bK-4DCB-11
[2014-05-15 17:04:21.738] Via: SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-72
[2014-05-15 17:04:21.738] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:21.738] From: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:21.738] To: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:21.738] CSeq: 32 SUBSCRIBE
[2014-05-15 17:04:21.738] Expires: 3600
[2014-05-15 17:04:21.738] Contact: sip:192.168.1.10:5060
[2014-05-15 17:04:21.738] Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER
[2014-05-15 17:04:21.738] Supported: 100rel, timer, replaces, norefersub
[2014-05-15 17:04:21.738] Content-Length: 0
[2014-05-15 17:04:21.738]
[2014-05-15 17:04:21.738]
[2014-05-15 17:04:21.739] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.739] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:04:21.739] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjc38e4001-5253-4f09-b7be-3656afe60b84
[2014-05-15 17:04:21.739] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:21.739] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:21.739] Contact: sip:192.168.1.10:5060
[2014-05-15 17:04:21.739] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:21.739] CSeq: 27117 NOTIFY
[2014-05-15 17:04:21.739] Event: presence
[2014-05-15 17:04:21.739] Subscription-State: active;expires=3599
[2014-05-15 17:04:21.739] Allow-Events: presence, message-summary, refer
[2014-05-15 17:04:21.739] Content-Type: application/pidf+xml
[2014-05-15 17:04:21.739] Content-Length: 606
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.739] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.739] Unavailable
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.739] closed
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.739] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.739] pp:person
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.739] ep:activitiesep:away</ep:activities>
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.739] </pp:person>
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.739]
[2014-05-15 17:04:21.758] <— Received SIP response (441 bytes) from UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:21.758] SIP/2.0 200 OK
[2014-05-15 17:04:21.758] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8
[2014-05-15 17:04:21.758] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:21.758] CSeq: 27117 NOTIFY
[2014-05-15 17:04:21.758] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:21.758] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:21.758] User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.3.1.1561
[2014-05-15 17:04:21.758] Via: SIP/2.0/UDP 192.168.1.10:5060;branch=z9hG4bKPjc38e4001-5253-4f09-b7be-3656afe60b84
[2014-05-15 17:04:21.758] Content-Length: 0
[2014-05-15 17:04:21.758]
[2014-05-15 17:04:21.758]
linux92
CLI>
linux92CLI>
linux92
CLI>
[2014-05-15 17:04:44.421] <— Received SIP request (821 bytes) from UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:44.421] REGISTER sip:192.168.1.10 SIP/2.0
[2014-05-15 17:04:44.421] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8
[2014-05-15 17:04:44.421] Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,MESSAGE,OPTIONS,INFO,SUBSCRIBE
[2014-05-15 17:04:44.421] Allow-Events: presence,kpml
[2014-05-15 17:04:44.421] Call-ID: OA5112F6FE04447720032B8A8481@100.SIP.UDP
[2014-05-15 17:04:44.421] Contact: sip:0444772003@192.168.1.100:5060;transport=udp;user=phone;methods=“INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER”
[2014-05-15 17:04:44.421] CSeq: 34 REGISTER
[2014-05-15 17:04:44.421] Expires: 3600
[2014-05-15 17:04:44.421] From: sip:0444772003@192.168.1.100:5060;user=phone;tag=6A89
[2014-05-15 17:04:44.421] Max-Forwards: 69
[2014-05-15 17:04:44.421] Supported: replaces,norefersub,extended-refer,X-cisco-serviceuri
[2014-05-15 17:04:44.421] To: sip:0444772003@192.168.1.10;user=phone
[2014-05-15 17:04:44.421] User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.3.1.1561
[2014-05-15 17:04:44.421] Via: SIP/2.0/UDP 192.168.1.100:5060;branch=z9hG4bK-4F29-13,SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-6
[2014-05-15 17:04:44.421] Content-Length: 0
[2014-05-15 17:04:44.421]
[2014-05-15 17:04:44.421]
[2014-05-15 17:04:44.422] <— Transmitting SIP response (543 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:44.422] SIP/2.0 401 Unauthorized
[2014-05-15 17:04:44.422] Via: SIP/2.0/UDP 192.168.1.100:5060;rport;received=192.168.1.100;branch=z9hG4bK-4F29-13
[2014-05-15 17:04:44.422] Via: SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-6
[2014-05-15 17:04:44.422] Call-ID: OA5112F6FE04447720032B8A8481@100.SIP.UDP
[2014-05-15 17:04:44.422] From: sip:0444772003@192.168.1.100;user=phone;tag=6A89
[2014-05-15 17:04:44.422] To: sip:0444772003@192.168.1.10;user=phone;tag=z9hG4bK-4F29-13
[2014-05-15 17:04:44.422] CSeq: 34 REGISTER
[2014-05-15 17:04:44.422] WWW-Authenticate: Digest realm=“XXXX”,nonce=“1400166284/417161399a155948e1e94c43e6421ced”,opaque=“72f4048f0858f5a1”,algorithm=md5,qop=“auth”
[2014-05-15 17:04:44.422] Content-Length: 0
[2014-05-15 17:04:44.422]
[2014-05-15 17:04:44.422]
[2014-05-15 17:04:44.442] <— Received SIP request (1088 bytes) from UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:44.442] REGISTER sip:192.168.1.10 SIP/2.0
[2014-05-15 17:04:44.442] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8
[2014-05-15 17:04:44.442] Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,MESSAGE,OPTIONS,INFO,SUBSCRIBE
[2014-05-15 17:04:44.442] Allow-Events: presence,kpml
[2014-05-15 17:04:44.442] Authorization: Digest username=“0444772003”,realm=“XXXX”,nonce=“1400166284/417161399a155948e1e94c43e6421ced”,uri=“sip:93.20.94.1:5060”,response=“28e6232e13204db09d98654936552eb4”,algorithm=MD5,cnonce=“643c9869”,opaque=“72f4048f0858f5a1”,qop=auth,nc=00000001
[2014-05-15 17:04:44.442] Call-ID: OA5112F6FE04447720032B8A8481@100.SIP.UDP
[2014-05-15 17:04:44.442] Contact: sip:0444772003@192.168.1.100:5060;transport=udp;user=phone;methods=“INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER”
[2014-05-15 17:04:44.442] CSeq: 36 REGISTER
[2014-05-15 17:04:44.442] Expires: 3600
[2014-05-15 17:04:44.442] From: sip:0444772003@192.168.1.100:5060;user=phone;tag=6A89
[2014-05-15 17:04:44.442] Max-Forwards: 69
[2014-05-15 17:04:44.442] Supported: replaces,norefersub,extended-refer,X-cisco-serviceuri
[2014-05-15 17:04:44.442] To: sip:0444772003@192.168.1.10;user=phone
[2014-05-15 17:04:44.442] User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.3.1.1561
[2014-05-15 17:04:44.442] Via: SIP/2.0/UDP 192.168.1.100:5060;branch=z9hG4bK-211-14,SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-24
[2014-05-15 17:04:44.442] Content-Length: 0
[2014-05-15 17:04:44.442]
[2014-05-15 17:04:44.442]
[2014-05-15 17:04:44.545] <— Transmitting SIP response (502 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:44.545] SIP/2.0 200 OK
[2014-05-15 17:04:44.545] Via: SIP/2.0/UDP 192.168.1.100:5060;rport;received=192.168.1.100;branch=z9hG4bK-211-14
[2014-05-15 17:04:44.545] Via: SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-24
[2014-05-15 17:04:44.545] Call-ID: OA5112F6FE04447720032B8A8481@100.SIP.UDP
[2014-05-15 17:04:44.545] From: sip:0444772003@192.168.1.100;user=phone;tag=6A89
[2014-05-15 17:04:44.545] To: sip:0444772003@192.168.1.10;user=phone;tag=z9hG4bK-211-14
[2014-05-15 17:04:44.545] CSeq: 36 REGISTER
[2014-05-15 17:04:44.545] Date: Thu, 15 May 2014 15:04:44 GMT
[2014-05-15 17:04:44.545] Contact: sip:0444772003@192.168.1.100:5060;user=phone;transport=udp;expires=3599
[2014-05-15 17:04:44.545] Content-Length: 0
[2014-05-15 17:04:44.545]
[2014-05-15 17:04:44.545]
[2014-05-15 17:04:44.565] <— Received SIP request (826 bytes) from UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:44.565] SUBSCRIBE sip:192.168.1.10:5060 SIP/2.0
[2014-05-15 17:04:44.565] Accept: application/pidf+xml,application/xpidf+xml
[2014-05-15 17:04:44.565] Accept-Language: fr-fr,fr;q=0.9,en;q=0.8
[2014-05-15 17:04:44.565] Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER
[2014-05-15 17:04:44.565] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:44.565] Contact: “0444772003” sip:0444772003@192.168.1.100:5060;user=phone
[2014-05-15 17:04:44.565] CSeq: 33 SUBSCRIBE
[2014-05-15 17:04:44.565] Event: presence
[2014-05-15 17:04:44.565] Expires: 3600
[2014-05-15 17:04:44.565] From: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:44.565] Max-Forwards: 69
[2014-05-15 17:04:44.565] Supported: replaces,norefsub,extended-refer,X-cisco-serviceuri,eventlist
[2014-05-15 17:04:44.565] To: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:44.565] User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.3.1.1561
[2014-05-15 17:04:44.565] Via: SIP/2.0/UDP 192.168.1.100:5060;branch=z9hG4bK-2FFF-15,SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-43
[2014-05-15 17:04:44.565] Content-Length: 0
[2014-05-15 17:04:44.565]
[2014-05-15 17:04:44.565]
[2014-05-15 17:04:44.566] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:44.566] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:04:44.566] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:04:44.566] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:44.566] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:44.566] Contact: sip:192.168.1.10:5060
[2014-05-15 17:04:44.566] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:44.566] CSeq: 27118 NOTIFY
[2014-05-15 17:04:44.566] Event: presence
[2014-05-15 17:04:44.566] Subscription-State: active;expires=3599
[2014-05-15 17:04:44.566] Allow-Events: presence, message-summary, refer
[2014-05-15 17:04:44.566] Content-Type: application/pidf+xml
[2014-05-15 17:04:44.566] Content-Length: 606
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.566] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.566] Unavailable
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.566] closed
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.566] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.566] pp:person
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.566] ep:activitiesep:away</ep:activities>
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.566] </pp:person>
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.566]
[2014-05-15 17:04:44.567] <— Transmitting SIP response (614 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:44.567] SIP/2.0 200 OK
[2014-05-15 17:04:44.567] Via: SIP/2.0/UDP 192.168.1.100:5060;rport;received=192.168.1.100;branch=z9hG4bK-2FFF-15
[2014-05-15 17:04:44.567] Via: SIP/2.0/UDP 93.20.94.200:5060;branch=z9hG4bK-16876-1-43
[2014-05-15 17:04:44.567] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:44.567] From: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:44.567] To: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:44.567] CSeq: 33 SUBSCRIBE
[2014-05-15 17:04:44.567] Expires: 3600
[2014-05-15 17:04:44.567] Contact: sip:192.168.1.10:5060
[2014-05-15 17:04:44.567] Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER
[2014-05-15 17:04:44.567] Supported: 100rel, timer, replaces, norefersub
[2014-05-15 17:04:44.567] Content-Length: 0
[2014-05-15 17:04:44.567]
[2014-05-15 17:04:44.567]
[2014-05-15 17:04:45.067] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:45.067] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:04:45.067] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:04:45.067] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:45.067] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:45.067] Contact: sip:192.168.1.10:5060
[2014-05-15 17:04:45.067] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:45.067] CSeq: 27118 NOTIFY
[2014-05-15 17:04:45.067] Event: presence
[2014-05-15 17:04:45.067] Subscription-State: active;expires=3599
[2014-05-15 17:04:45.067] Allow-Events: presence, message-summary, refer
[2014-05-15 17:04:45.067] Content-Type: application/pidf+xml
[2014-05-15 17:04:45.067] Content-Length: 606
[2014-05-15 17:04:45.067]
[2014-05-15 17:04:45.067] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:04:45.067]
[2014-05-15 17:04:45.067] Unavailable
[2014-05-15 17:04:45.067]
[2014-05-15 17:04:45.067]
[2014-05-15 17:04:45.067] closed
[2014-05-15 17:04:45.068]
[2014-05-15 17:04:45.068] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:04:45.068]
[2014-05-15 17:04:45.068] pp:person
[2014-05-15 17:04:45.068]
[2014-05-15 17:04:45.068] ep:activitiesep:away</ep:activities>
[2014-05-15 17:04:45.068]
[2014-05-15 17:04:45.068] </pp:person>
[2014-05-15 17:04:45.068]
[2014-05-15 17:04:45.205]
[2014-05-15 17:04:46.068] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:46.068] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:04:46.068] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:04:46.068] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:46.068] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:46.068] Contact: sip:192.168.1.10:5060
[2014-05-15 17:04:46.068] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:46.068] CSeq: 27118 NOTIFY
[2014-05-15 17:04:46.068] Event: presence
[2014-05-15 17:04:46.068] Subscription-State: active;expires=3599
[2014-05-15 17:04:46.068] Allow-Events: presence, message-summary, refer
[2014-05-15 17:04:46.068] Content-Type: application/pidf+xml
[2014-05-15 17:04:46.068] Content-Length: 606
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:46.068] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:46.068] Unavailable
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:46.068] closed
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:46.068] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:46.068] pp:person
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:46.068] ep:activitiesep:away</ep:activities>
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:46.068] </pp:person>
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:46.068]
[2014-05-15 17:04:48.068] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:48.068] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:04:48.068] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:04:48.068] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:48.068] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:48.068] Contact: sip:192.168.1.10:5060
[2014-05-15 17:04:48.068] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:48.068] CSeq: 27118 NOTIFY
[2014-05-15 17:04:48.068] Event: presence
[2014-05-15 17:04:48.068] Subscription-State: active;expires=3599
[2014-05-15 17:04:48.068] Allow-Events: presence, message-summary, refer
[2014-05-15 17:04:48.068] Content-Type: application/pidf+xml
[2014-05-15 17:04:48.068] Content-Length: 606
[2014-05-15 17:04:48.068]
[2014-05-15 17:04:48.068] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:04:48.068]
[2014-05-15 17:04:48.068] Unavailable
[2014-05-15 17:04:48.068]
[2014-05-15 17:04:48.068]
[2014-05-15 17:04:48.068] closed
[2014-05-15 17:04:48.068]
[2014-05-15 17:04:48.068] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:04:48.068]
[2014-05-15 17:04:48.068] pp:person
[2014-05-15 17:04:48.068]
[2014-05-15 17:04:48.068] ep:activitiesep:away</ep:activities>
[2014-05-15 17:04:48.068]
[2014-05-15 17:04:48.069] </pp:person>
[2014-05-15 17:04:48.069]
[2014-05-15 17:04:48.069]
[2014-05-15 17:04:52.068] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:52.068] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:04:52.068] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:04:52.068] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:52.068] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:52.068] Contact: sip:192.168.1.10:5060
[2014-05-15 17:04:52.069] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:52.069] CSeq: 27118 NOTIFY
[2014-05-15 17:04:52.069] Event: presence
[2014-05-15 17:04:52.069] Subscription-State: active;expires=3599
[2014-05-15 17:04:52.069] Allow-Events: presence, message-summary, refer
[2014-05-15 17:04:52.069] Content-Type: application/pidf+xml
[2014-05-15 17:04:52.069] Content-Length: 606
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:52.069] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:52.069] Unavailable
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:52.069] closed
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:52.069] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:52.069] pp:person
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:52.069] ep:activitiesep:away</ep:activities>
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:52.069] </pp:person>
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:52.069]
[2014-05-15 17:04:56.069] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:04:56.069] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:04:56.069] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:04:56.069] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:04:56.069] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:04:56.069] Contact: sip:192.168.1.10:5060
[2014-05-15 17:04:56.069] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:04:56.069] CSeq: 27118 NOTIFY
[2014-05-15 17:04:56.069] Event: presence
[2014-05-15 17:04:56.069] Subscription-State: active;expires=3599
[2014-05-15 17:04:56.069] Allow-Events: presence, message-summary, refer
[2014-05-15 17:04:56.069] Content-Type: application/pidf+xml
[2014-05-15 17:04:56.069] Content-Length: 606
[2014-05-15 17:04:56.069]
[2014-05-15 17:04:56.069] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:04:56.069]
[2014-05-15 17:04:56.069] Unavailable
[2014-05-15 17:04:56.069]
[2014-05-15 17:04:56.069]
[2014-05-15 17:04:56.069] closed
[2014-05-15 17:04:56.069]
[2014-05-15 17:04:56.069] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:04:56.069]
[2014-05-15 17:04:56.069] pp:person
[2014-05-15 17:04:56.069]
[2014-05-15 17:04:56.069] ep:activitiesep:away</ep:activities>
[2014-05-15 17:04:56.069]
[2014-05-15 17:04:56.069] </pp:person>
[2014-05-15 17:04:56.069]
[2014-05-15 17:04:56.069]
[2014-05-15 17:05:00.069] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:05:00.069] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:05:00.069] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:05:00.069] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:05:00.069] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:05:00.069] Contact: sip:192.168.1.10:5060
[2014-05-15 17:05:00.069] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:05:00.069] CSeq: 27118 NOTIFY
[2014-05-15 17:05:00.069] Event: presence
[2014-05-15 17:05:00.069] Subscription-State: active;expires=3599
[2014-05-15 17:05:00.069] Allow-Events: presence, message-summary, refer
[2014-05-15 17:05:00.069] Content-Type: application/pidf+xml
[2014-05-15 17:05:00.069] Content-Length: 606
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:00.069] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:00.069] Unavailable
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:00.069] closed
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:00.069] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:00.069] pp:person
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:00.069] ep:activitiesep:away</ep:activities>
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:00.069] </pp:person>
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:00.069]
[2014-05-15 17:05:04.069] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:05:04.069] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:05:04.069] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:05:04.069] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:05:04.069] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:05:04.069] Contact: sip:192.168.1.10:5060
[2014-05-15 17:05:04.069] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:05:04.069] CSeq: 27118 NOTIFY
[2014-05-15 17:05:04.069] Event: presence
[2014-05-15 17:05:04.069] Subscription-State: active;expires=3599
[2014-05-15 17:05:04.069] Allow-Events: presence, message-summary, refer
[2014-05-15 17:05:04.069] Content-Type: application/pidf+xml
[2014-05-15 17:05:04.069] Content-Length: 606
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:04.069] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:04.069] Unavailable
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:04.069] closed
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:04.069] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:04.069] pp:person
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:04.069] ep:activitiesep:away</ep:activities>
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:04.069] </pp:person>
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:04.069]
[2014-05-15 17:05:08.069] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:05:08.069] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:05:08.069] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:05:08.069] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:05:08.069] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:05:08.069] Contact: sip:192.168.1.10:5060
[2014-05-15 17:05:08.069] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:05:08.069] CSeq: 27118 NOTIFY
[2014-05-15 17:05:08.069] Event: presence
[2014-05-15 17:05:08.069] Subscription-State: active;expires=3599
[2014-05-15 17:05:08.069] Allow-Events: presence, message-summary, refer
[2014-05-15 17:05:08.069] Content-Type: application/pidf+xml
[2014-05-15 17:05:08.069] Content-Length: 606
[2014-05-15 17:05:08.069]
[2014-05-15 17:05:08.069] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:05:08.069]
[2014-05-15 17:05:08.070] Unavailable
[2014-05-15 17:05:08.070]
[2014-05-15 17:05:08.070]
[2014-05-15 17:05:08.070] closed
[2014-05-15 17:05:08.070]
[2014-05-15 17:05:08.070] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:05:08.070]
[2014-05-15 17:05:08.070] pp:person
[2014-05-15 17:05:08.070]
[2014-05-15 17:05:08.070] ep:activitiesep:away</ep:activities>
[2014-05-15 17:05:08.070]
[2014-05-15 17:05:08.070] </pp:person>
[2014-05-15 17:05:08.070]
[2014-05-15 17:05:08.070]
[2014-05-15 17:05:12.070] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:05:12.070] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:05:12.070] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:05:12.070] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:05:12.070] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:05:12.070] Contact: sip:192.168.1.10:5060
[2014-05-15 17:05:12.070] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:05:12.070] CSeq: 27118 NOTIFY
[2014-05-15 17:05:12.070] Event: presence
[2014-05-15 17:05:12.070] Subscription-State: active;expires=3599
[2014-05-15 17:05:12.070] Allow-Events: presence, message-summary, refer
[2014-05-15 17:05:12.070] Content-Type: application/pidf+xml
[2014-05-15 17:05:12.070] Content-Length: 606
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:12.070] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:12.070] Unavailable
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:12.070] closed
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:12.070] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:12.070] pp:person
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:12.070] ep:activitiesep:away</ep:activities>
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:12.070] </pp:person>
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:12.070]
[2014-05-15 17:05:16.070] <— Transmitting SIP request (1180 bytes) to UDP:192.168.1.100:5060 —>
[2014-05-15 17:05:16.070] NOTIFY sip:0444772003@192.168.1.100:5060;user=phone SIP/2.0
[2014-05-15 17:05:16.070] Via: SIP/2.0/UDP 192.168.1.10:5060;rport;branch=z9hG4bKPjd9722365-a8d8-47f4-aeb0-6cce49c310cb
[2014-05-15 17:05:16.070] From: sip:multiblfs@192.168.1.10;tag=186c128b-f1b4-4e54-9f4b-491ba24b57cf
[2014-05-15 17:05:16.070] To: “0444772003” sip:0444772003@192.168.1.100;user=phone;tag=1010
[2014-05-15 17:05:16.070] Contact: sip:192.168.1.10:5060
[2014-05-15 17:05:16.070] Call-ID: OA211887B804447720034060415E@100.SIP.UDP
[2014-05-15 17:05:16.070] CSeq: 27118 NOTIFY
[2014-05-15 17:05:16.070] Event: presence
[2014-05-15 17:05:16.070] Subscription-State: active;expires=3599
[2014-05-15 17:05:16.070] Allow-Events: presence, message-summary, refer
[2014-05-15 17:05:16.070] Content-Type: application/pidf+xml
[2014-05-15 17:05:16.070] Content-Length: 606
[2014-05-15 17:05:16.070]
[2014-05-15 17:05:16.070] <?xml version="1.0" encoding="UTF-8"?>
[2014-05-15 17:05:16.070]
[2014-05-15 17:05:16.070] Unavailable
[2014-05-15 17:05:16.070]
[2014-05-15 17:05:16.070]
[2014-05-15 17:05:16.070] closed
[2014-05-15 17:05:16.070]
[2014-05-15 17:05:16.070] “0444772003” <sip:0444772003@192.168.1.100;user=phone>
[2014-05-15 17:05:16.070]
[2014-05-15 17:05:16.070] pp:person
[2014-05-15 17:05:16.070]
[2014-05-15 17:05:16.070] ep:activitiesep:away</ep:activities>
[2014-05-15 17:05:16.070]
[2014-05-15 17:05:16.070] </pp:person>
[2014-05-15 17:05:16.070]
[2014-05-15 17:05:16.070]
linux92*CLI>

My problem is that when the notify comes to my device, the device do not process the notify even after receiving the subscribe 200OK, and at the notify timeout Asterisk don’t answer to any further SIP requests.

Is it a bug ? Should I open an issue on the tracker ?