Contents

Introduction

Problema

Troubleshoot

Caso de falha: (Logs do mecanismo)

Caso de sucesso: (Logs do mecanismo)

Solução

Introduction

Este documento descreve como solucionar problemas de falha de chamada VoLTE (Voice over Long Term Evolution) devido ao 5012 (DIAMETER_UNABLE_TO_COMPLY) no Cisco Policy Suite (CPS).

Problema

Falhas de chamada VoLTE relatadas devido a 5012 (DIAMETER_UNABLE_TO_COMPLY) no CPS.

Estes são os registros do mecanismo consolidado onde você pode ver o código de comando Rx_AAR (265) - Solicitação que foi recebida pelo CPS e resposta enviada de volta ao IMS (IP Multimedia Subsystem, Subsistema Multimídia IP) do CPS na interface Rx com o código de resultado DIAMETER_UNABLE_TO_COMPLY (5012).

=========  HOSTNAME-qnsXX  [yyyy-mm-dd 15:32:00,673] ============

POLICY RESULT ERROR:  null

session action = None

TRIGGER: Message: com.broadhop.diameter2.messages.DiameterRequestMessage

Application Id: Rx (16777236)

Command Code: Rx_AAR (265)

Dest realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Device protocol: RX_TGPP

End to end id: 204492743 (0x0c304fc7)

Hop by hop id: 2985189310 (0xb1ee5fbe)

Origin state: 0

.

.

.

SENT MESSAGES (asynchronous):  <<<<<<<<<<<

Message: com.broadhop.diameter2.messages.DiameterResponseMessage

Application Id: Rx (16777236)

Command Code: Rx_AAA (265)

Dest host: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Dest realm:XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Device protocol: RX_TGPP

End to end id: 204492743 (0x0c304fc7)

Hop by hop id: 2985189310 (0xb1ee5fbe)

Origin state: 0

Stack name: null

Origin realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Session-Id: pcsf-stdn.imsgroup1-111-1111111.aaa.sbc.bbb.com;316160;1;5.2551556468.2;pcsf-stdn.imsgroup1-111-1111111

Auth-Application-Id: 16777236

QPS-Internal-Route-Record-Host: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

QPS-Internal-Route-Record-Realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Result-Code: DIAMETER_UNABLE_TO_COMPLY (5012)  <<<<<<<<<<<<<

Troubleshoot

Aqui está o comportamento como visto nos registros consolidados do mecanismo:

Em caso de falha, os registros do mecanismo consolidado mostram o 5012 para a Solicitação de autorização/autenticação (AAR), quando marcada a Mensagem de solicitação, você pode ver que o CPS está imprimindo dados de codec no formato Hex [destacado em negrito].

Caso de falha: (Logs do mecanismo)

HOSTNAME-qnsXX   [yyyy-mm-dd 15:33:02,529] ===============================================

POLICY RESULT ERROR:  null

session action = None

TRIGGER: Message: com.broadhop.diameter2.messages.DiameterRequestMessage

Application Id: Rx (16777236)

Command Code: Rx_AAR (265)

Dest realm: YYYY.mncXYZ.mccXYZ.3gppnetwork.org

Device protocol: RX_TGPP

End to end id: 90747852 (0x0568b3cc)

Hop by hop id: 2509770985 (0x959810e9)

Origin state: 0

Stack name: null

Origin host: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Origin realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Session-Id: pcsf-stdn.imsgroup1-111-1111111.aaa.sbc.bbb.com;316160;1;5.2551556468.2;pcsf-stdn.imsgroup1-111-1111111

Auth-Application-Id: 16777236

Media-Component-Description:

Media-Component-Number: 1

Media-Sub-Component:

Flow-Number: 1

Flow-Description:

Media-Sub-Component:

Flow-Number: 2

Codec-Data: uplink\r\noffer\r\nm=audio 50010 RTP/AVP 99 97 105 96\r\na=rtpmap:99 AMR-WB/16000/1\r\na=fmtp:99 mode-change-capability=2;max-re

