E51 Sip registration fails. RFC 3261 compliance?

[thegapi]

Hi there,
in my LAN I'm using sipXecs (http://www.sipfoundry.org/) a popular open source PBX.
It is pretty simple and easy to use. I have gateways and phones successfully working with it.

I wanted to use my nokia e51 but unfortunately it aways claim it cannot register. The phone connect to the server via wlan. Phone and server are on the same ip lan perfectly reaching each other. No router, no firewall, no nat.

So I checked the exchange of register messages between the phone and the sever. I couldn't understand myself why the server always responded with an unregister. So I asked for help to the Sipfoundry community.

They analyzed the log and said:

If you examine the REGISTER requests closely, you'll see that they all
have different Call-Id and from-tag values. sipX rejects each REGISTER
request with a 401 and provides an authentication nonce. But in sipX,
the nonces are bound to the Call-Id and from-tag values; they can't be
re-used in different dialogs. (This helps prevent forgeries.)

RFC 3261 section 22.2 states that if a request is rejected for
authentication, the UAC must resend the request, with an incremented
CSeq but the same Call-Id and from-tag. Unfortunately, the Symbian is
violating this rule, so the nonce that it gets in response to one
REGISTER is not usable in the next REGISTER it generates.

You'll have to get Nokia to fix this.


I had the same registration problem with a nokia e90.

Can you investigate if this is this really a bug?

Thanks in advance.

[thegapi]

Log Part 1

