Versions Compared

Old Version 2

changes.mady.by.user Keerthana Pandurangan

Saved on

compared with

New Version 3

changes.mady.by.user Chandrakanth Pokuru

Saved on

Key

  • This line was added.
  • This line was removed.
  • Formatting was changed.

Table of Contents
stylenone

Scope

                       The scope of this work is to explain webconfig feature validation procedure through RDKM Webconfig UI(http://webconfig.rdkcentral.com:9008/) for BPI Reference Platform .

Target Audience  

  • RDK-B Operators
  • RDK-B SoC Vendors
  • RDK-B OEM's
  • RDK-B Application Vendors
  • RDK-B System Integrators 

 High level Code Flow Design

WebConfig Client Arch Design 

Image Modified 

RDK Telemetry Overview via webconfig

            Image Modified

Supported device  

  • BPI R4 64 bit

Build Dependencies

  • webconfig-framework
  • webcfg(webconfig client library)
  • parodus2ccsp

TR-181 Data Model Parameters

ModuleTR-181 DM ParameterPurpose / Inputs





       


        WebConfig


dmcli eRT getv Device.X_RDK_WebConfig.RfcEnableenable/disable  webconfig
dmcli eRT getv Device.X_RDK_WebConfig.URL

By default this DM is empty.

It Should have webconfig server url with CPE MAC Address

dmcli eRT setv Device.X_RDK_WebConfig.URL string http://webconfig.rdkcentral.com:9007/api/v1/device/<CPE MAC>/config

replace CPE MAC and set URL

for ex: http://webconfig.rdkcentral.com:9007/api/v1/device/aabbccddeeff/config

dmcli eRT getv Device.X_RDK_WebConfig.URLverify URL set or not
dmcli eRT getv Device.X_RDK_WebConfig.ForceSync

Value "root" for ForceSync DM  - to sync all the values from webconfig server to CPE devices.

Value "telemetry" for ForceSync DM - to sync telemetryprofile data's

dmcli eRT getv Device.X_RDK_WebConfig.SupplementaryServiceUrls.Telemetry

By default this DM is empty.

It Should have webconfig server url with CPE MAC Address

dmcli eRT setv Device.X_RDK_WebConfig.SupplementaryServiceUrls.Telemetry string http://webconfig.rdkcentral.com:9007/api/v1/device/<CPE MAC>/config

replace CPE MAC and set URL .This is used for telemetry feature.

for ex: http://webconfig.rdkcentral.com:9007/api/v1/device/aabbccddeeff/config

dmcli eRT setv Device.X_RDK_WebConfig.SupplementaryServiceUrls.Telemetry string NULL 

Disabling WebConfig telemetry  Supplementary Services

Webconfig Settings

Code Block
languagebash
themeDJango
titlewebconfig settings - dmcli
collapsetrue
root@Filogic-GW:~# dmcli eRT getv Device.X_RDK_WebConfig.         
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.X_RDK_WebConfig.RfcEnable
               type:       bool,    value: true 
Parameter    2 name: Device.X_RDK_WebConfig.Data
               type:     string,    value: gap3ZWJjZmdibG9ikYWkbmFtZaRyb290p3ZlcnNpb24ApnN0YXR1c6dzdWNjZXNzrWVycm9yX2RldGFpbHOkbm9uZaplcnJvcl9jb2RlAA== 
Parameter    3 name: Device.X_RDK_WebConfig.SupportedDocs
               type:     string,    value: 16777247,33554435,50331649,67108865,83886081,100663297,117440513,134217729,201326594,218103809 
Parameter    4 name: Device.X_RDK_WebConfig.SupportedSchemaVersion
               type:     string,    value:  
Parameter    5 name: Device.X_RDK_WebConfig.webcfgSubdocForceReset
               type:     string,    value:  
Parameter    6 name: Device.X_RDK_WebConfig.URL
               type:     string,    value: http://webconfig.rdkcentral.com:9007/api/v1/device/8A976BAF3F90/config 
Parameter    7 name: Device.X_RDK_WebConfig.ForceSync
               type:     string,    value:  
Parameter    8 name: Device.X_RDK_WebConfig.SupplementaryServiceUrls.Telemetry
               type:     string,    value: http://webconfig.rdkcentral.com:9007/api/v1/device/8A976BAF3F90/config


Test Procedure and Results

 OneWiFi  validation

For privatessid subdoc

  1. Enter the below info in RDKM WebConfig UI & submit it

...

Code Block
languagebash
themeDJango
titlewebconfig success logs
collapsetrue
240425-08:17:40.189970 [mod=WEBCONFIG, lvl=INFO] [tid=4376] WEBCONFIG: doc privatessid is updated to version 289218514 status pending error_details none error_code 0 trans_id 0 temp->retry_count 0
240425-08:17:40.190537 [mod=WEBCONFIG, lvl=INFO] [tid=4376] WEBCONFIG: subdoc_name: privatessid, version: 289218514, transaction_id: 25012
240425-08:17:40.190669 [mod=WEBCONFIG, lvl=INFO] [tid=4376] WEBCONFIG: doc privatessid is updated to version 289218514 status pending error_details none error_code 0 trans_id 25012 temp->retry_count 0
240425-08:17:40.190736 [mod=WEBCONFIG, lvl=INFO] [tid=4376] WEBCONFIG: Request:> param[0].name = Device.WiFi.Private, type = 5
240425-08:17:40.195397 [mod=WEBCONFIG, lvl=INFO] [tid=2909] WEBCONFIG: Received msg privatessid,25012,289218514,ACK,90 from topic webconfigSignal
240425-08:17:40.195501 [mod=WEBCONFIG, lvl=INFO] [tid=2909] WEBCONFIG: Received webconfig event signal Info privatessid,25012,289218514,ACK,90
240425-08:17:40.195649 [mod=WEBCONFIG, lvl=INFO] [tid=4381] WEBCONFIG: TIMEOUT EVENT: privatessid,25012,289218514,ACK,90 (doc apply need time)
240425-08:17:40.195731 [mod=WEBCONFIG, lvl=INFO] [tid=4381] WEBCONFIG: doc apply need time, start timer.
240425-08:17:40.195799 [mod=WEBCONFIG, lvl=INFO] [tid=4381] WEBCONFIG: Timer list is empty
240425-08:17:40.195921 [mod=WEBCONFIG, lvl=INFO] [tid=4381] WEBCONFIG: new_node->subdoc_name privatessid new_node->txid 25012 new_node->timeout 90 status 1 added to list
240425-08:17:40.195984 [mod=WEBCONFIG, lvl=INFO] [tid=4381] WEBCONFIG: startWebcfgTimer success
240425-08:17:40.196150 [mod=WEBCONFIG, lvl=INFO] [tid=4377] WEBCONFIG: dest is event:subdoc-report/privatessid/mac:d83add3c5cc3/ack
240425-08:17:40.196231 [mod=WEBCONFIG, lvl=INFO] [tid=4377] WEBCONFIG: stringifiedNotifyPayload is {"device_id":"mac:d83add3c5cc3","namespace":"privatessid","application_status":"pending","timeout":90,"transaction_uuid":"c64bb03a-d4d0-4f30-9cfc-53bc18fd367b","version":"289218514"}
240425-08:17:40.196421 [mod=WEBCONFIG, lvl=INFO] [tid=4377] WEBCONFIG: Notification successfully sent to Webconfig.Upstream
240425-08:17:40.197419 [mod=WEBCONFIG, lvl=INFO] [tid=4376] WEBCONFIG: The ret status for rbus_setMulti is 0
240425-08:17:40.197505 [mod=WEBCONFIG, lvl=INFO] [tid=4376] WEBCONFIG: ccspRetStatus is 100 240425-08:17:40.227876 [mod=WEBCONFIG, lvl=INFO] [tid=4376] WEBCONFIG: Request:> param[0].name = Device.WiFi.Private, type = 5
240425-08:17:40.228193 [mod=WEBCONFIG, lvl=INFO] [tid=2909] WEBCONFIG: Received msg privatessid,25012,289218514,ACK,0 from topic webconfigSignal
240425-08:17:40.228284 [mod=WEBCONFIG, lvl=INFO] [tid=2909] WEBCONFIG: Received webconfig event signal Info privatessid,25012,289218514,ACK,0
240425-08:17:40.228459 [mod=WEBCONFIG, lvl=INFO] [tid=4381] WEBCONFIG: ACK EVENT: privatessid,25012,289218514,ACK,0 (doc apply success)
240425-08:17:40.228526 [mod=WEBCONFIG, lvl=INFO] [tid=4381] WEBCONFIG: doc apply success, proceed to add to DB

PAM Validation

For Lan subdoc

  1. Enter the below info in WebConfig UI & submit it.

...

Code Block
languagebash
themeDJango
titlePAM logs
collapsetrue
240423-06:06:16.537008 [mod=PAM, lvl=WARN] [tid=1463] base64 decoded data contains 194 bytes
240423-06:06:16.537072 [mod=PAM, lvl=WARN] [tid=1463] Base64 decode Elapsed time : 0 ms
240423-06:06:16.537135 [mod=PAM, lvl=WARN] [tid=1463] MSGPACK_UNPACK_SUCCESS :2
240423-06:06:16.537222 [mod=PAM, lvl=WARN] [tid=1463] pLanInfo->entries_count is 1
240423-06:06:16.537279 [mod=PAM, lvl=WARN] [tid=1463] pLanInfo->subdoc_name is lan
240423-06:06:16.537335 [mod=PAM, lvl=WARN] [tid=1463] pLanInfo->version is 627376240
240423-06:06:16.537391 [mod=PAM, lvl=WARN] [tid=1463] pLanInfo->transaction_id is 8517
240423-06:06:16.537446 [mod=PAM, lvl=WARN] [tid=1463] Lan configuration received
240423-06:06:16.537508 [mod=PAM, lvl=INFO] [tid=1463] PushBlobRequest : subdoc_name lan , txid 8517, version 627376240 , entries 1
240423-06:06:16.537581 [mod=PAM, lvl=INFO] [tid=1463] Inside FUNC checkNewVersionUpdateRequired LINE 1244 
240423-06:06:16.537645 [mod=PAM, lvl=INFO] [tid=1463] checkIfVersionExecInQueue : Queue Empty
240423-06:06:16.537707 [mod=PAM, lvl=INFO] [tid=1463] New version available , prcessing new Blob request
240423-06:06:16.537785 [mod=PAM, lvl=INFO] [tid=1463] calculateTimeout is NULL , using default timeout routine
240423-06:06:16.537843 [mod=PAM, lvl=INFO] [tid=1463] PushBlobRequest timeout received from calcTimeout is 13
240423-06:06:16.537902 [mod=PAM, lvl=INFO] [tid=1463] getPendingQueueTimeout : Queue Empty
240423-06:06:16.537962 [mod=PAM, lvl=INFO] [tid=1463] PushBlobRequest : Send received request ACK , timeout is 13
240423-06:06:16.538023 [mod=PAM, lvl=INFO] [tid=1463] send_ACK : doc name lan , doc version 627376240, txid is 8517  timeout is 13
240423-06:06:16.538083 [mod=PAM, lvl=INFO] [tid=1463] isWebCfgRbusEnabled: rbus enabled is 1 
240423-06:06:16.538139 [mod=PAM, lvl=INFO] [tid=1463] sendWebConfigSignal : rbus_set :: event_name : webconfigSignal :: 
240423-06:06:16.539767 [mod=PAM, lvl=WARN] [tid=1463] PushBlobRequest complete
240423-06:06:16.539854 [mod=PAM, lvl=WARN] [tid=1463] Before committing:Device.DHCPv4.Server.
240423-06:06:16.539979 [mod=PAM, lvl=WARN] [tid=1463] After committing:Device.DHCPv4.Server.:0
240423-06:06:16.540003 [mod=PAM, lvl=INFO] [tid=1716] Received event
240423-06:06:16.540284 [mod=PAM, lvl=INFO] [tid=24223] Inside FUNC execute_request LINE 854 
240423-06:06:16.544687 [mod=PAM, lvl=WARN] [tid=24223]  Dhcpv4_Reset_Cache done 
240423-06:06:16.544767 [mod=PAM, lvl=WARN] [tid=24223] CheckIpIsValid:IpAddressReceivedIs:10.0.0.1
240423-06:06:16.544829 [mod=PAM, lvl=WARN] [tid=24223] CheckIpIsValid:IpAddressReceivedIs:10.0.0.8
240423-06:06:16.544887 [mod=PAM, lvl=WARN] [tid=24223] CheckIpIsValid:IpAddressReceivedIs:10.0.0.240
240423-06:06:16.544952 [mod=PAM, lvl=WARN] [tid=24223] Dhcpv4_Lan_Ip_IsInPrivate_Blocked_AddrRange:addr:a000001
240423-06:06:16.545015 [mod=PAM, lvl=WARN] [tid=24223]  Entering Dhcpv4_Lan_BackupFromDb 
240423-06:06:16.551077 [mod=PAM, lvl=WARN] [tid=24223]  Dhcpv4_Cache_ApplyAllToDb line.no 328 numOfParam 6
240423-06:06:16.556649 [mod=PAM, lvl=WARN] [tid=24223]  Dhcpv4_Cache_ApplyAllToDb line.no 344 Success 
240423-06:06:16.557618 [mod=PAM, lvl=NOTICE] [tid=24223] CosaDmlDhcpsGetPool: ulIndex = 0
240423-06:06:16.559081 [mod=PAM, lvl=WARN] [tid=24223] Process_Lan_WebConfigRequest: setting lan-restart
240423-06:06:16.573772 [mod=PAM, lvl=WARN] [tid=1463] CosaNTPInitJournal : Partner = RDKM 
240423-06:06:16.577499 [mod=PAM, lvl=WARN] [tid=1463] CosaNTPInitJournal : Partner = RDKM 
240423-06:06:16.635650 [mod=PAM, lvl=INFO] [tid=1716] messageQueueProcessing : Execution success , sending completed ACK
240423-06:06:16.640807 [mod=PAM, lvl=INFO] [tid=1716] send_ACK : doc name lan , doc version 627376240, txid is 8517  timeout is 0
240423-06:06:16.640906 [mod=PAM, lvl=INFO] [tid=1716] isWebCfgRbusEnabled: rbus enabled is 1 
240423-06:06:16.640956 [mod=PAM, lvl=INFO] [tid=1716] sendWebConfigSignal : rbus_set :: event_name : webconfigSignal :: 
240423-06:06:16.646741 [mod=PAM, lvl=WARN] [tid=1716]  Entering FreeResources_Lan

For DMZ subdoc

  1. Enter the below info in WebConfig UI & submit it.

...

Code Block
languagebash
themeDJango
titlePAM logs
collapsetrue
240423-06:14:24.592125 [mod=PAM, lvl=WARN] [tid=1463] base64 decoded data contains 82 bytes
240423-06:14:24.592168 [mod=PAM, lvl=WARN] [tid=1463] Base64 decode Elapsed time : 0 ms
240423-06:14:24.592212 [mod=PAM, lvl=WARN] [tid=1463] MSGPACK_UNPACK_SUCCESS :2
240423-06:14:24.592259 [mod=PAM, lvl=WARN] [tid=1463] rpm->subdoc_name is wan
240423-06:14:24.592300 [mod=PAM, lvl=WARN] [tid=1463] rpm->version is 3808881139
240423-06:14:24.592339 [mod=PAM, lvl=WARN] [tid=1463] rpm->transaction_id is 5016
240423-06:14:24.592377 [mod=PAM, lvl=WARN] [tid=1463] DMZ/wan  configuration received
240423-06:14:24.592421 [mod=PAM, lvl=INFO] [tid=1463] PushBlobRequest : subdoc_name wan , txid 5016, version 3808881139 , entries 0
240423-06:14:24.592462 [mod=PAM, lvl=INFO] [tid=1463] Inside FUNC checkNewVersionUpdateRequired LINE 1244 
240423-06:14:24.592519 [mod=PAM, lvl=INFO] [tid=1463] checkIfVersionExecInQueue : Queue Empty
240423-06:14:24.592559 [mod=PAM, lvl=INFO] [tid=1463] New version available , prcessing new Blob request
240423-06:14:24.592616 [mod=PAM, lvl=INFO] [tid=1463] calculateTimeout is NULL , using default timeout routine
240423-06:14:24.592657 [mod=PAM, lvl=INFO] [tid=1463] PushBlobRequest timeout received from calcTimeout is 10
240423-06:14:24.592696 [mod=PAM, lvl=INFO] [tid=1463] getPendingQueueTimeout : Queue Empty
240423-06:14:24.592738 [mod=PAM, lvl=INFO] [tid=1463] PushBlobRequest : Send received request ACK , timeout is 10
240423-06:14:24.592780 [mod=PAM, lvl=INFO] [tid=1463] send_ACK : doc name wan , doc version 3808881139, txid is 5016  timeout is 10
240423-06:14:24.592825 [mod=PAM, lvl=INFO] [tid=1463] isWebCfgRbusEnabled: rbus enabled is 1 
240423-06:14:24.592867 [mod=PAM, lvl=INFO] [tid=1463] sendWebConfigSignal : rbus_set :: event_name : webconfigSignal :: 
240423-06:14:24.594806 [mod=PAM, lvl=WARN] [tid=1463] PushBlobRequest complete
240423-06:14:24.594940 [mod=PAM, lvl=WARN] [tid=1463] Before committing:Device.NAT.X_CISCO_COM_DMZ.
240423-06:14:24.595028 [mod=PAM, lvl=WARN] [tid=1463] !!!!!! in X_CISCO_COM_DMZ_Commit pDmz->InternalIP: 0.0.0.0 !!!!!!
240423-06:14:24.595118 [mod=PAM, lvl=INFO] [tid=1716] Received event
240423-06:14:24.595157 [mod=PAM, lvl=WARN] [tid=1463] After committing:Device.NAT.X_CISCO_COM_DMZ.:0
240423-06:14:24.595385 [mod=PAM, lvl=INFO] [tid=32256] Inside FUNC execute_request LINE 854 
240423-06:14:24.595454 [mod=PAM, lvl=WARN] [tid=32256] DMZ configurartion recieved!!!!!!
240423-06:14:24.595508 [mod=PAM, lvl=WARN] [tid=32256] dmz_cache backup [0].cmd - dmz_enabled
240423-06:14:24.595551 [mod=PAM, lvl=WARN] [tid=32256] dmz_cache backup [0].val - 
240423-06:14:24.595604 [mod=PAM, lvl=WARN] [tid=32256] dmz_cache backup [1].cmd - dmz_dst_ip_addr
240423-06:14:24.595646 [mod=PAM, lvl=WARN] [tid=32256] dmz_cache backup [1].val - 
240423-06:14:24.607573 [mod=PAM, lvl=INFO] [tid=32256] CheckIfIpIsValid:IpAddressReceivedIs:10.0.0.17
240423-06:14:24.608313 [mod=PAM, lvl=WARN] [tid=32256] dmz_cache apply to DB [0].cmd - dmz_enabled
240423-06:14:24.608395 [mod=PAM, lvl=WARN] [tid=32256] dmz_cache apply to DB [0].val - 1
240423-06:14:24.608448 [mod=PAM, lvl=WARN] [tid=32256] dmz_cache apply to DB [1].cmd - dmz_dst_ip_addr
240423-06:14:24.608490 [mod=PAM, lvl=WARN] [tid=32256] dmz_cache apply to DB [1].val - 10.0.0.17
240423-06:14:24.613281 [mod=PAM, lvl=WARN] [tid=32256] DMZ configurartion applied!!!!!!!!
240423-06:14:24.613371 [mod=PAM, lvl=NOTICE] [tid=32256] DMZ_split:Feature Switch DMZ 1
240423-06:14:24.618447 [mod=PAM, lvl=WARN] [tid=1463] CosaNTPInitJournal : Partner = RDKM 
240423-06:14:24.628410 [mod=PAM, lvl=WARN] [tid=1463] CosaNTPInitJournal : Partner = RDKM 
240423-06:14:24.649677 [mod=PAM, lvl=WARN] [tid=32256] DMZ configurartion applied to DML layer!!!!!!!!
240423-06:14:24.649780 [mod=PAM, lvl=WARN] [tid=32256] rpm->param[0].Enable true
240423-06:14:24.649826 [mod=PAM, lvl=WARN] [tid=32256] rpm->entries[0].internal_ip 10.0.0.17
240423-06:14:24.650282 [mod=PAM, lvl=INFO] [tid=1716] messageQueueProcessing : Execution success , sending completed ACK
240423-06:14:24.655732 [mod=PAM, lvl=INFO] [tid=1716] send_ACK : doc name wan , doc version 3808881139, txid is 5016  timeout is 0
240423-06:14:24.655818 [mod=PAM, lvl=INFO] [tid=1716] isWebCfgRbusEnabled: rbus enabled is 1 
240423-06:14:24.655866 [mod=PAM, lvl=INFO] [tid=1716] sendWebConfigSignal : rbus_set :: event_name : webconfigSignal :: 
240423-06:14:24.667342 [mod=PAM, lvl=INFO] [tid=1716]  Entering freeResources_dmz

For PortMapping subdoc

  1. Enter the below info in WebConfig UI  & submit it.

...

Code Block
languagebash
themeDJango
titlePAM Logs
collapsetrue
240423-06:29:55.137534 [mod=PAM, lvl=WARN] [tid=1463] base64 decoded data contains 419 bytes
240423-06:29:55.137628 [mod=PAM, lvl=WARN] [tid=1463] Base64 decode Elapsed time : 0 ms
240423-06:29:55.137694 [mod=PAM, lvl=WARN] [tid=1463] MSGPACK_UNPACK_SUCCESS :2
240423-06:29:55.137794 [mod=PAM, lvl=WARN] [tid=1463] rpm->entries_count is 3
240423-06:29:55.137853 [mod=PAM, lvl=WARN] [tid=1463] rpm->subdoc_name is portforwarding
240423-06:29:55.137910 [mod=PAM, lvl=WARN] [tid=1463] rpm->version is 3329431262
240423-06:29:55.137966 [mod=PAM, lvl=WARN] [tid=1463] rpm->transaction_id is 63776
240423-06:29:55.138021 [mod=PAM, lvl=WARN] [tid=1463] Portmap configuration received
240423-06:29:55.138083 [mod=PAM, lvl=INFO] [tid=1463] PushBlobRequest : subdoc_name portforwarding , txid 63776, version 3329431262 , entries 3
240423-06:29:55.138142 [mod=PAM, lvl=INFO] [tid=1463] Inside FUNC checkNewVersionUpdateRequired LINE 1244 
240423-06:29:55.138253 [mod=PAM, lvl=INFO] [tid=1463] checkIfVersionExecInQueue : Queue Empty
240423-06:29:55.138311 [mod=PAM, lvl=INFO] [tid=1463] New version available , prcessing new Blob request
240423-06:29:55.138399 [mod=PAM, lvl=INFO] [tid=1463] calculateTimeout is NULL , using default timeout routine
240423-06:29:55.138459 [mod=PAM, lvl=INFO] [tid=1463] PushBlobRequest timeout received from calcTimeout is 19
240423-06:29:55.138516 [mod=PAM, lvl=INFO] [tid=1463] getPendingQueueTimeout : Queue Empty
240423-06:29:55.138574 [mod=PAM, lvl=INFO] [tid=1463] PushBlobRequest : Send received request ACK , timeout is 19
240423-06:29:55.138633 [mod=PAM, lvl=INFO] [tid=1463] send_ACK : doc name portforwarding , doc version 3329431262, txid is 63776  timeout is 19
240423-06:29:55.138692 [mod=PAM, lvl=INFO] [tid=1463] isWebCfgRbusEnabled: rbus enabled is 1 
240423-06:29:55.138749 [mod=PAM, lvl=INFO] [tid=1463] sendWebConfigSignal : rbus_set :: event_name : webconfigSignal :: 
240423-06:29:55.140790 [mod=PAM, lvl=WARN] [tid=1463] PushBlobRequest complete
240423-06:29:55.140874 [mod=PAM, lvl=INFO] [tid=1716] Received event
240423-06:29:55.141283 [mod=PAM, lvl=INFO] [tid=15686] Inside FUNC execute_request LINE 854 
240423-06:29:55.141378 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries_count is 3
240423-06:29:55.141438 [mod=PAM, lvl=WARN] [tid=15686] Portmap configurartion recieved
240423-06:29:55.165710 [mod=PAM, lvl=WARN] [tid=1463] CosaNTPInitJournal : Partner = RDKM 
240423-06:29:55.170084 [mod=PAM, lvl=WARN] [tid=1463] CosaNTPInitJournal : Partner = RDKM 
240423-06:29:55.180459 [mod=PAM, lvl=INFO] [tid=15686] CheckIfIpIsValid:IpAddressReceivedIs:10.0.0.111
240423-06:29:55.180571 [mod=PAM, lvl=INFO] [tid=15686] CheckIfPortsAreValid:ExternalPortEndRangeReceivedIs:23
240423-06:29:55.180627 [mod=PAM, lvl=INFO] [tid=15686] CheckIfPortsAreValid:ExternalPortEndRangeReceivedIs:23
240423-06:29:55.180699 [mod=PAM, lvl=INFO] [tid=15686] CheckIfIpIsValid:IpAddressReceivedIs:10.0.0.112
240423-06:29:55.180748 [mod=PAM, lvl=INFO] [tid=15686] CheckIfPortsAreValid:ExternalPortEndRangeReceivedIs:25
240423-06:29:55.180793 [mod=PAM, lvl=INFO] [tid=15686] CheckIfPortsAreValid:ExternalPortEndRangeReceivedIs:25
240423-06:29:55.180875 [mod=PAM, lvl=INFO] [tid=15686] CheckIfIpIsValid:IpAddressReceivedIs:10.0.0.102
240423-06:29:55.180922 [mod=PAM, lvl=INFO] [tid=15686] CheckIfPortsAreValid:ExternalPortEndRangeReceivedIs:443
240423-06:29:55.180967 [mod=PAM, lvl=INFO] [tid=15686] CheckIfPortsAreValid:ExternalPortEndRangeReceivedIs:443
240423-06:29:55.219712 [mod=PAM, lvl=WARN] [tid=15686] Portmap configurartion applied
240423-06:29:55.219820 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[0].InternalClient 10.0.0.111
240423-06:29:55.219873 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[0].ExternalPortEndRange 23
240423-06:29:55.219922 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[0].Enable true
240423-06:29:55.219973 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[0].Protocol TCP/UDP
240423-06:29:55.220024 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[0].Description telnet
240423-06:29:55.220075 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[0].external_port 23
240423-06:29:55.220162 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[1].InternalClient 10.0.0.112
240423-06:29:55.220218 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[1].ExternalPortEndRange 25
240423-06:29:55.220265 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[1].Enable true
240423-06:29:55.220311 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[1].Protocol TCP/UDP
240423-06:29:55.220356 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[1].Description mail
240423-06:29:55.220401 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[1].external_port 25
240423-06:29:55.220443 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[2].InternalClient 10.0.0.102
240423-06:29:55.220492 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[2].ExternalPortEndRange 443
240423-06:29:55.220532 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[2].Enable true
240423-06:29:55.220572 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[2].Protocol TCP/UDP
240423-06:29:55.220611 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[2].Description https
240423-06:29:55.220650 [mod=PAM, lvl=WARN] [tid=15686] rpm->entries[2].external_port 443
240423-06:29:55.220749 [mod=PAM, lvl=INFO] [tid=1716] messageQueueProcessing : Execution success , sending completed ACK
240423-06:29:55.225331 [mod=PAM, lvl=INFO] [tid=1716] send_ACK : doc name portforwarding , doc version 3329431262, txid is 63776  timeout is 0
240423-06:29:55.225405 [mod=PAM, lvl=INFO] [tid=1716] isWebCfgRbusEnabled: rbus enabled is 1 
240423-06:29:55.225449 [mod=PAM, lvl=INFO] [tid=1716] sendWebConfigSignal : rbus_set :: event_name : webconfigSignal :: 
240423-06:29:55.227354 [mod=PAM, lvl=INFO] [tid=1716]  Entering freeResources_PortForwarding


Telemetry Multiprofiles Validation

  1.   please refer this link WebConfig, XConf & T2#CreatingaT2ReportProfileinXConf to create a T2 profiles in XCONF server
  2.   Please refer this link WebConfig, XConf & T2#CreatingaT2RuleinXConf to create a T2 rules in XCONF server
  3.  Ensure SupplementaryServiceUrls should be set properly like below

...

Code Block
languagebash
themeDJango
titletelemetry2_logs
collapsetrue
40411-13:22:47.374561 [mod=T2, lvl=INFO] [tid=3697] Successfully enabled profile : DM_DEVICEINFO_PROFILE
240411-13:22:47.374647 [mod=T2, lvl=INFO] [tid=3697] T2ER isn't initialized or dispatch thread is already running
240411-13:22:47.376483 [mod=T2, lvl=INFO] [tid=3697] Starting TimeoutThread for profile : RDKB-RPI-SELFHEALPROFILE
240411-13:22:47.376784 [mod=T2, lvl=INFO] [tid=3697] T2ER isn't initialized or dispatch thread is already running
240411-13:22:47.376841 [mod=T2, lvl=INFO] [tid=3697] Successfully enabled profile : RDKB-RPI-SELFHEALPROFILE
240411-13:22:47.376894 [mod=T2, lvl=INFO] [tid=3697] T2ER isn't initialized or dispatch thread is already running
240411-13:22:47.377527 [mod=T2, lvl=INFO] [tid=3700] Waiting for 600 sec for next TIMEOUT for profile as reporting interval is taken - RDKB-RPI-SELFHEALPROFILE
240411-13:22:47.377646 [mod=T2, lvl=INFO] [tid=3700] Waiting for timeref or reporting interval for the profile - RDKB-RPI-SELFHEALPROFILE is started
240411-13:22:47.378491 [mod=T2, lvl=INFO] [tid=3697] Starting TimeoutThread for profile : RDKB-RPI-BootTime-Logs
240411-13:22:47.378733 [mod=T2, lvl=INFO] [tid=3697] T2ER isn't initialized or dispatch thread is already running
240411-13:22:47.378789 [mod=T2, lvl=INFO] [tid=3697] Successfully enabled profile : RDKB-RPI-BootTime-Logs
240411-13:22:47.378849 [mod=T2, lvl=INFO] [tid=3697] T2ER isn't initialized or dispatch thread is already running
240411-13:22:47.379017 [mod=T2, lvl=INFO] [tid=3701] Waiting for 600 sec for next TIMEOUT for profile as reporting interval is taken - RDKB-RPI-BootTime-Logs
240411-13:22:47.379153 [mod=T2, lvl=INFO] [tid=3701] Waiting for timeref or reporting interval for the profile - RDKB-RPI-BootTime-Logs is started
240411-13:22:47.379195 [mod=T2, lvl=INFO] [tid=3698] Waiting for 600 sec for next TIMEOUT for profile as reporting interval is taken - DM_DEVICEINFO_PROFILE
240411-13:22:47.379274 [mod=T2, lvl=INFO] [tid=3698] Waiting for timeref or reporting interval for the profile - DM_DEVICEINFO_PROFILE is started
240411-13:22:47.395270 [mod=T2, lvl=INFO] [tid=3697] rbuscore.c:2326 RBus Enabled

240411-13:22:50.562716 [mod=T2, lvl=INFO] [tid=2464] rbus.c:1014 Consumer=rbus.meshAgent.INBOX.3316 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated

240411-13:22:50.562857 [mod=T2, lvl=INFO] [tid=2464] eventSubHandler called:
 	action=subscribe 	eventName=Telemetry.ReportProfiles.ProfilesUpdated
240424-07:52:36.000206 [mod=T2, lvl=INFO] [tid=18653] TIMEOUT for profile - RDKB-RPI-SELFHEALPROFILE
240424-07:52:36.000381 [mod=T2, lvl=INFO] [tid=18653] ReportProfiles_TimeoutCb ++in
240424-07:52:36.000411 [mod=T2, lvl=INFO] [tid=18654] TIMEOUT for profile - RDKB-RPI-BootTime-Logs
240424-07:52:36.000506 [mod=T2, lvl=INFO] [tid=18653] NotifyTimeout: profile RDKB-RPI-SELFHEALPROFILE is in Enabled state
240424-07:52:36.000523 [mod=T2, lvl=INFO] [tid=18654] ReportProfiles_TimeoutCb ++in
240424-07:52:36.000706 [mod=T2, lvl=INFO] [tid=18654] NotifyTimeout: profile RDKB-RPI-BootTime-Logs is in Enabled state
240424-07:52:36.000796 [mod=T2, lvl=INFO] [tid=28829] CollectAndReport ++in profileName : RDKB-RPI-SELFHEALPROFILE
240424-07:52:36.000737 [mod=T2, lvl=INFO] [tid=18653] ReportProfiles_TimeoutCb --out
240424-07:52:36.000989 [mod=T2, lvl=INFO] [tid=18653] Waiting for 600 sec for next TIMEOUT for profile as reporting interval is taken - RDKB-RPI-SELFHEALPROFILE
240424-07:52:36.001022 [mod=T2, lvl=INFO] [tid=18654] ReportProfiles_TimeoutCb --out
240424-07:52:36.001073 [mod=T2, lvl=INFO] [tid=28830] CollectAndReport ++in profileName : RDKB-RPI-BootTime-Logs
240424-07:52:36.001092 [mod=T2, lvl=INFO] [tid=18653] Waiting for timeref or reporting interval for the profile - RDKB-RPI-SELFHEALPROFILE is started
240424-07:52:36.001121 [mod=T2, lvl=INFO] [tid=18654] Waiting for 600 sec for next TIMEOUT for profile as reporting interval is taken - RDKB-RPI-BootTime-Logs
240424-07:52:36.001393 [mod=T2, lvl=INFO] [tid=18654] Waiting for timeref or reporting interval for the profile - RDKB-RPI-BootTime-Logs is started
240424-07:52:36.002013 [mod=T2, lvl=ERROR] [tid=28829] rbus.c:3660 Discover component names failed with error 6 and counts 1/0

240424-07:52:36.002104 [mod=T2, lvl=ERROR] [tid=28829] Failed to retrieve param : Device.DeviceInfo.X_RDK.RDKProfileName
240424-07:52:36.002504 [mod=T2, lvl=ERROR] [tid=28830] rbus.c:3660 Discover component names failed with error 6 and counts 1/0

240424-07:52:36.002607 [mod=T2, lvl=ERROR] [tid=28830] Failed to retrieve param : Device.DeviceInfo.X_RDK.RDKProfileName
240424-08:14:34.637693 [mod=T2, lvl=INFO] [tid=8850] cJSON Report = {"Report":[{"Profile.Name":"RDKB-RPI-BootTime-Logs"},{"Profile.Version":"1"},{"MAC":"D8:3A:DD:3C:5C:C3"},{"UpTime":"304"},{"bootuptime_Ethernet_split":"=20"},{"bootuptime_wifi_split":"=31"},{"bootuptime_tr069_split":"=31"},{"waninit_split":"=28"},{"bootuptime_webpa_split":"=35"},{"bootuptime_meshagent_split":"=37"}]}
240424-08:14:34.637829 [mod=T2, lvl=INFO] [tid=8850] Report Size = 318
240424-08:14:34.689539 [mod=T2, lvl=ERROR] [tid=8850] getParameterValue partner id failed
240424-08:14:34.803536 [mod=T2, lvl=INFO] [tid=8851] cJSON Report = {"Report":[{"Profile.Name":"RDKB-RPI-SELFHEALPROFILE"},{"Profile.Version":"1"},{"Time":"2024-04-24T08:14:33Z"},{"mac":"d8:3a:dd:3c:5c:c3"},{"erouterIpv4":"192.168.2.150"},{"PartnerId":"RDKM"},{"Version":"rdkb-generic-broadband-image_rdk-next_20240411132237"},{"MAC":"D8:3A:DD:3C:5C:C3"},{"UpTime":"304"},{"WAN_SSH_STATUS":"false"}]}
240424-08:14:34.803688 [mod=T2, lvl=INFO] [tid=8851] Report Size = 332
240424-08:14:35.173337 [mod=T2, lvl=INFO] [tid=8976] FILE:../../../../../../../../../rdk/components/generic/telemetry/source/bulkdata/reportprofiles.c	 FUNCTION:Process_Telemetry_WebConfigRequest	 LINE:863
240424-08:14:35.173513 [mod=T2, lvl=INFO] [tid=8976] FILE:../../../../../../../../../rdk/components/generic/telemetry/source/bulkdata/reportprofiles.c	 FUNCTION:Process_Telemetry_WebConfigRequest	 LINE:867 Execution in Handler, excuted 
240424-08:14:35.173707 [mod=T2, lvl=INFO] [tid=8976] Number of report profiles in current configuration is 3 
240424-08:14:35.173827 [mod=T2, lvl=INFO] [tid=8976] rbuscore.c:2326 RBus Enabled

240424-08:14:35.173945 [mod=T2, lvl=INFO] [tid=8976] DM_DEVICEINFO_PROFILE is found 
240424-08:14:35.173996 [mod=T2, lvl=INFO] [tid=8976] RDKB-RPI-SELFHEALPROFILE is found 
240424-08:14:35.174067 [mod=T2, lvl=INFO] [tid=8976] RDKB-RPI-BootTime-Logs is found 
240424-08:14:35.174124 [mod=T2, lvl=INFO] [tid=8976] rbuscore.c:2326 RBus Enabled

240424-08:14:35.174388 [mod=T2, lvl=INFO] [tid=8976] Profile DM_DEVICEINFO_PROFILE with cf3d1060 hash already exist 
240424-08:14:35.174463 [mod=T2, lvl=INFO] [tid=8976] Profile RDKB-RPI-SELFHEALPROFILE with de899a23 hash already exist 
240424-08:14:35.174528 [mod=T2, lvl=INFO] [tid=8976] Profile RDKB-RPI-BootTime-Logs with 3f7b4f1b hash already exist 
240424-08:14:35.174675 [mod=T2, lvl=INFO] [tid=8976] rbuscore.c:2326 RBus Enabled

240424-08:14:35.655881 [mod=T2, lvl=INFO] [tid=8850] The return status from the child with pid 8911 is CurlStatus : 1
240424-08:14:35.655998 [mod=T2, lvl=INFO] [tid=8850] The return status from the child with pid 8911 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 335 
240424-08:14:35.656040 [mod=T2, lvl=INFO] [tid=8850] Report Sent Successfully over HTTP : 200
240424-08:14:35.656103 [mod=T2, lvl=INFO] [tid=8850] Elapsed Time for : RDKB-RPI-BootTime-Logs = 1.142592536 (Sec.NanoSec)
240424-08:14:35.656157 [mod=T2, lvl=INFO] [tid=8850] CollectAndReport --out
240424-08:14:35.825062 [mod=T2, lvl=ERROR] [tid=8851] getParameterValue partner id failed
240424-08:14:35.833962 [mod=T2, lvl=INFO] [tid=8849] cJSON Report = {"Report":[{"CmMacT1":"D8:3A:DD:3C:5C:C3"},{"ClearResetCount":"false"},{"Desc":"Raspberry Pi 3 device"},{"Device.DeviceInfo.FactoryResetCount":"1"},{"MemFree":"133"},{"MemUsed":"272"},{"MemStatFree":"3277"},{"MemStatTotal":"3513"},{"MemStatUsed":"148"},{"FrMemThrshld":"0"},{"CpuUsage":"6"},{"Uptime":"305"}]}
240424-08:14:35.834136 [mod=T2, lvl=INFO] [tid=8849] Report Size = 309
240424-08:14:35.860502 [mod=T2, lvl=ERROR] [tid=8849] getParameterValue partner id failed
240424-08:14:36.807264 [mod=T2, lvl=INFO] [tid=8849] The return status from the child with pid 9024 is CurlStatus : 1
240424-08:14:36.807380 [mod=T2, lvl=INFO] [tid=8849] The return status from the child with pid 9024 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 335 
240424-08:14:36.807423 [mod=T2, lvl=INFO] [tid=8849] Report Sent Successfully over HTTP : 200
240424-08:14:36.807477 [mod=T2, lvl=INFO] [tid=8849] Elapsed Time for : DM_DEVICEINFO_PROFILE = 2.295589017 (Sec.NanoSec)
240424-08:14:36.807530 [mod=T2, lvl=INFO] [tid=8849] CollectAndReport --out
240424-08:14:36.830727 [mod=T2, lvl=INFO] [tid=8851] The return status from the child with pid 9015 is CurlStatus : 1
240424-08:14:36.830838 [mod=T2, lvl=INFO] [tid=8851] The return status from the child with pid 9015 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 335 
240424-08:14:36.830885 [mod=T2, lvl=INFO] [tid=8851] Report Sent Successfully over HTTP : 200
240424-08:14:36.830943 [mod=T2, lvl=INFO] [tid=8851] Elapsed Time for : RDKB-RPI-SELFHEALPROFILE = 2.315808869 (Sec.NanoSec)


References

Jira
serverJIRA
serverId11deff04-0380-3a3d-a916-0849d4e573f7
keyRDKBACCL-291

...