d=0\r\na=rtpmap:97 AMR/8000/1\r\na=fmtp:97 mode-change-capability=2;max-red=0\r\na=rtpmap:105 telephone-event/16000\r\na=fmtp:105 0-15\r\na=rtpmap:96 telephone-event/8000\r

\na=fmtp:96 0-15\r\na=curr:qos local none\r\na=curr:qos remote none\r\na=des:qos mandatory local sendrecv\r\na=des:qos optional remote sendrecv\r\na=ptime:20\r\na=maxptime:

240\r\n  (0x75706c696e6b0d0a6f666665720d0a6d3d617564696f203530303130205254502f415650203939203937203130352039360d0a613d7274706d61703a393920414d522d57422f31363030302f310d0a613

d666d74703a3939206d6f64652d6368616e67652d6361706162696c6974793d323b6d61782d7265643d300d0a613d7274706d61703a393720414d522f383030302f310d0a613d666d74703a3937206d6f64652d63686

16e67652d6361706162696c6974793d323b6d61782d7265643d300d0a613d7274706d61703a3130352074656c6570686f6e652d6576656e742f31363030300d0a613d666d74703a31303520302d31350d0a613d72747

06d61703a39362074656c6570686f6e652d6576656e742f383030300d0a613d666d74703a393620302d31350d0a613d637572723a716f73206c6f63616c206e6f6e650d0a613d637572723a716f732072656d6f74652

06e6f6e650d0a613d6465733a716f73206d616e6461746f7279206c6f63616c2073656e64726563760d0a613d6465733a716f73206f7074696f6e616c2072656d6f74652073656e64726563760d0a613d7074696d653

a32300d0a613d6d6178)

Codec-Data: downlink\r\nanswer\r\nm=audio 36602 RTP/AVP 97 96\r\na=rtpmap:97 AMR/8000/1\r\na=fmtp:97 mode-set=0,2,4,7; mode-change-period=2;

mode-change-capability=2; mode-change-neighbor=1; max-red=0\r\na=rtpmap:96 telephone-event/8000\r\na=fmtp:96 0-15\r\na=curr:qos local sendrecv\r\na=curr:qos remote none\r\

na=des:qos mandatory local sendrecv\r\na=des:qos mandatory remote sendrecv\r\na=conf:qos remote sendrecv\r\na=maxptime:40\r\n(0x646f776e6c696e6b0d0a616e737765720d0a6d3d617

564696f203336363032205254502f4156502039372039360d0a613d7274706d61703a393720414d522f383030302f310d0a613d666d74703a3937206d6f64652d7365743d302c322c342c373b206d6f64652d6368616

e67652d706572696f643d323b206d6f64652d6368616e67652d6361706162696c6974793d323b206d6f64652d6368616e67652d6e65696768626f723d313b206d61782d7265643d300d0a613d7274706d61703a39362

074656c6570686f6e652d6576656e742f383030300d0a613d666d74703a393620302d31350d0a613d637572723a716f73206c6f63616c2073656e64726563760d0a613d637572723a716f732072656d6f7465206e6f6

e650d0a613d6465733a716f73206d616e6461746f7279206c6f63616c2073656e64726563760d0a613d6465733a716f73206d616e6461746f72792072656d6f74652073656e64726563760d0a613d636f6e663a716f7

32072656d6f74652073656e64726563760d0a613d6d6178707)

DEBUG MSGS:

INFO   : (core) Tagging message with ID: RX_TGPP

INFO   : (rx) Sending 265 Message on session pcsf-stdn.imsgroup1-111-1111111.aaa.sbc.bbb.com;316160;1;5.2551556468.2;pcsf-stdn.imsgroup1-111-1111111

INFO   : (balance) Error found,  rolling back transaction

ERROR  : (core) Error processing policy request:  null

SERVICE CALC MSGS:

USE CASES:

SENT MESSAGES (asynchronous):

Message: com.broadhop.diameter2.messages.DiameterResponseMessage

Application Id: Rx (16777236)

Command Code: Rx_AAA (265)

Dest host: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Dest realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Device protocol: RX_TGPP

End to end id: 90747852 (0x0568b3cc)