"2008-01-15T17:06:25.307672Z":401:SIP:DEBUG:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"SipClient[SipClient-10]::run 0x9a6da10 socket 0x9a6d128 host: sock addr: 0.0.0.0 via addr: 192.168.0.220 rcv addr: 192.168.0.220 sock type: UDP read ready READY"
"2008-01-15T17:06:25.352928Z":402:INCOMING:INFO:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"Read SIP message:\n----Remote Host:192.168.0.220---- Port: 32791----\nREGISTER sip:pbx.ictengineer.net;transport=UDP SIP/2.0\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=n52tijt5m9hc77b30a7o\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nContact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600\r\nCseq: 1022 REGISTER\r\nCall-Id: Zw_pjPOloIfXLgafx1-S5mgTl7UWwP\r\nSupported: sec-agree\r\nUser-Agent: Nokia RM-244 100.34.20\r\nMax-Forwards: 20\r\nContent-Length: 0\r\nDate: Tue, 15 Jan 2008 17:06:25 GMT\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-7a1070aa621f21f6e06e1349f4ee9031\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKq3ptijohohhc62gu0a7o8su;rport=5060\r\nRoute: <sip:192.168.0.220:5070;transport=udp;lr>\r\n\r\n====================END====================\n"
"2008-01-15T17:06:25.379140Z":403:INCOMING_PARSED:DEBUG:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"REGISTER sip:pbx.ictengineer.net;transport=UDP SIP/2.0\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=n52tijt5m9hc77b30a7o\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nContact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600\r\nCseq: 1022 REGISTER\r\nCall-Id: Zw_pjPOloIfXLgafx1-S5mgTl7UWwP\r\nSupported: sec-agree\r\nUser-Agent: Nokia RM-244 100.34.20\r\nMax-Forwards: 20\r\nContent-Length: 0\r\nDate: Tue, 15 Jan 2008 17:06:25 GMT\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-7a1070aa621f21f6e06e1349f4ee9031\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKq3ptijohohhc62gu0a7o8su;rport=5060\r\nRoute: <sip:192.168.0.220:5070;transport=udp;lr>\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n"
"2008-01-15T17:06:25.381867Z":404:SIP:DEBUG:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"SipClient[SipClient-10]::run time log: \n Time Increment Name\n 0.000000 waiting\n 5134.957000 5134.957000 locking\n 5134.958233 0.001233 locked\n 5135.005310 0.047077 reading\n 5135.045285 0.039975 read\n 5135.045290 0.000005 released\n 5135.047480 0.002190 bytesread\n 5135.071906 0.024426 dispatching\n 5135.076381 0.004475 dispatched"
"2008-01-15T17:06:25.410006Z":405:SIP:DEBUG:pbx.ictengineer.net:SipRegistrar:B759DB90:SipRegistrar:"SipRegistrar::handleMessage() Start processing SIP message"
"2008-01-15T17:06:25.412837Z":406:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipRegistrarServer::handleMessage() - Start processing REGISTER Message"
"2008-01-15T17:06:25.413243Z":407:AUTH:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipRegistrar::isValidDomain(pbx.ictengineer.net:5060) VALID"
"2008-01-15T17:06:25.415154Z":408:AUTH:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipRegistrarServer::isAuthorized(): fromUri='sip:TEST@pbx.ictengineer.net', toUri='sip:TEST@pbx.ictengineer.net', realm='ictengineer.net' "
"2008-01-15T17:06:25.416901Z":409:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"nonceSignature: callId='Zw_pjPOloIfXLgafx1-S5mgTl7UWwP' fromTag='' realm='ictengineer.net' timestamp='1200416785'"
"2008-01-15T17:06:25.419419Z":410:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"\n----------------------------------\nSending final response\nSIP/2.0 401 Unauthorized\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=n52tijt5m9hc77b30a7o\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nCall-Id: Zw_pjPOloIfXLgafx1-S5mgTl7UWwP\r\nCseq: 1022 REGISTER\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-7a1070aa621f21f6e06e1349f4ee9031\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKq3ptijohohhc62gu0a7o8su;rport=5060\r\nWww-Authenticate: Digest realm=\"ictengineer.net\", nonce=\"606664fcf0cf4fb3f48352578b7982d21200416785\"\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nContent-Length: 0\r\n\r\n"
"2008-01-15T17:06:25.421948Z":411:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"OsDatagramSocket::_ attempt 192.168.0.220:5060"
"2008-01-15T17:06:25.422864Z":412:KERNEL:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"OsDatagramSocket::_ 15 (192.168.0.220:5060 192.168.0.220:0) socket and bind succeeded"
"2008-01-15T17:06:25.423724Z":413:KERNEL:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"OsDatagramSocket::doConnect 15 (192.168.0.220:5060 192.168.0.220:32792) succeeded)"
"2008-01-15T17:06:25.424972Z":414:KERNEL:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"OsTask::_ 'SipClient-32' created 0x9a9bab8"
"2008-01-15T17:06:25.425937Z":415:SIP:INFO:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipClient[SipClient-32]::_ created UDP unshared socket 15: host '192.168.0.220' port 5060"
"2008-01-15T17:06:25.426742Z":416:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipUDPServer(SipProtocolServerBase)::createClient client: 0x9a9bab8 '192.168.0.220':5060"
"2008-01-15T17:06:25.426950Z":417:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipUDPServerBase::send 0x9a9bab8 isInUseForWrite 7022, client info\n remote host: 192.168.0.220:5060\n remote IP: 192.168.0.220:5060\n remote Via address: :-1\n received address: :-1"
"2008-01-15T17:06:25.427917Z":418:OUTGOING:INFO:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:192.168.0.220---- Port: 5060----\nSIP/2.0 401 Unauthorized\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=n52tijt5m9hc77b30a7o\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nCall-Id: Zw_pjPOloIfXLgafx1-S5mgTl7UWwP\r\nCseq: 1022 REGISTER\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-7a1070aa621f21f6e06e1349f4ee9031\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKq3ptijohohhc62gu0a7o8su;rport=5060\r\nWww-Authenticate: Digest realm=\"ictengineer.net\", nonce=\"606664fcf0cf4fb3f48352578b7982d21200416785\"\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nDate: Tue, 15 Jan 2008 17:06:25 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, REGISTER, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: gruu\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n"
"2008-01-15T17:06:49.264558Z":419:SIP:DEBUG:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"SipClient[SipClient-10]::run 0x9a6da10 socket 0x9a6d128 host: sock addr: 0.0.0.0 via addr: 192.168.0.220 rcv addr: 192.168.0.220 sock type: UDP read ready READY"
"2008-01-15T17:06:49.265388Z":420:INCOMING:INFO:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"Read SIP message:\n----Remote Host:192.168.0.220---- Port: 32791----\nREGISTER sip:pbx.ictengineer.net;transport=UDP SIP/2.0\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=n1edijuakthc66qq3c71\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nContact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600\r\nCseq: 1023 REGISTER\r\nCall-Id: 9PnpjTAFoIeOsxIe7io7vxT38JYSvC\r\nSupported: sec-agree\r\nUser-Agent: Nokia RM-244 100.34.20\r\nMax-Forwards: 20\r\nContent-Length: 0\r\nDate: Tue, 15 Jan 2008 17:06:49 GMT\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-aceab60e15bdd57def9a7ba49cf78a26\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKc46dijudbthc7lk53c71q0e;rport=5060\r\nRoute: <sip:192.168.0.220:5070;transport=udp;lr>\r\n\r\n====================END====================\n"

