May 9, 2008 4:24 PM
MWI (Message Waiting Indication) with opensbc & asterisk
I am using opensbc in front of my asterisk deployment. MWI does not
appear to be working.
I took a sip trace and noticed the notify for MWI is sent from asterisk to
opensbc but opensbc response back with a "405 Method Not Allowed"
. Does MWI work on opensbc when you're using Upper Registration Routes to
asterisk or any other sip server? (Just incase asterisk was sending a strange
notify message i also tried this with my broadsoft switch and received the same
response)
Here is a caption of the logs. My apologies if this has been discussed
in previous posts, I've looked for several hours and could not find any
confirmation on the discussion boards.
Version of opensbc is 1.1.4
6add0e19-e9f1baff-b7a2df44@192.168.7.75
336205:44:40.087 INF: CID=0x0a82 PROXY: Session DESTROYED
336205:44:44.494 DTL: CID=0x0a82 NICT(3433925657) Event( Timer-K ) Interval: 5000
336205:44:44.494 DTL: CID=0x0a82 NICT(3433925657) Event(Final)
336205:44:44.494 DTL: CID=0x0000 *** REMOVED TRANSACTION *** NICT|6add0e19-e9f1baff-b7a2df44@192.168.7.75|z9hG4bK3e6c70bd3b1cdd11969aaf32d5ad55fd-a076b0ee67b01c83cf887de37cd0ac56|REGISTER
336205:44:44.494 DBG: CID=0x0000 GC: First Stale Object SIPTransaction
336205:44:44.495 DBG: CID=0x0a82 TRANSACTION: (NICT) DESTROYED
336205:44:44.495 DTL: CID=0x0a82 NICT(3433925657) *** DESTROYED *** - NICT|6add0e19-e9f1baff-b7a2df44@192.168.7.75|z9hG4bK3e6c70bd3b1cdd11969aaf32d5ad55fd-a076b0ee67b01c83cf887de37cd0ac56|REGISTER
336205:44:44.993 DTL: CID=0x0a82 NICT(3433925661) Event( Timer-K ) Interval: 5000
336205:44:44.994 DTL: CID=0x0a82 NICT(3433925661) Event(Final)
336205:44:44.994 DTL: CID=0x0000 *** REMOVED TRANSACTION *** NICT|6add0e19-e9f1baff-b7a2df44@192.168.7.75|z9hG4bK1c0d92bd3b1cdd11969aaf32d5ad55fd-5ad2325ca4bbb01c9e281b9973397946|REGISTER
336205:44:44.994 DBG: CID=0x0000 GC: First Stale Object SIPTransaction
336205:44:44.995 DBG: CID=0x0a82 TRANSACTION: (NICT) DESTROYED
336205:44:44.995 DTL: CID=0x0a82 NICT(3433925661) *** DESTROYED *** - NICT|6add0e19-e9f1baff-b7a2df44@192.168.7.75|z9hG4bK1c0d92bd3b1cdd11969aaf32d5ad55fd-5ad2325ca4bbb01c9e281b9973397946|REGISTER
336205:44:50.937 DBG: CID=0x0b29 RCV: enc=0 575 Bytes from RCVADDR: xxx.xxx.xxx.82:RCVPORT: 5060:UDP (NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0)
336205:44:50.938 DBG: CID=0x0b29
336205:44:50.938 DBG: CID=0x0b29 RECEIVE: REQ: NOTIFY (102)
336205:44:50.938 DBG: CID=0x0b29 FROM: sip:asterisk@xxx.xxx.xxx.82
336205:44:50.938 DBG: CID=0x0b29 TO: sip:5164369839@xxx.xxx.xxx.67:65080
336205:44:50.938 DBG: CID=0x0b29 XOR: FALSE
336205:44:50.938 DBG: CID=0x0b29 SRC: xxx.xxx.xxx.82 PORT: 5060
336205:44:50.938 DBG: CID=0x0b29
336205:44:50.939 DBG: CID=0x0b29
336205:44:50.939 DBG: CID=0x0b29 *** CREATING TRANSACTION (NIST) ***
336205:44:50.939 DBG: CID=0x0b29 Message: NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0
336205:44:50.939 DBG: CID=0x0b29 Call-Id: 2ffc421156409b7551b56acb307c4a73@xxx.xxx.xxx.82
336205:44:50.939 DBG: CID=0x0b29
336205:44:50.940 DTL: CID=0x0b29 NIST(3433925663) *** CREATED *** - NIST|2ffc421156409b7551b56acb307c4a73@xxx.xxx.xxx.82|z9hG4bK6e1b55dc|NOTIFY
336205:44:50.941 DTL: CID=0x0b29 NIST(3433925663) Event(SIPMessage) - NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0
336205:44:50.941 DBG: CID=0x0b29 TRANSACTION: (NIST) NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0 State: 0
336205:44:50.941 DTL: CID=0x0b29 NIST(3433925663) StateIdle->StateTrying
336205:44:50.941 DBG: CID=0x0b29 Event: SIPStack::Enqueue(NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0)
336205:44:50.942 DBG: CID=0x0b29 Event: B2BUserAgent::ProcessEvent( NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0 )
336205:44:50.942 INF: CID=0x0b29 Event: Setting UA Core BackDoor to handle event NOTIFY
336205:44:50.944 DTL: CID=0x0b29 Event: ---> Inbound - NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0
336205:44:50.944 WRN: CID=0x06cb CallSessionManager::OnCreateServerSession() got a None-INVITE request
336205:44:50.944 WRN: CID=0x06cb CallSessionManager::OnCreateServerSession() returning NULL
336205:44:50.944 DTL: CID=0x06cb *** MESSAGE ARRIVAL *** No Session available to handle NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0
336205:44:50.945 DTL: CID=0x0b29 Found NIST|2ffc421156409b7551b56acb307c4a73@xxx.xxx.xxx.82|z9hG4bK6e1b55dc|NOTIFY for SIP/2.0 405 Method Not Allowed
336205:44:50.946 DTL: CID=0x0b29 NIST(3433925663) Event(SIPMessage) - SIP/2.0 405 Method Not Allowed
336205:44:50.946 DBG: CID=0x0b29 TRANSACTION: (NIST) SIP/2.0 405 Method Not Allowed State: 1
336205:44:50.947 DTL: CID=0x0b29 NIST(3433925663)HandleStateTrying->StateCompleted
336205:44:50.948 DBG: CID=0x0b29
336205:44:50.948 DBG: CID=0x0b29 TRANSMIT: RESPONSE: SIP/2.0 405 Method Not Allowed
336205:44:50.948 DBG: CID=0x0b29 TO: sip:5164369839@xxx.xxx.xxx.67:65080
336205:44:50.948 DBG: CID=0x0b29 REQ: NOTIFY (102)
336205:44:50.948 DBG: CID=0x0b29 XOR: FALSE
336205:44:50.948 DBG: CID=0x0b29 DST: xxx.xxx.xxx.82 PORT: 5060
336205:44:50.948 DBG: CID=0x0b29
336205:44:50.951 DBG: CID=0x0b29
336205:44:50.951 DBG: CID=0x0b29 SEND: enc=0 327 Bytes to xxx.xxx.xxx.82:5060:UDP (SIP/2.0 405 Method Not Allowed) Interface Address=xxx.xxx.xxx.67
336205:44:50.951 DBG: CID=0x0b29 SIP/2.0 405 Method Not Allowed
336205:44:50.951 DBG: CID=0x0b29 From: "asterisk" <sip:asterisk@xxx.xxx.xxx.82>;tag=as162ffd9e
336205:44:50.951 DBG: CID=0x0b29 To: <sip:5164369839@xxx.xxx.xxx.67:65080>
336205:44:50.951 DBG: CID=0x0b29 Via: SIP/2.0/UDP xxx.xxx.xxx.82:5060;branch=z9hG4bK6e1b55dc;rport=5060;received=xxx.xxx.xxx.82
336205:44:50.951 DBG: CID=0x0b29 CSeq: 102 NOTIFY
336205:44:50.951 DBG: CID=0x0b29 Call-ID: 2ffc421156409b7551b56acb307c4a73@xxx.xxx.xxx.82
336205:44:50.951 DBG: CID=0x0b29 Content-Length: 0
336205:44:50.951 DBG: CID=0x0b29
336205:44:50.951 DBG: CID=0x0b29
336205:45:11.494 DTL: CID=0x0b41 NIST(3433925659) Event( Timer-J ) Interval: 32000
336205:45:11.494 DTL: CID=0x0b41 NIST(3433925659) StateCompleted->StateTerminated
336205:45:11.494 DTL: CID=0x0b3e NIST(3433925658) Event( Timer-J ) Interval: 32000
336205:45:11.494 DTL: CID=0x0b3e NIST(3433925658) StateCompleted->StateTerminated
336205:45:11.494 DTL: CID=0x0a82 NIST(3433925656) Event( Timer-J ) Interval: 32000
336205:45:11.494 DTL: CID=0x0a82 NIST(3433925656)
appear to be working.
I took a sip trace and noticed the notify for MWI is sent from asterisk to
opensbc but opensbc response back with a "405 Method Not Allowed"
. Does MWI work on opensbc when you're using Upper Registration Routes to
asterisk or any other sip server? (Just incase asterisk was sending a strange
notify message i also tried this with my broadsoft switch and received the same
response)
Here is a caption of the logs. My apologies if this has been discussed
in previous posts, I've looked for several hours and could not find any
confirmation on the discussion boards.
Version of opensbc is 1.1.4
6add0e19-e9f1baff-b7a2df44@192.168.7.75
336205:44:40.087 INF: CID=0x0a82 PROXY: Session DESTROYED
336205:44:44.494 DTL: CID=0x0a82 NICT(3433925657) Event( Timer-K ) Interval: 5000
336205:44:44.494 DTL: CID=0x0a82 NICT(3433925657) Event(Final)
336205:44:44.494 DTL: CID=0x0000 *** REMOVED TRANSACTION *** NICT|6add0e19-e9f1baff-b7a2df44@192.168.7.75|z9hG4bK3e6c70bd3b1cdd11969aaf32d5ad55fd-a076b0ee67b01c83cf887de37cd0ac56|REGISTER
336205:44:44.494 DBG: CID=0x0000 GC: First Stale Object SIPTransaction
336205:44:44.495 DBG: CID=0x0a82 TRANSACTION: (NICT) DESTROYED
336205:44:44.495 DTL: CID=0x0a82 NICT(3433925657) *** DESTROYED *** - NICT|6add0e19-e9f1baff-b7a2df44@192.168.7.75|z9hG4bK3e6c70bd3b1cdd11969aaf32d5ad55fd-a076b0ee67b01c83cf887de37cd0ac56|REGISTER
336205:44:44.993 DTL: CID=0x0a82 NICT(3433925661) Event( Timer-K ) Interval: 5000
336205:44:44.994 DTL: CID=0x0a82 NICT(3433925661) Event(Final)
336205:44:44.994 DTL: CID=0x0000 *** REMOVED TRANSACTION *** NICT|6add0e19-e9f1baff-b7a2df44@192.168.7.75|z9hG4bK1c0d92bd3b1cdd11969aaf32d5ad55fd-5ad2325ca4bbb01c9e281b9973397946|REGISTER
336205:44:44.994 DBG: CID=0x0000 GC: First Stale Object SIPTransaction
336205:44:44.995 DBG: CID=0x0a82 TRANSACTION: (NICT) DESTROYED
336205:44:44.995 DTL: CID=0x0a82 NICT(3433925661) *** DESTROYED *** - NICT|6add0e19-e9f1baff-b7a2df44@192.168.7.75|z9hG4bK1c0d92bd3b1cdd11969aaf32d5ad55fd-5ad2325ca4bbb01c9e281b9973397946|REGISTER
336205:44:50.937 DBG: CID=0x0b29 RCV: enc=0 575 Bytes from RCVADDR: xxx.xxx.xxx.82:RCVPORT: 5060:UDP (NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0)
336205:44:50.938 DBG: CID=0x0b29
336205:44:50.938 DBG: CID=0x0b29 RECEIVE: REQ: NOTIFY (102)
336205:44:50.938 DBG: CID=0x0b29 FROM: sip:asterisk@xxx.xxx.xxx.82
336205:44:50.938 DBG: CID=0x0b29 TO: sip:5164369839@xxx.xxx.xxx.67:65080
336205:44:50.938 DBG: CID=0x0b29 XOR: FALSE
336205:44:50.938 DBG: CID=0x0b29 SRC: xxx.xxx.xxx.82 PORT: 5060
336205:44:50.938 DBG: CID=0x0b29
336205:44:50.939 DBG: CID=0x0b29
336205:44:50.939 DBG: CID=0x0b29 *** CREATING TRANSACTION (NIST) ***
336205:44:50.939 DBG: CID=0x0b29 Message: NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0
336205:44:50.939 DBG: CID=0x0b29 Call-Id: 2ffc421156409b7551b56acb307c4a73@xxx.xxx.xxx.82
336205:44:50.939 DBG: CID=0x0b29
336205:44:50.940 DTL: CID=0x0b29 NIST(3433925663) *** CREATED *** - NIST|2ffc421156409b7551b56acb307c4a73@xxx.xxx.xxx.82|z9hG4bK6e1b55dc|NOTIFY
336205:44:50.941 DTL: CID=0x0b29 NIST(3433925663) Event(SIPMessage) - NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0
336205:44:50.941 DBG: CID=0x0b29 TRANSACTION: (NIST) NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0 State: 0
336205:44:50.941 DTL: CID=0x0b29 NIST(3433925663) StateIdle->StateTrying
336205:44:50.941 DBG: CID=0x0b29 Event: SIPStack::Enqueue(NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0)
336205:44:50.942 DBG: CID=0x0b29 Event: B2BUserAgent::ProcessEvent( NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0 )
336205:44:50.942 INF: CID=0x0b29 Event: Setting UA Core BackDoor to handle event NOTIFY
336205:44:50.944 DTL: CID=0x0b29 Event: ---> Inbound - NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0
336205:44:50.944 WRN: CID=0x06cb CallSessionManager::OnCreateServerSession() got a None-INVITE request
336205:44:50.944 WRN: CID=0x06cb CallSessionManager::OnCreateServerSession() returning NULL
336205:44:50.944 DTL: CID=0x06cb *** MESSAGE ARRIVAL *** No Session available to handle NOTIFY sip:5164369839@xxx.xxx.xxx.67:65080 SIP/2.0
336205:44:50.945 DTL: CID=0x0b29 Found NIST|2ffc421156409b7551b56acb307c4a73@xxx.xxx.xxx.82|z9hG4bK6e1b55dc|NOTIFY for SIP/2.0 405 Method Not Allowed
336205:44:50.946 DTL: CID=0x0b29 NIST(3433925663) Event(SIPMessage) - SIP/2.0 405 Method Not Allowed
336205:44:50.946 DBG: CID=0x0b29 TRANSACTION: (NIST) SIP/2.0 405 Method Not Allowed State: 1
336205:44:50.947 DTL: CID=0x0b29 NIST(3433925663)HandleStateTrying->StateCompleted
336205:44:50.948 DBG: CID=0x0b29
336205:44:50.948 DBG: CID=0x0b29 TRANSMIT: RESPONSE: SIP/2.0 405 Method Not Allowed
336205:44:50.948 DBG: CID=0x0b29 TO: sip:5164369839@xxx.xxx.xxx.67:65080
336205:44:50.948 DBG: CID=0x0b29 REQ: NOTIFY (102)
336205:44:50.948 DBG: CID=0x0b29 XOR: FALSE
336205:44:50.948 DBG: CID=0x0b29 DST: xxx.xxx.xxx.82 PORT: 5060
336205:44:50.948 DBG: CID=0x0b29
336205:44:50.951 DBG: CID=0x0b29
336205:44:50.951 DBG: CID=0x0b29 SEND: enc=0 327 Bytes to xxx.xxx.xxx.82:5060:UDP (SIP/2.0 405 Method Not Allowed) Interface Address=xxx.xxx.xxx.67
336205:44:50.951 DBG: CID=0x0b29 SIP/2.0 405 Method Not Allowed
336205:44:50.951 DBG: CID=0x0b29 From: "asterisk" <sip:asterisk@xxx.xxx.xxx.82>;tag=as162ffd9e
336205:44:50.951 DBG: CID=0x0b29 To: <sip:5164369839@xxx.xxx.xxx.67:65080>
336205:44:50.951 DBG: CID=0x0b29 Via: SIP/2.0/UDP xxx.xxx.xxx.82:5060;branch=z9hG4bK6e1b55dc;rport=5060;received=xxx.xxx.xxx.82
336205:44:50.951 DBG: CID=0x0b29 CSeq: 102 NOTIFY
336205:44:50.951 DBG: CID=0x0b29 Call-ID: 2ffc421156409b7551b56acb307c4a73@xxx.xxx.xxx.82
336205:44:50.951 DBG: CID=0x0b29 Content-Length: 0
336205:44:50.951 DBG: CID=0x0b29
336205:44:50.951 DBG: CID=0x0b29
336205:45:11.494 DTL: CID=0x0b41 NIST(3433925659) Event( Timer-J ) Interval: 32000
336205:45:11.494 DTL: CID=0x0b41 NIST(3433925659) StateCompleted->StateTerminated
336205:45:11.494 DTL: CID=0x0b3e NIST(3433925658) Event( Timer-J ) Interval: 32000
336205:45:11.494 DTL: CID=0x0b3e NIST(3433925658) StateCompleted->StateTerminated
336205:45:11.494 DTL: CID=0x0a82 NIST(3433925656) Event( Timer-J ) Interval: 32000
336205:45:11.494 DTL: CID=0x0a82 NIST(3433925656)