Hop by hop id: 2509770985 (0x959810e9)

Origin state: 0

Stack name: null

Origin realm: YYYY.ims.mncXYZ.mccXYZ.3gppnetwork.org

Session-Id: pcsf-stdn.imsgroup1-111-1111111.aaa.sbc.bbb.com;316160;1;5.2551556468.2;pcsf-stdn.imsgroup1-111-1111111

Auth-Application-Id: 16777236

QPS-Internal-Route-Record-Host: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

QPS-Internal-Route-Record-Realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Result-Code: DIAMETER_UNABLE_TO_COMPLY (5012)

Caso de sucesso: (Logs do mecanismo)

Em caso de sucesso, nenhum dado Hex é visto no codec de uplink e downlink e nenhuma diferença na AAR em casos de sucesso e falha.

Para casos de sucesso, consulte INFORMAÇÕES: (núcleo) Carga por chave bem-sucedida: imsiFramedIpKey:11111111111111:172.16.xx.yy em DEBUG MSG que não estava presente no cenário de falha.

HOSTNAME-qnsXX  [yyyy-mm-dd 15:33:30,543]

POLICY RESULT SUCCESS:

session action = None

domainId = IMS

locationId = ims

SERVICES: IMS_DEFAULT

TRIGGER: Message: com.broadhop.diameter2.messages.DiameterRequestMessage

Application Id: Rx (16777236)

Command Code: Rx_AAR (265)

Dest realm: YYYY.ims.mncXYZ.mccXYZ.3gppnetwork.org

Device protocol: RX_TGPP

End to end id: 182837146 (0x0ae5df9a)

Hop by hop id: 2274945953 (0x8798eba1)

Origin state: 0

Stack name: null

Origin host: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Origin realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Session-Id: pcsf-stdn.imsgroup1-111-1111111.aaa.sbc.bbb.com;316160;1;5.2551556468.2;pcsf-stdn.imsgroup1-111-1111111

Auth-Application-Id: 16777236

Media-Component-Description:

Media-Component-Number: 1

Media-Sub-Component:

Flow-Number: 1

Media-Sub-Component:

Flow-Number: 2

Codec-Data: uplink\r\noffer\r\nm=audio 50008 RTP/AVP 116 107 118 96 111 110\r\na=rtpmap:116 AMR-WB/16000/1\r\na=fmtp:116 mode-change-capabil

ity=2;max-red=220\r\na=rtpmap:107 AMR-WB/16000/1\r\na=fmtp:107 octet-align=1;mode-change-capability=2;max-red=220\r\na=rtpmap:118 AMR/8000/1\r\na=fmtp:118 mode-change-capab

ility=2;max-red=220\r\na=rtpmap:96 AMR/8000/1\r\na=fmtp:96 octet-align=1;mode-change-capability=2;max-red=220\r\na=rtpmap:111 telephone-event/16000\r\na=fmtp:111 0-15\r\na=

rtpmap:110 telephone-event/8000\r\na=fmtp:110 0-15\r\na=curr:qos local none\r\na=curr:qos remote none\r\na=des:qos mandatory local sendrecv\r\na=des:qos optional remote sen

drecv\r\na=ptime:20\r\na=maxptime:240\r\n (uplink

offer

m=audio 50008 RTP/AVP 116 107 118 96 111 110 Note: Codec-Data is in readable form not like how it was in failure case.

a=rtpmap:116 AMR-WB/16000/1

a=fmtp:116 mode-change-capability=2;max-red=220

a=rtpmap:107 AMR-WB/16000/1

a=fmtp:107 octet-align=1;mode-change-capability=2;max-red=220

a=rtpmap:118 AMR/8000/1

a=fmtp:118 mode-change-capability=2;max-red=220

a=rtpmap:96 AMR/8000/1

a=fmtp:96 octet-align=1;mode-change-capability=2;max-red=220

a=rtpmap:111 telephone-event/16000

a=fmtp:111 0-15

a=rtpmap:110 telephone-event/8000

a=fmtp:110 0-15

a=curr:qos local none

a=curr:qos remote none

a=des:qos mandatory local sendrecv

a=des:qos optional remote sendrecv

a=ptime:20

a=maxptime:240

)