[thegapi]

Log part 2

"2008-01-15T17:06:49.266482Z":421:INCOMING_PARSED:DEBUG:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"REGISTER sip:pbx.ictengineer.net;transport=UDP SIP/2.0\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=n1edijuakthc66qq3c71\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nContact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600\r\nCseq: 1023 REGISTER\r\nCall-Id: 9PnpjTAFoIeOsxIe7io7vxT38JYSvC\r\nSupported: sec-agree\r\nUser-Agent: Nokia RM-244 100.34.20\r\nMax-Forwards: 20\r\nContent-Length: 0\r\nDate: Tue, 15 Jan 2008 17:06:49 GMT\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-aceab60e15bdd57def9a7ba49cf78a26\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKc46dijudbthc7lk53c71q0e;rport=5060\r\nRoute: <sip:192.168.0.220:5070;transport=udp;lr>\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n"
"2008-01-15T17:06:49.267101Z":422:SIP:DEBUG:pbx.ictengineer.net:SipRegistrar:B759DB90:SipRegistrar:"SipRegistrar::handleMessage() Start processing SIP message"
"2008-01-15T17:06:49.267369Z":423:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipRegistrarServer::handleMessage() - Start processing REGISTER Message"
"2008-01-15T17:06:49.267588Z":424:AUTH:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipRegistrar::isValidDomain(pbx.ictengineer.net:5060) VALID"
"2008-01-15T17:06:49.267835Z":425:AUTH:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipRegistrarServer::isAuthorized(): fromUri='sip:TEST@pbx.ictengineer.net', toUri='sip:TEST@pbx.ictengineer.net', realm='ictengineer.net' "
"2008-01-15T17:06:49.268183Z":426:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"nonceSignature: callId='9PnpjTAFoIeOsxIe7io7vxT38JYSvC' fromTag='' realm='ictengineer.net' timestamp='1200416809'"
"2008-01-15T17:06:49.268698Z":427:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"\n----------------------------------\nSending final response\nSIP/2.0 401 Unauthorized\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=n1edijuakthc66qq3c71\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nCall-Id: 9PnpjTAFoIeOsxIe7io7vxT38JYSvC\r\nCseq: 1023 REGISTER\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-aceab60e15bdd57def9a7ba49cf78a26\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKc46dijudbthc7lk53c71q0e;rport=5060\r\nWww-Authenticate: Digest realm=\"ictengineer.net\", nonce=\"311ddc30e831c0403dfbdd55e65f63ee1200416809\"\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nContent-Length: 0\r\n\r\n"
"2008-01-15T17:06:49.269643Z":428:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipUDPServerBase::send 0x9a9bab8 isInUseForWrite 7046, client info\n remote host: 192.168.0.220:5060\n remote IP: 192.168.0.220:5060\n remote Via address: :-1\n received address: :-1"
"2008-01-15T17:06:49.270127Z":429:OUTGOING:INFO:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:192.168.0.220---- Port: 5060----\nSIP/2.0 401 Unauthorized\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=n1edijuakthc66qq3c71\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nCall-Id: 9PnpjTAFoIeOsxIe7io7vxT38JYSvC\r\nCseq: 1023 REGISTER\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-aceab60e15bdd57def9a7ba49cf78a26\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKc46dijudbthc7lk53c71q0e;rport=5060\r\nWww-Authenticate: Digest realm=\"ictengineer.net\", nonce=\"311ddc30e831c0403dfbdd55e65f63ee1200416809\"\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nDate: Tue, 15 Jan 2008 17:06:49 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, REGISTER, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: gruu\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n"
"2008-01-15T17:06:49.270495Z":430:SIP:DEBUG:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"SipClient[SipClient-10]::run time log: \n Time Increment Name\n 0.000000 waiting\n 23.880581 23.880581 locking\n 23.880588 0.000007 locked\n 23.880962 0.000374 reading\n 23.881126 0.000164 read\n 23.881411 0.000285 released\n 23.881426 0.000015 bytesread\n 23.881896 0.000470 dispatching\n 23.886531 0.004635 dispatched"
"2008-01-15T17:07:14.811464Z":431:SIP:DEBUG:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"SipClient[SipClient-10]::run 0x9a6da10 socket 0x9a6d128 host: sock addr: 0.0.0.0 via addr: 192.168.0.220 rcv addr: 192.168.0.220 sock type: UDP read ready READY"
"2008-01-15T17:07:14.813454Z":432:INCOMING:INFO:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"Read SIP message:\n----Remote Host:192.168.0.220---- Port: 32791----\nREGISTER sip:pbx.ictengineer.net;transport=UDP SIP/2.0\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=h2ptijom7hhc6dgb6ima\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nContact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600\r\nCseq: 1024 REGISTER\r\nCall-Id: aELpjTXhoIfTPziIFRH96SfYm3skS0\r\nSupported: sec-agree\r\nUser-Agent: Nokia RM-244 100.34.20\r\nMax-Forwards: 20\r\nContent-Length: 0\r\nDate: Tue, 15 Jan 2008 17:07:14 GMT\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-f868ff4277d84c83be42a68c11bf5b70\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bK1uetijt299hc74pp6imafs3;rport=5060\r\nRoute: <sip:192.168.0.220:5070;transport=udp;lr>\r\n\r\n====================END====================\n"
"2008-01-15T17:07:14.817655Z":433:INCOMING_PARSED:DEBUG:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"REGISTER sip:pbx.ictengineer.net;transport=UDP SIP/2.0\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=h2ptijom7hhc6dgb6ima\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nContact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600\r\nCseq: 1024 REGISTER\r\nCall-Id: aELpjTXhoIfTPziIFRH96SfYm3skS0\r\nSupported: sec-agree\r\nUser-Agent: Nokia RM-244 100.34.20\r\nMax-Forwards: 20\r\nContent-Length: 0\r\nDate: Tue, 15 Jan 2008 17:07:14 GMT\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-f868ff4277d84c83be42a68c11bf5b70\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bK1uetijt299hc74pp6imafs3;rport=5060\r\nRoute: <sip:192.168.0.220:5070;transport=udp;lr>\r\n\r\n++++++++++++++++++++END++++++++++++++++++++\n"
"2008-01-15T17:07:14.818716Z":434:SIP:DEBUG:pbx.ictengineer.net:SipRegistrar:B759DB90:SipRegistrar:"SipRegistrar::handleMessage() Start processing SIP message"
"2008-01-15T17:07:14.819958Z":435:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipRegistrarServer::handleMessage() - Start processing REGISTER Message"
"2008-01-15T17:07:14.821409Z":436:AUTH:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipRegistrar::isValidDomain(pbx.ictengineer.net:5060) VALID"
"2008-01-15T17:07:14.822542Z":437:AUTH:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipRegistrarServer::isAuthorized(): fromUri='sip:TEST@pbx.ictengineer.net', toUri='sip:TEST@pbx.ictengineer.net', realm='ictengineer.net' "
"2008-01-15T17:07:14.822943Z":438:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"nonceSignature: callId='aELpjTXhoIfTPziIFRH96SfYm3skS0' fromTag='' realm='ictengineer.net' timestamp='1200416834'"
"2008-01-15T17:07:14.826306Z":439:SIP:DEBUG:pbx.ictengineer.net:SipClient-10:B7098B90:SipRegistrar:"SipClient[SipClient-10]::run time log: \n Time Increment Name\n 0.000000 waiting\n 25.540675 25.540675 locking\n 25.540688 0.000013 locked\n 25.542508 0.001820 reading\n 25.542698 0.000190 read\n 25.542702 0.000004 released\n 25.542718 0.000016 bytesread\n 25.544862 0.002144 dispatching\n 25.555536 0.010674 dispatched"
"2008-01-15T17:07:14.824719Z":440:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"\n----------------------------------\nSending final response\nSIP/2.0 401 Unauthorized\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=h2ptijom7hhc6dgb6ima\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nCall-Id: aELpjTXhoIfTPziIFRH96SfYm3skS0\r\nCseq: 1024 REGISTER\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-f868ff4277d84c83be42a68c11bf5b70\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bK1uetijt299hc74pp6imafs3;rport=5060\r\nWww-Authenticate: Digest realm=\"ictengineer.net\", nonce=\"41d3ec92d83af73ec6246f48263ca5c81200416834\"\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nContent-Length: 0\r\n\r\n"
"2008-01-15T17:07:14.828033Z":441:SIP:DEBUG:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipUDPServerBase::send 0x9a9bab8 isInUseForWrite 7071, client info\n remote host: 192.168.0.220:5060\n remote IP: 192.168.0.220:5060\n remote Via address: :-1\n received address: :-1"
"2008-01-15T17:07:14.828787Z":442:OUTGOING:INFO:pbx.ictengineer.net:SipRegistrarServer:B678FB90:SipRegistrar:"SipUserAgent::sendUdp UDP SIP User Agent sent message:\n----Remote Host:192.168.0.220---- Port: 5060----\nSIP/2.0 401 Unauthorized\r\nFrom: <sip:TEST@pbx.ictengineer.net>;tag=h2ptijom7hhc6dgb6ima\r\nTo: <sip:TEST@pbx.ictengineer.net>\r\nCall-Id: aELpjTXhoIfTPziIFRH96SfYm3skS0\r\nCseq: 1024 REGISTER\r\nVia: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-f868ff4277d84c83be42a68c11bf5b70\r\nVia: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bK1uetijt299hc74pp6imafs3;rport=5060\r\nWww-Authenticate: Digest realm=\"ictengineer.net\", nonce=\"41d3ec92d83af73ec6246f48263ca5c81200416834\"\r\nUser-Agent: sipX/3.8.1 sipX/registry (Linux)\r\nDate: Tue, 15 Jan 2008 17:07:14 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, REGISTER, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: gruu\r\nContent-Length: 0\r\n\r\n--------------------END--------------------\n"

[juhanak]

Can you provide Ethereal logs of one REGISTER dialog (REGISTER,401,REGISTER)? The log at previous messages is a bit hard to read.

"RFC 3261 section 22.2 states that if a request is rejected for authentication, the UAC must resend the request, with an incremented CSeq but the same Call-Id and from-tag"

Nokia's stack should follow this standard. Here is an example:

REGISTER (from N95)
Call-ID: sF_MTYZqoIdV60j102vTrPKDQIJMl7
CSeq: 675
from-tag=a8pf1nslv1hc6m500j0g

401 (FROM SERVER)
Call-ID: sF_MTYZqoIdV60j102vTrPKDQIJMl7
CSeq: 675
nonce="47909b3b2594b839b169394bf0eccfb4cfeefdb1"

REGISTER (FROM N95)
Call-ID: sF_MTYZqoIdV60j102vTrPKDQIJMl7
CSeq: 676
from-tag=a8pf1nslv1hc6m500j0g
nonce="47909b3b2594b839b169394bf0eccfb4cfeefdb1"

You can see that in the second REGISTER request Cseq value is incremented by one and call-id and from-tag are same as in the first one.

[thegapi]

Hi,
I generated a new sequence of SIP messages. Extracted from the server log:

<time>2008-01-18T15:28:16.320469Z</time>
REGISTER sip:pbx.ictengineer.net;transport=UDP SIP/2.0
Route: <sip:pbx.ictengineer.net;lr;transport=UDP>
Via: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKvshggjmhu5hc62r704d40n6;rport
From: <sip:TEST@pbx.ictengineer.net>;tag=tde0gjj5gthc6ohj04d4
To: <sip:TEST@pbx.ictengineer.net>
Contact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600
CSeq: 1026 REGISTER
Call-ID: tho8jLOwoIfHU04qgd4tkk1E_CMAQe
Supported: sec-agree
User-Agent: Nokia RM-244 100.34.20
Max-Forwards: 70
Content-Length: 0