Codec-Data: downlink\r\nanswer\r\nm=audio 3302 RTP/AVP 118 110\r\na=rtpmap:118 AMR/8000/1\r\na=fmtp:118 mode-set=0,2,4,7; mode-change-period

=2; mode-change-capability=2; mode-change-neighbor=1; max-red=0\r\na=rtpmap:110 telephone-event/8000\r\na=fmtp:110 0-15\r\na=curr:qos local sendrecv\r\na=curr:qos remote no

ne\r\na=des:qos mandatory local sendrecv\r\na=des:qos mandatory remote sendrecv\r\na=conf:qos remote sendrecv\r\na=maxptime:40\r\n (downlink

answer

m=audio 3302 RTP/AVP 118 110

a=rtpmap:118 AMR/8000/1

a=fmtp:118 mode-set=0,2,4,7; mode-change-period=2; mode-change-capability=2; mode-change-neighbor=1; max-red=0

a=rtpmap:110 telephone-event/8000

a=fmtp:110 0-15

a=curr:qos local sendrecv

a=curr:qos remote none

a=des:qos mandatory local sendrecv

a=des:qos mandatory remote sendrecv

a=conf:qos remote sendrecv

a=maxptime:40

)

Media-Type: AUDIO (0)

Max-Requested-Bandwidth-UL: 64000

Max-Requested-Bandwidth-DL: 41000

Flow-Status: ENABLED (2)

RS-Bandwidth: 0

RR-Bandwidth: 0

Subscription-Id:

Subscription-Id-Type: END_USER_SIP_URI (2)

Subscription-Id-Data: sip:+911234567890@ims.mncXYZ.mccXYZ.3gppnetwork.org

Subscription-Id:

Subscription-Id-Type: END_USER_IMSI (1)

Subscription-Id-Data: <1111111111111111>

AF-Charging-Identifier: "PCSF:1-sbc"

Specific-Action: INDICATION_OF_LOSS_OF_BEARER (2)

Specific-Action: INDICATION_OF_RECOVERY_OF_BEARER (3)

Specific-Action: INDICATION_OF_RELEASE_OF_BEARER (4)

Specific-Action: IP_CAN_CHANGE (6)

Framed-IP-Address: 172.16.xx.yy (0x0aAAAAcB)

Supported-Features:

Vendor-Id: XXXX

Service-Info-Status: FINAL SERVICE INFORMATION (0)

QPS-Internal-Route-Record-Host: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

QPS-Internal-Route-Record-Realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

DEBUG MSGS:

INFO   : (core) Tagging message with ID: RX_TGPP

INFO   : (core) Successful load by key: imsiFramedIpKey:111111111111111:172.16.xx.yy  <<<<<<<<<

INFO   : (core) Start session triggered

INFO   : (rx) Success binding to Gx session 0008-diamproxy.AAAA.cisco.com;11111111;111111111;5c765a28-3b02 by IMSI And IP Address

INFO   : (TGPP) [Rel8, Rel9, Rel10] features supported for Vendor-Id XXXX, Feature-List-ID X

INFO   : (core) domain: IMS

INFO   : (rx) Sending AAA Message on session pcsf-stdn.imsgroup1-111-1111111.aaa.sbc.bbb.com;316160;1;5.2551556468.2;pcsf-stdn.imsgroup1-111-1111111

INFO   : (gx) Installing preconfigured rule: _1_1_2_AF_AUDIO

INFO   : (gx) Installing preconfigured rule: _1_1_1_AF_AUDIO

INFO   : (et) Event trigger 2 added

INFO   : (et) Event trigger 5 added

INFO   : (et) Event trigger 6 added

INFO   : (et) Event trigger 7 added

INFO   : (gx) Sending RAR Message on session 0008-diamproxy.AAAA.cisco.com;11111111;111111111;5c765a28-3b02

SERVICE CALC MSGS:

USE CASES:

INFO   : (use-cases) Use case 'XXXX IMS Default', status: true, Condition: No Condition Set