<time>2008-01-18T15:28:16.350778Z</time>
REGISTER sip:pbx.ictengineer.net;transport=UDP SIP/2.0
From: <sip:TEST@pbx.ictengineer.net>;tag=tde0gjj5gthc6ohj04d4
To: <sip:TEST@pbx.ictengineer.net>
Contact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600
Cseq: 1026 REGISTER
Call-Id: tho8jLOwoIfHU04qgd4tkk1E_CMAQe
Supported: sec-agree
User-Agent: Nokia RM-244 100.34.20
Max-Forwards: 20
Content-Length: 0
Date: Fri, 18 Jan 2008 15:28:16 GMT
Via: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-6fb6c0a73f01af1577f5efdc9f047850
Via: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKvshggjmhu5hc62r704d40n6;rport=5060
Route: <sip:192.168.0.220:5070;transport=udp;lr>

<time>2008-01-18T15:28:16.361540Z</time>
SIP/2.0 401 Unauthorized
From: <sip:TEST@pbx.ictengineer.net>;tag=tde0gjj5gthc6ohj04d4
To: <sip:TEST@pbx.ictengineer.net>
Call-Id: tho8jLOwoIfHU04qgd4tkk1E_CMAQe
Cseq: 1026 REGISTER
Via: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-6fb6c0a73f01af1577f5efdc9f047850
Via: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKvshggjmhu5hc62r704d40n6;rport=5060
Www-Authenticate: Digest realm="ictengineer.net", nonce="3f4d582c3e0172a2874f1a5ba298126f1200670096"
User-Agent: sipX/3.8.1 sipX/registry (Linux)
Date: Fri, 18 Jan 2008 15:28:16 GMT
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, REGISTER, SUBSCRIBE
Accept-Language: en
Supported: gruu
Content-Length: 0

<time>2008-01-18T15:28:16.366254Z</time>
SIP/2.0 401 Unauthorized
From: <sip:TEST@pbx.ictengineer.net>;tag=tde0gjj5gthc6ohj04d4
To: <sip:TEST@pbx.ictengineer.net>
Call-Id: tho8jLOwoIfHU04qgd4tkk1E_CMAQe
Cseq: 1026 REGISTER
Via: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bKvshggjmhu5hc62r704d40n6;rport=5060
Www-Authenticate: Digest realm="ictengineer.net", nonce="3f4d582c3e0172a2874f1a5ba298126f1200670096"
User-Agent: sipX/3.8.1 sipX/registry (Linux)
Date: Fri, 18 Jan 2008 15:28:16 GMT
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, REGISTER, SUBSCRIBE
Accept-Language: en
Supported: gruu
Content-Length: 0

My phone is a E51, maybe n95 is not affected.

Let me know and thanks for now.

[thegapi]

Hi,
in my previous post cutting and pasting I probably deleted many informations.
I generated another log that explain the traffic flow.

Actually what happen is:
NOKIA E51 (REGISTER) -> PROXY
PROXY(REGISTER) -> REGISTRAR
REGISTRAR(401) -> PROXY
PROXY(401) -> E51

Studying the messages I belive the problem could be something else preventing the phone for further going on.