SENT MESSAGES (asynchronous):

Message: com.broadhop.diameter2.messages.DiameterResponseMessage

Application Id: Rx (16777236)

Command Code: Rx_AAA (265)

Dest host: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Dest realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Device protocol: RX_TGPP

End to end id: 182837146 (0x0ae5df9a)

Hop by hop id: 2274945953 (0x8798eba1)

Origin state: 0

Stack name: null

Origin realm: pcrf.mncXYZ.mccXYZ.3gppnetwork.org

Session-Id: pcsf-stdn.imsgroup1-111-1111111.aaa.sbc.bbb.com;316160;1;5.2551556468.2;pcsf-stdn.imsgroup1-111-1111111

Auth-Application-Id: 16777236

IP-CAN-Type: 3GPP_EPS (5)

RAT-Type: EUTRAN (1004)

Supported-Features:

Vendor-Id: XXXX

QPS-Internal-Route-Record-Host: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

QPS-Internal-Route-Record-Realm: XXXX.ims.mncXYZ.mccXYZ.3gppnetwork.org

Result-Code: DIAMETER_SUCCESS (2001)

Nos logs consolidados-qns também parece que a associação de Gx com sessão Rx não acontece. Isso pode ser visto nos logs consolidados-qns por uma exceção mostrada aqui.

HOSTNAME-qnsXX yyyy-mm-dd 02:23:03,445 [pool-3-thread-1] WARN  c.b.policy.impl.RulesPolicyService - An exception was thrown while executing a policy action.

java.lang.NullPointerException: null   <<<<<<<<<<

at com.broadhop.diameter2.policy.rx.tgpp.DiameterRxTGPPDeviceMgr.getGxSession(DiameterRxTGPPDeviceMgr.java:1104)

at com.broadhop.diameter2.policy.rx.tgpp.DiameterRxTGPPDeviceMgr.filterEvent(DiameterRxTGPPDeviceMgr.java:7418)

at com.broadhop.blueprint.master.MasterBlueprint.filterEvent(MasterBlueprint.java:1283)

at com.broadhop.blueprint.master.MasterBlueprint.executionPreSession2(MasterBlueprint.java:245)

at com.broadhop.policy.Rule_Execute_Presession_Phase_2__392a9765_1022_4823_b082_cde9f22abf37__e4BawWmREeKEu46lGH_XOQ__0.consequence(Unknown Source)

Solução

O acelerador de varredura completo do banco de dados está ativado e definido como 5. Observou-se que o número de RAAs de Rx aumenta e vai além de 110 (5*22) e, ao mesmo tempo, observam-se respostas de AAA de Rx 5012.

Isso porque a limitação está definida como 5 e há 22 VMs QNS e a verificação completa de DB permite apenas 110 solicitações.

Todas as outras solicitações estão sendo descartadas com a resposta do erro. Todas as solicitações AAR fazem uma varredura completa do banco de dados, pois ainda não configuraram a chave IMSIand framedIP em chaves de pesquisa.

Este é o procedimento para resolver o problema:

Etapa 1. Adicione imsiFramedIpKey em PB - cluster - prefixos de chave de pesquisa.

Etapa 2. Aguarde a publicação ser bem-sucedida (aguarde 5 minutos após a publicação).

Etapa 3. Volte para o terminal "Cluster Manager" e reinicie o processo qns usando o script restartall.sh.

Etapa 4. Quando a operação de reinicialização estiver concluída, execute o script diaganostics.sh para verificar se o CPS está ativo e em execução.

Etapa 5. Faça login no console QNS OSGI usando o comando telnet:

telnet <qns vm host name> 9091        [Eg:- telnet qns01 9091]

Execute “rebuildAllSkRings” command on OSGI console to rebuild the configured “Lookaside Key Prefixes” so qns process start utilizing it

osgi> rebuildAllSkRings

Please wait until execution completes

Execute “disconnect” command on console and it waits for user input just press “Enter” key to come out from OSGI console.

osgi> disconnect

Disconnect from console? (y/n; default=y)

Connection closed by foreign host.