NOKIA E51 -> PROXY

<time>2008-01-18T16:46:28.454938Z</time>
<source>192.168.0.111:5060</source>
<destination>pbx.ictengineer.net-SipProxy</destination>
<sourceAddress>192.168.0.111:5060</sourceAddress>
<destinationAddress>pbx.ictengineer.net-SipProxy</destinationAddress>
<transactionId>1028,wso8jBv8oIcBhggopaWu_M3POPc_MW,fh00gjhv4lhc6s1o0gc6,</transactionId>
<method>REGISTER</method>
<frameId>1 sipproxy.xml:570</frameId>
<message><![CDATA[
REGISTER sipbx.ictengineer.net;transport=UDP SIP/2.0
Route: <sipbx.ictengineer.net;lr;transport=UDP>
Via: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bK3hoggjkbadhc6si00gc68uf;rport
From: <sip:TEST@pbx.ictengineer.net>;tag=fh00gjhv4lhc6s1o0gc6
To: <sip:TEST@pbx.ictengineer.net>
Contact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600
CSeq: 1028 REGISTER
Call-ID: wso8jBv8oIcBhggopaWu_M3POPc_MW
Supported: sec-agree
User-Agent: Nokia RM-244 100.34.20
Max-Forwards: 70
Content-Length: 0

]]></message>

PROXY -> REGISTRAR
<time>2008-01-18T16:46:28.463412Z</time>
<source>pbx.ictengineer.net-SipProxy</source>
<destination>pbx.ictengineer.net-SipRegistrar</destination>
<sourceAddress>pbx.ictengineer.net-SipProxy</sourceAddress>
<destinationAddress>pbx.ictengineer.net-SipRegistrar</destinationAddress>
<transactionId>1028,wso8jBv8oIcBhggopaWu_M3POPc_MW,fh00gjhv4lhc6s1o0gc6,</transactionId>
<method>REGISTER</method>
<frameId>2 sipproxy.xml:584 sipregistrar.xml:565</frameId>
<message><![CDATA[

REGISTER sipbx.ictengineer.net;transport=UDP SIP/2.0
From: <sip:TEST@pbx.ictengineer.net>;tag=fh00gjhv4lhc6s1o0gc6
To: <sip:TEST@pbx.ictengineer.net>
Contact: <sip:TEST@192.168.0.111;transport=UDP>;expires=3600
Cseq: 1028 REGISTER
Call-Id: wso8jBv8oIcBhggopaWu_M3POPc_MW
Supported: sec-agree
User-Agent: Nokia RM-244 100.34.20
Max-Forwards: 20
Content-Length: 0
Date: Fri, 18 Jan 2008 16:46:28 GMT
Via: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-1a012aa1605eac0733cd995abe0ef068
Via: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bK3hoggjkbadhc6si00gc68uf;rport=5060
Route: <sip:192.168.0.220:5070;transport=udp;lr>

]]></message>

REGISTRAR-> PROXY
<time>2008-01-18T16:46:28.476967Z</time>
<source>pbx.ictengineer.net-SipRegistrar</source>
<destination>pbx.ictengineer.net-SipProxy</destination>
<sourceAddress>pbx.ictengineer.net-SipRegistrar</sourceAddress>
<destinationAddress>pbx.ictengineer.net-SipProxy</destinationAddress>
<transactionId>1028,wso8jBv8oIcBhggopaWu_M3POPc_MW,fh00gjhv4lhc6s1o0gc6,</transactionId>
<responseCode>401</responseCode>
<responseText>Unauthorized</responseText>
<frameId>3 sipproxy.xml:588 sipregistrar.xml:581</frameId>
<message><![CDATA[

SIP/2.0 401 Unauthorized
From: <sip:TEST@pbx.ictengineer.net>;tag=fh00gjhv4lhc6s1o0gc6
To: <sip:TEST@pbx.ictengineer.net>
Call-Id: wso8jBv8oIcBhggopaWu_M3POPc_MW
Cseq: 1028 REGISTER
Via: SIP/2.0/UDP 192.168.0.220;branch=z9hG4bK-1a012aa1605eac0733cd995abe0ef068
Via: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bK3hoggjkbadhc6si00gc68uf;rport=5060
Www-Authenticate: Digest realm="ictengineer.net", nonce="6ed2d14cbb28d3338b10a02f27b306a31200674788"
User-Agent: sipX/3.8.1 sipX/registry (Linux)
Date: Fri, 18 Jan 2008 16:46:28 GMT
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, REGISTER, SUBSCRIBE
Accept-Language: en
Supported: gruu
Content-Length: 0

]]></message>


PROXY->E51
<time>2008-01-18T16:46:28.481302Z</time>
<source>pbx.ictengineer.net-SipProxy</source>
<destination>192.168.0.111:5060</destination>
<sourceAddress>pbx.ictengineer.net-SipProxy</sourceAddress>
<destinationAddress>192.168.0.111:5060</destinationAddress>
<transactionId>1028,wso8jBv8oIcBhggopaWu_M3POPc_MW,fh00gjhv4lhc6s1o0gc6,</transactionId>
<responseCode>401</responseCode>
<responseText>Unauthorized</responseText>
<frameId>4 sipproxy.xml:599</frameId>
<message><![CDATA[

SIP/2.0 401 Unauthorized
From: <sip:TEST@pbx.ictengineer.net>;tag=fh00gjhv4lhc6s1o0gc6
To: <sip:TEST@pbx.ictengineer.net>
Call-Id: wso8jBv8oIcBhggopaWu_M3POPc_MW
Cseq: 1028 REGISTER
Via: SIP/2.0/UDP 192.168.0.111:5060;branch=z9hG4bK3hoggjkbadhc6si00gc68uf;rport=5060
Www-Authenticate: Digest realm="ictengineer.net", nonce="6ed2d14cbb28d3338b10a02f27b306a31200674788"
User-Agent: sipX/3.8.1 sipX/registry (Linux)
Date: Fri, 18 Jan 2008 16:46:28 GMT
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, REGISTER, SUBSCRIBE
Accept-Language: en
Supported: gruu
Content-Length: 0

]]></message>


Basically the server respond to the phone with a first 401 Unauthorized message. The phone did not try to challenge authentication after that. Why?

Thanks in advance

[thegapi]

Well,
it's registering now. The problem was the realm.

And it's working very good!

Thanks anyway

[tsguy]

You said the problem was with the Realm. What exactly was the problem? I am having similar difficulties.
Thank you in advance.

[thegapi]

Hi,
I wrote in the realm field the server FQHN when my server defaults the domain only. This field in a nokia phone is case sensitive. I heard someone that couldn't connect just cause the capital letter ...
Anyway to check the exact value of your realm you should try the default field for your server first. If the phone doesn't register the last option is to capture sip message with a network analyzer and read there the realm value.

When the phone receive the first response from the server and the realm is different compared to the one you set up in settings it won't even try to authenticate!

Hope it could help!