Pre-requisite

Configure the XCONF server with the values 

Client side

1) Make sure that required Telemetry RFC parameters are configured before starting the validation and file community-rfc-configs.ini is present in /etc/rfcdefaults/ path to do so. 

/etc/rfcdefaults# ls -l
-rw-r--r--    1 root     root           352 Jan 30 05:11 community-rfc-configs.ini

2) Ensure telemetry process is running by checking the pid

root@raspberrypi4-64-rdke:~# pidof telemetry2_0 
1312

3) Invoke system service query for setting timezone

root@raspberrypi4-64-rdke:/opt/persistent# curl -d '{"jsonrpc":"2.0","id":"3","method": "org.rdk.System.setTimeZoneDST","params": {"timeZone": "America/New_York"}}' http://127.0.0.1:9998/jsonrpc 
{"jsonrpc":"2.0","id":3,"result":{"success":true}} 
root@raspberrypi4-64-rdke:/opt/persistent# cat timeZoneDST 
America/New_York
root@raspberrypi4-64-rdke:/opt/persistent#

4) Get the Telemetry URL 

tr181 -g Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.Telemetry.ConfigURL
https://xconf.rdkcentral.com/loguploader/getT2Settings

tr181 -g Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.Telemetry.Version 
2.0.1

5) Restart telemetry2_0

systemctl restart telemetry2_0.service


Telemetry2_0 adds support for RDK Components to send their telemetry data to the T2 component for reporting. This allows for moving away from the CPU-intensive process of searching through log files for telemetry strings.

Single Profile Configuration

Server side cofiguration 

Client side verification



Multi-Profile Configuration

For Configuring Multiple T2 profiles in XCONF , webconfig support is mandatory. Currently telemetry2 is validated using rbuscli command. 

Example :

rbuscli setvalues Device.X_RDKCENTRAL-COM_T2.ReportProfiles string '{
  "profiles": [
    {
      "name": "RDKE_TEST_Profile",
      "hash": "hash1",
      "value": {
        "Name": "RDKE_TEST_Profile",
        "Description": "Report to check Parameters",
        "Version": "0.1",
        "Protocol": "HTTP",
        "EncodingType": "JSON",
        "ReportingInterval": 30,
        "TimeReference": "0001-01-01T00:00:00Z",
        "Parameter": [
          {
            "type": "dataModel",
            "name": "NTPServer3",
            "reference": "Device.Time.NTPServer3"
          },
          {
            "type": "dataModel",
            "name": "HwVer",
            "reference": "Device.DeviceInfo.HardwareVersion"
          }, 
          {
            "type": "dataModel",
            "name": "MemStatFree",
            "reference": "Device.DeviceInfo.MemoryStatus.Free"
          },
          {
            "type": "dataModel",
            "name": "MemStatTotal",
            "reference": "Device.DeviceInfo.MemoryStatus.Total"
          },
          {
            "type": "event",
            "eventName": "Xi_wifiMAC_split",
            "component": "sysint", "use": "absolute"
          },
          {
            "type": "event",
            "eventName": "SYST_ERR_LogUpload_Failed",
            "component": "sysint",
            "use": "count",
           "reportEmpty": false
          },
          {
            "type": "grep",
            "marker": "NW_DNSMASQ",
            "search": "starting /usr/bin/dnsmasq",
            "logFile": "NetworkManager.log",
            "use": "absolute"
          },
          {
            "type": "dataModel",
            "name": "CpuUsage",
            "reference": "Device.DeviceInfo.ProcessStatus.CPUUsage"
          },
          {
            "type": "dataModel",
            "name": "Uptime",
            "reference": "Device.DeviceInfo.UpTime"
          }
        ],
        "HTTP": {
          "URL": "https://xconf.rdkcentral.com/logTelemetryServer/telemetry_upload.php",
          "Compression": "None",
          "Method": "POST",
          "RequestURIParameter": [
            {
              "Name": "device",
              "Reference": "Device.DeviceInfo.X_COMCAST-COM_STB_MAC"
            }
          ]
        },
        "JSONEncoding": {
          "ReportFormat": "NameValuePair",
          "ReportTimestamp": "Unix-Epoch"
        }
      }
    }
  ]
}'  

Once this is configured, you can see the profile available in path /opt/.t2reportprofiles

root@raspberrypi4-64-rdke:~# cat /opt/.t2reportprofiles/RDKE_test_profile
{"Name":"RDKE_TEST_Profile","Description":"Report to check Parameters","Version":"0.1","Protocol":"HTTP","EncodingType":"JSON","ReportingInterval":30,"TimeReference":"0001-01-01T00:00:00Z","Parameter":[{"type":"dataModel","name":"NTPServer3","reference":"Device.Time.NTPServer3"},{"type":"dataModel","name":"HwVer","reference":"Device.DeviceInfo.HardwareVersion"},{"type":"dataModel","name":"MemStatFree","reference":"Device.DeviceInfo.MemoryStatus.Free"},{"type":"dataModel","name":"MemStatTotal","reference":"Device.DeviceInfo.MemoryStatus.Total"},{"type":"event","eventName":"Xi_wifiMAC_split","component":"sysint","use":"absolute"},{"type":"event","eventName":"SYST_ERR_LogUpload_Failed","component":"sysint","use":"count","reportEmpty":false},{"type":"grep","marker":"NW_DNSMASQ","search":"starting /usr/bin/dnsmasq","logFile":"NetworkManager.log","use":"absolute"},{"type":"dataModel","name":"CpuUsage","reference":"Device.DeviceInfo.ProcessStatus.CPUUsage"},{"type":"dataModel","name":"Uptime","reference":"Device.DeviceInfo.UpTime"}],"HTTP":{"URL":"https://xconf.rdkcentral.com/logTelemetryServer/telemetry_upload.php","Compression":"None","Method":"POST","RequestURIParameter":[{"Name":"device","Reference":"Device.DeviceInfo.X_COMCAST-COM_STB_MAC"}]},"JSONEncoding":{"ReportFormat":"NameValuePair","ReportTimestamp":"Unix-Epoch"},"Hash":"hash1"}
root@raspberrypi4-64-rdke:~#

 

root@raspberrypi4-64-rdke:~# cat /opt/logs/telemetry2_0.txt.0
2025-10-03T20:07:08.555Z telemetry2_0[1174]:  rdk_dyn_log_initg_dl_socket = 3 __progname = telemetry2_0
2025-10-03T20:07:08.556Z telemetry2_0[1174]:  Conf file /etc/debug.ini open success
2025-10-03T20:07:08.556Z telemetry2_0[1174]:  251003-20:07:08.554777 [mod=T2, lvl=INFO] [tid=1174] Starting Telemetry 2.0 Process
2025-10-03T20:07:08.562Z telemetry2_0[1174]:  251003-20:07:08.559743 [mod=T2, lvl=INFO] [tid=1193] Initializing Telemetry 2.0 Component
2025-10-03T20:07:08.562Z telemetry2_0[1174]:  251003-20:07:08.560066 [mod=T2, lvl=INFO] [tid=1193] WhoAmI feature is disabled
2025-10-03T20:07:08.562Z telemetry2_0[1174]:  251003-20:07:08.560660 [mod=RBUSCORE, lvl=INFO] [tid=1193] RBus Enabled
2025-10-03T20:07:08.563Z telemetry2_0[1174]:  251003-20:07:08.560766 [mod=T2, lvl=INFO] [tid=1193] :0 rbus open for component: telemetry2_0
2025-10-03T20:07:08.563Z telemetry2_0[1174]:  251003-20:07:08.560881 [mod=T2, lvl=INFO] [tid=1193] :0 RBus Enabled
2025-10-03T20:07:08.563Z telemetry2_0[1174]:  251003-20:07:08.560969 [mod=T2, lvl=INFO] [tid=1193] :0 Broker address: unix:///tmp/rtrouted
2025-10-03T20:07:08.563Z telemetry2_0[1174]:  251003-20:07:08.562272 [mod=T2, lvl=INFO] [tid=1193] :0  rbus open (telemetry2_0) success
2025-10-03T20:07:08.583Z telemetry2_0[1174]:  251003-20:07:08.580778 [mod=T2, lvl=ERROR] [tid=1193] getParameterValue partner id failed
2025-10-03T20:07:08.583Z telemetry2_0[1174]:  251003-20:07:08.580841 [mod=T2, lvl=INFO] [tid=1193] NonRoot feature is enabled, dropping root privileges for Telemetry 2.0 Process
2025-10-03T20:07:08.589Z telemetry2_0[1174]:  251003-20:07:08.589087 [mod=T2, lvl=INFO] [tid=1193] SEEKMAP folder is present notify the profiles for saved seekmap
2025-10-03T20:07:08.589Z telemetry2_0[1174]:  251003-20:07:08.589267 [mod=T2, lvl=INFO] [tid=1193] :0 RBus Enabled
2025-10-03T20:07:08.589Z telemetry2_0[1174]:  251003-20:07:08.589416 [mod=T2, lvl=INFO] [tid=1193] :0 file /tmp/rbus_subs_telemetry2_0
2025-10-03T20:07:08.597Z telemetry2_0[1174]:  251003-20:07:08.597636 [mod=T2, lvl=INFO] [tid=1193] :0 Subscription for Device.X_RDKCENTREL-COM.Reloadconfig::Device.X_RDKCENTREL-COM.Reloadconfig is now active.
2025-10-03T20:07:08.597Z telemetry2_0[1174]:  251003-20:07:08.597710 [mod=T2, lvl=INFO] [tid=1193] :0 Device.X_RDKCENTREL-COM.Reloadconfig subscribe retries succeeded
2025-10-03T20:07:08.607Z telemetry2_0[1174]:  251003-20:07:08.606874 [mod=T2, lvl=INFO] [tid=1193] T2 is now Ready to Recieve Events
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.628067 [mod=T2, lvl=INFO] [tid=1193] Filename : DCMresponse.txt Size : 1850
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.628231 [mod=T2, lvl=INFO] [tid=1193] Returning 1 local configurations
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.628490 [mod=T2, lvl=INFO] [tid=1193] Received profile name : RDKMA_Test with interval of : 900 secs and upload url : http://xconf.rdkcentral.com/xconf/logupload.php
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.628601 [mod=T2, lvl=INFO] [tid=1193] Number of tr181params/markers successfully added in profile = 2
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.633398 [mod=T2, lvl=ERROR] [tid=1193] Failed to open file
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.633526 [mod=T2, lvl=INFO] [tid=1193] Starting TimeoutThread for profile : RDKMA_Test
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.633821 [mod=T2, lvl=INFO] [tid=1193] Successfully set profile : RDKMA_Test
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.633872 [mod=T2, lvl=INFO] [tid=1193] Successfully set new profile: RDKMA_Test
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.634088 [mod=T2, lvl=INFO] [tid=1193] Failed to open persistence folder : /tmp/t2reportprofiles/, creating folder
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.634317 [mod=T2, lvl=INFO] [tid=1193] loadReportProfilesFromDisk
2025-10-03T20:07:08.636Z telemetry2_0[1174]:  251003-20:07:08.635005 [mod=T2, lvl=INFO] [tid=1193] JSON: loadReportProfilesFromDisk
2025-10-03T20:07:08.637Z telemetry2_0[1174]:  251003-20:07:08.635338 [mod=T2, lvl=INFO] [tid=1226] Waiting for 900 sec for next TIMEOUT for profile as reporting interval is taken - RDKMA_Test
2025-10-03T20:07:08.637Z telemetry2_0[1174]:  251003-20:07:08.635399 [mod=T2, lvl=INFO] [tid=1226] Waiting for timeref or reporting interval for the profile - RDKMA_Test is started
2025-10-03T20:07:08.659Z telemetry2_0[1174]:  251003-20:07:08.658934 [mod=T2, lvl=INFO] [tid=1193] Filename : RDKE_test_profile Size : 1288
2025-10-03T20:07:08.660Z telemetry2_0[1174]:  251003-20:07:08.659832 [mod=T2, lvl=INFO] [tid=1193] Returning 1 local configurations
2025-10-03T20:07:08.660Z telemetry2_0[1174]:  251003-20:07:08.660053 [mod=T2, lvl=INFO] [tid=1193] Reporting Adjustments parameters check done successfully
2025-10-03T20:07:08.660Z telemetry2_0[1174]:  251003-20:07:08.660216 [mod=T2, lvl=INFO] [tid=1193] Profile Name: RDKE_test_profile
2025-10-03T20:07:08.660Z telemetry2_0[1174]:  Configured httpURIReqParam count = 1
2025-10-03T20:07:08.660Z telemetry2_0[1174]:  251003-20:07:08.660253 [mod=T2, lvl=INFO] [tid=1193] Number of httpURIReqParam added  = 1
2025-10-03T20:07:08.660Z telemetry2_0[1174]:  251003-20:07:08.660643 [mod=T2, lvl=INFO] [tid=1193] Number of tr181params/markers successfully added in profile = 9
2025-10-03T20:07:08.663Z telemetry2_0[1174]:  251003-20:07:08.662864 [mod=T2, lvl=INFO] [tid=1193] Successfully created/added new profile : RDKE_test_profile
2025-10-03T20:07:08.663Z telemetry2_0[1174]:  251003-20:07:08.663131 [mod=T2, lvl=INFO] [tid=1193] Starting TimeoutThread for profile : RDKE_test_profile
2025-10-03T20:07:08.663Z telemetry2_0[1174]:  251003-20:07:08.663575 [mod=T2, lvl=INFO] [tid=1193] Successfully enabled profile : RDKE_test_profile
2025-10-03T20:07:08.664Z telemetry2_0[1174]:  251003-20:07:08.663629 [mod=T2, lvl=INFO] [tid=1193] Previous Seek is enabled so generate the report for RDKE_test_profile
2025-10-03T20:07:08.664Z telemetry2_0[1174]:  251003-20:07:08.663686 [mod=T2, lvl=INFO] [tid=1193] NotifyTimeout: profile RDKE_test_profile is in Enabled state
2025-10-03T20:07:08.664Z telemetry2_0[1174]:  251003-20:07:08.663926 [mod=T2, lvl=INFO] [tid=1193] Completed processing 1 profiles on the disk,trying to fetch new/updated profiles
2025-10-03T20:07:08.665Z telemetry2_0[1174]:  251003-20:07:08.665006 [mod=T2, lvl=INFO] [tid=1241] CollectAndReport while Loop -- START
2025-10-03T20:07:08.665Z telemetry2_0[1174]:  251003-20:07:08.665071 [mod=T2, lvl=INFO] [tid=1241] CollectAndReport ++in profileName : RDKE_test_profile
2025-10-03T20:07:08.676Z telemetry2_0[1174]:  251003-20:07:08.673322 [mod=T2, lvl=INFO] [tid=1239] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_test_profile
2025-10-03T20:07:08.676Z telemetry2_0[1174]:  251003-20:07:08.673410 [mod=T2, lvl=INFO] [tid=1239] Waiting for timeref or reporting interval for the profile - RDKE_test_profile is started
2025-10-03T20:07:08.691Z telemetry2_0[1174]:  251003-20:07:08.691313 [mod=T2, lvl=INFO] [tid=1247] process_tmprp_thread: Waiting for event from tr-181
2025-10-03T20:07:08.691Z telemetry2_0[1174]:  251003-20:07:08.691701 [mod=T2, lvl=INFO] [tid=1245] process_rp_thread: Waiting for event from tr-181
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  Inside FUNC register_sub_docs LINE 240
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  Inside FUNC initMessageQueue LINE 1124
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  Inside FUNC messageQueueProcessing LINE 899
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  Inside FUNC check_component_crash LINE 359, init file is /tmp/telemetry_initialized_bootup
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  /tmp/telemetry_initialized_bootup file not present, need to send component init event to webconfig
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  notifyVersion_to_Webconfig : doc name telemetry , doc version 0
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  Inside FUNC display_subDocs LINE 88
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  251003-20:07:08.744409 [mod=T2, lvl=INFO] [tid=1193] :0 RBus Enabled
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  Entering rbusInit
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  251003-20:07:08.744505 [mod=T2, lvl=INFO] [tid=1193] :0 rbus open for component: telemetry2_0_wbcfg
2025-10-03T20:07:08.746Z telemetry2_0[1174]:  251003-20:07:08.744835 [mod=T2, lvl=INFO] [tid=1193] :0  rbus open (telemetry2_0_wbcfg) success
2025-10-03T20:07:08.750Z telemetry2_0[1174]:  isWebCfgRbusEnabled: rbus enabled is 1
2025-10-03T20:07:08.750Z telemetry2_0[1174]:  sendWebConfigSignal : rbus_set :: event_name : webconfigSignal ::
2025-10-03T20:07:08.750Z telemetry2_0[1174]:  isWebCfgRbusEnabled: rbus enabled is 1
2025-10-03T20:07:08.750Z telemetry2_0[1174]:  subscribeSubdocForceReset: Discover component for event 'Device.X_RDK_WebConfig.webcfgSubdocForceReset'
2025-10-03T20:07:08.750Z telemetry2_0[1174]:  webcfg_rbus_discover_component rbusModuleList[Device.X_RDK_WebConfig.webcfgSubdocForceReset]
2025-10-03T20:07:08.750Z telemetry2_0[1174]:  webcfg_rbus_discover_component: (1-1)ret[TRUE]
2025-10-03T20:07:08.751Z telemetry2_0[1174]:  251003-20:07:08.749377 [mod=T2, lvl=ERROR] [tid=1193] :0 _setInternal for webconfigSignal failed with error: Entry not found error
2025-10-03T20:07:08.762Z telemetry2_0[1174]:  sendWebConfigSignal rbus_set for webconfigSignal failed & returns with Err: 5
2025-10-03T20:07:08.762Z telemetry2_0[1174]:  251003-20:07:08.762692 [mod=T2, lvl=INFO] [tid=1193] :0 RBus Enabled
2025-10-03T20:07:08.763Z telemetry2_0[1174]:  251003-20:07:08.763713 [mod=T2, lvl=INFO] [tid=1193] No data elements present to unregister
2025-10-03T20:07:08.765Z telemetry2_0[1174]:  251003-20:07:08.765083 [mod=T2, lvl=INFO] [tid=1193] T2 is now Ready to be configured for report profiles
2025-10-03T20:07:08.765Z telemetry2_0[1174]:  251003-20:07:08.765476 [mod=T2, lvl=INFO] [tid=1193] T2ER isn't initialized or dispatch thread is already running
2025-10-03T20:07:08.766Z telemetry2_0[1174]:  251003-20:07:08.766033 [mod=T2, lvl=INFO] [tid=1193] InitProperties is successful
2025-10-03T20:07:08.766Z telemetry2_0[1174]:  251003-20:07:08.766545 [mod=T2, lvl=INFO] [tid=1193] Init ReportProfiles Successful
2025-10-03T20:07:08.767Z telemetry2_0[1174]:  251003-20:07:08.767254 [mod=T2, lvl=INFO] [tid=1193] Init Xconf Client Success
2025-10-03T20:07:08.767Z telemetry2_0[1174]:  251003-20:07:08.767612 [mod=T2, lvl=INFO] [tid=1193] Triggering XCONF report generation during boot with delay
2025-10-03T20:07:09.255Z telemetry2_0[1174]:  251003-20:07:09.255654 [mod=T2, lvl=INFO] [tid=1199] :0 Consumer=rbus.sysTimeMgr.INBOX.1334 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-10-03T20:07:09.255Z telemetry2_0[1174]:  251003-20:07:09.255718 [mod=T2, lvl=INFO] [tid=1199] eventSubHandler called:
2025-10-03T20:07:09.255Z telemetry2_0[1174]:    action=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-25T20:46:19.262Z telemetry2_0[1174]:  251125-20:46:19.262348 [mod=T2, lvl=INFO] [tid=1199] :0 Consumer=rbus.dcmd.INBOX.1175 SUBSCRIBED to event=Device.DCM.Setconfig
2025-11-25T20:46:19.262Z telemetry2_0[1174]:  251125-20:46:19.262412 [mod=T2, lvl=INFO] [tid=1199] eventSubHandler called:
2025-11-25T20:46:19.262Z telemetry2_0[1174]:    action=subscribe        eventName=Device.DCM.Setconfig
2025-11-25T20:46:19.266Z telemetry2_0[1174]:  251125-20:46:19.266105 [mod=T2, lvl=INFO] [tid=1199] :0 Consumer=rbus.dcmd.INBOX.1175 SUBSCRIBED to event=Device.DCM.Processconfig
2025-11-25T20:46:19.266Z telemetry2_0[1174]:  251125-20:46:19.266171 [mod=T2, lvl=INFO] [tid=1199] eventSubHandler called:
2025-11-25T20:46:19.266Z telemetry2_0[1174]:    action=subscribe        eventName=Device.DCM.Processconfig
2025-11-25T20:46:19.267Z telemetry2_0[1174]:  251125-20:46:19.267661 [mod=T2, lvl=INFO] [tid=1199] rbusReloadConf ++in
2025-11-25T20:46:19.268Z telemetry2_0[1174]:  251125-20:46:19.267717 [mod=T2, lvl=INFO] [tid=1199] Recieved eventName: Device.X_RDKCENTREL-COM.Reloadconfig, Event type: 3, Event Name: Device.X_RDKCENTREL-COM.Reloadconfig
2025-11-25T20:46:19.268Z telemetry2_0[1174]:  251125-20:46:19.267751 [mod=T2, lvl=INFO] [tid=1199] rbusReloadConf --out
2025-11-25T20:46:19.474Z telemetry2_0[1174]:  251125-20:46:19.453271 [mod=T2, lvl=INFO] [tid=1199] :0 Consumer=rbus.tr69hostif.INBOX.832 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-25T20:46:19.474Z telemetry2_0[1174]:  251125-20:46:19.453342 [mod=T2, lvl=INFO] [tid=1199] eventSubHandler called:
2025-11-25T20:46:19.474Z telemetry2_0[1174]:    action=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-25T20:46:19.474Z telemetry2_0[1174]:  251125-20:46:19.460014 [mod=T2, lvl=INFO] [tid=1272] Setting config URL base location to : https://xconf.rdkcentral.com/loguploader/getT2Settings
2025-11-25T20:46:19.475Z telemetry2_0[1174]:  251125-20:46:19.463442 [mod=T2, lvl=INFO] [tid=1241] Previous Logs report is sent clear the previousSeek flag
2025-11-25T20:46:19.475Z telemetry2_0[1174]:  251125-20:46:19.463506 [mod=T2, lvl=INFO] [tid=1241] cJSON Report = {"Report":[{"PREVIOUS_LOG":"1"},{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7782424"},{"MemStatTotal":"8010996"},{"CpuUsage":"97"},{"Uptime":"13"}]}
2025-11-25T20:46:19.475Z telemetry2_0[1174]:  251125-20:46:19.463580 [mod=T2, lvl=INFO] [tid=1241] Report Size = 173
2025-11-25T20:46:19.475Z telemetry2_0[1174]:  251125-20:46:19.466457 [mod=T2, lvl=ERROR] [tid=1241] :0 rbus_get for Device.DeviceInfo.X_COMCAST-COM_CM_MAC failed with error: Entry not found error
2025-11-25T20:46:19.475Z telemetry2_0[1174]:  251125-20:46:19.466509 [mod=T2, lvl=ERROR] [tid=1241] Unable to get Device.DeviceInfo.X_COMCAST-COM_CM_MAC
2025-11-25T20:46:19.475Z telemetry2_0[1174]:  251125-20:46:19.466549 [mod=T2, lvl=ERROR] [tid=1241] Failed to retrieve param : Device.DeviceInfo.X_COMCAST-COM_CM_MAC
2025-11-25T20:46:19.498Z telemetry2_0[1174]:  251125-20:46:19.491089 [mod=T2, lvl=ERROR] [tid=1241] getParameterValue partner id failed
2025-11-25T20:46:19.576Z telemetry2_0[1174]:  251125-20:46:19.576646 [mod=T2, lvl=INFO] [tid=1272] T2: Curl Using XCONF URI : https://xconf.rdkcentral.com/loguploader/getT2Settings?estbMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&firmwareVersion=lib32-application-test-image-RPI4-20251030114955&model=RDKERPi4&manufacturer=RaspberryPi&partnerId=community&accountId=1234&ecmMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&env=dev&controllerId=2504&channelMapId=2345&vodId=15660&timezone=America%2fNew_York&version=2 with Length :373
2025-11-25T20:46:19.577Z telemetry2_0[1174]:  251125-20:46:19.576749 [mod=T2, lvl=INFO] [tid=1272] doHttpGet with url https://xconf.rdkcentral.com/loguploader/getT2Settings?estbMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&firmwareVersion=lib32-application-test-image-RPI4-20251030114955&model=RDKERPi4&manufacturer=RaspberryPi&partnerId=community&accountId=1234&ecmMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&env=dev&controllerId=2504&channelMapId=2345&vodId=15660&timezone=America%2fNew_York&version=2
2025-11-25T20:46:19.583Z telemetry2_0[1174]:  251125-20:46:19.583258 [mod=T2, lvl=ERROR] [tid=1272] getParameterValue partner id failed
2025-11-25T20:46:20.214Z telemetry2_0[1174]:  251125-20:46:20.213848 [mod=T2, lvl=INFO] [tid=1502] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-25T20:46:20.225Z telemetry2_0[1174]:  251125-20:46:20.225257 [mod=T2, lvl=INFO] [tid=1241] The return status from the child with pid 1502 is CurlStatus : 1
2025-11-25T20:46:20.225Z telemetry2_0[1174]:  251125-20:46:20.225375 [mod=T2, lvl=INFO] [tid=1241] The return status from the child with pid 1502 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 505
2025-11-25T20:46:20.225Z telemetry2_0[1174]:  251125-20:46:20.225415 [mod=T2, lvl=INFO] [tid=1241] Report Sent Successfully over HTTP : 200
2025-11-25T20:46:20.225Z telemetry2_0[1174]:  251125-20:46:20.225468 [mod=T2, lvl=INFO] [tid=1241] Elapsed Time for : RDKE_test_profile = 4581551.560350782 (Sec.NanoSec)
2025-11-25T20:46:20.225Z telemetry2_0[1174]:  251125-20:46:20.225535 [mod=T2, lvl=INFO] [tid=1241] CollectAndReport while Loop -- END; wait for restart event
2025-11-25T20:46:20.225Z telemetry2_0[1174]:  251125-20:46:20.225568 [mod=T2, lvl=INFO] [tid=1241] CollectAndReport --out
2025-11-25T20:46:20.530Z telemetry2_0[1174]:  251125-20:46:20.529339 [mod=T2, lvl=INFO] [tid=1533] doHttpGet:816, T2:Telemetry XCONF communication success
2025-11-25T20:46:20.542Z telemetry2_0[1174]:  251125-20:46:20.542536 [mod=T2, lvl=INFO] [tid=1272] Received profile name : RDKMA_Test with interval of : 900 secs and upload url : http://xconf.rdkcentral.com/xconf/logupload.php
2025-11-25T20:46:20.542Z telemetry2_0[1174]:  251125-20:46:20.542642 [mod=T2, lvl=INFO] [tid=1272] Number of tr181params/markers successfully added in profile = 2
2025-11-25T20:46:20.552Z telemetry2_0[1174]:  251125-20:46:20.552155 [mod=T2, lvl=ERROR] [tid=1272] Failed to remove the file /tmp/t2_caching_file
2025-11-25T20:46:20.552Z telemetry2_0[1174]:  251125-20:46:20.552300 [mod=T2, lvl=ERROR] [tid=1272] Component name list is not initialized . Re-initializing
2025-11-25T20:46:20.552Z telemetry2_0[1174]:  251125-20:46:20.552392 [mod=T2, lvl=INFO] [tid=1272] Profile exists already, updating the config in file system
2025-11-25T20:46:20.552Z telemetry2_0[1174]:  251125-20:46:20.552447 [mod=T2, lvl=INFO] [tid=1272] removing profile : RDKMA_Test
2025-11-25T20:46:20.552Z telemetry2_0[1174]:  251125-20:46:20.552551 [mod=T2, lvl=INFO] [tid=1272] Scheduler already assigned for profile RDKMA_Test , exiting .
2025-11-25T20:46:20.553Z telemetry2_0[1174]:  251125-20:46:20.552589 [mod=T2, lvl=INFO] [tid=1272] Successfully set profile : RDKMA_Test
2025-11-25T20:46:20.553Z telemetry2_0[1174]:  251125-20:46:20.552632 [mod=T2, lvl=INFO] [tid=1272] T2ER isn't initialized or dispatch thread isn't running
2025-11-25T20:46:20.553Z telemetry2_0[1174]:  251125-20:46:20.552680 [mod=T2, lvl=INFO] [tid=1272] :0 RBus Enabled
2025-11-25T20:46:20.559Z telemetry2_0[1174]:  251125-20:46:20.559509 [mod=T2, lvl=INFO] [tid=1272] Successfully set new profile : RDKMA_Test
2025-11-25T20:46:20.560Z telemetry2_0[1174]:  251125-20:46:20.559667 [mod=T2, lvl=INFO] [tid=1272] Set DCM flag for sending events
2025-11-25T20:46:20.627Z telemetry2_0[1174]:  251125-20:46:20.569081 [mod=T2, lvl=INFO] [tid=1272] URL for Report Profile: https://xconf.rdkcentral.com/loguploader/getTelemetryProfiles251125-20:46:20.626979 [mod=T2, lvl=INFO] [tid=1272] T2: Curl Using XCONF URI : https://xconf.rdkcentral.com/loguploader/getTelemetryProfiles?estbMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&firmwareVersion=lib32-application-test-image-RPI4-20251030114955&model=RDKERPi4&manufacturer=RaspberryPi&partnerId=community&accountId=1234&ecmMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&env=dev&controllerId=2504&channelMapId=2345&vodId=15660&timezone=America%2fNew_York&version=2 with Length :380
2025-11-25T20:46:20.627Z telemetry2_0[1174]:  251125-20:46:20.627054 [mod=T2, lvl=INFO] [tid=1272] doHttpGet with url https://xconf.rdkcentral.com/loguploader/getTelemetryProfiles?estbMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&firmwareVersion=lib32-application-test-image-RPI4-20251030114955&model=RDKERPi4&manufacturer=RaspberryPi&partnerId=community&accountId=1234&ecmMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&env=dev&controllerId=2504&channelMapId=2345&vodId=15660&timezone=America%2fNew_York&version=2
2025-11-25T20:46:20.633Z telemetry2_0[1174]:  251125-20:46:20.633093 [mod=T2, lvl=ERROR] [tid=1272] getParameterValue partner id failed
2025-11-25T20:46:21.516Z telemetry2_0[1174]:  251125-20:46:21.516064 [mod=T2, lvl=INFO] [tid=1271] Publishing the set conf event Path: /opt/.t2persistentfolder/DCMresponse.txt
2025-11-25T20:46:21.516Z telemetry2_0[1174]:  251125-20:46:21.516587 [mod=T2, lvl=INFO] [tid=1271] Publishing the Process conf event
2025-11-25T20:46:21.616Z telemetry2_0[1174]:  251125-20:46:21.615607 [mod=T2, lvl=INFO] [tid=1803] doHttpGet:816, T2:Telemetry XCONF communication success
2025-11-25T20:46:21.624Z telemetry2_0[1174]:  251125-20:46:21.624460 [mod=T2, lvl=INFO] [tid=1272] Number of report profiles in current configuration is 1
2025-11-25T20:46:21.624Z telemetry2_0[1174]:  251125-20:46:21.624582 [mod=T2, lvl=INFO] [tid=1272] :0 RBus Enabled
2025-11-25T20:46:21.624Z telemetry2_0[1174]:  251125-20:46:21.624861 [mod=T2, lvl=INFO] [tid=1272] Filename : .
2025-11-25T20:46:21.625Z telemetry2_0[1174]:  251125-20:46:21.624933 [mod=T2, lvl=INFO] [tid=1272] Filename : RDKE_test_profile
2025-11-25T20:46:21.625Z telemetry2_0[1174]:  251125-20:46:21.624969 [mod=T2, lvl=INFO] [tid=1272] Filename : ..
2025-11-25T20:46:21.625Z telemetry2_0[1174]:  251125-20:46:21.625045 [mod=T2, lvl=INFO] [tid=1272] :0 RBus Enabled
2025-11-25T20:46:21.625Z telemetry2_0[1174]:  251125-20:46:21.625342 [mod=T2, lvl=INFO] [tid=1272] Reporting Adjustments parameters check done successfully
2025-11-25T20:46:21.625Z telemetry2_0[1174]:  251125-20:46:21.625494 [mod=T2, lvl=INFO] [tid=1272] Profile Name: RDKE_test_profile
2025-11-25T20:46:21.625Z telemetry2_0[1174]:  Configured httpURIReqParam count = 1
2025-11-25T20:46:21.625Z telemetry2_0[1174]:  251125-20:46:21.625528 [mod=T2, lvl=INFO] [tid=1272] Number of httpURIReqParam added  = 1
2025-11-25T20:46:21.627Z telemetry2_0[1174]:  251125-20:46:21.627494 [mod=T2, lvl=INFO] [tid=1272] Number of tr181params/markers successfully added in profile = 9
2025-11-25T20:46:21.658Z telemetry2_0[1174]:  251125-20:46:21.628471 [mod=T2, lvl=INFO] [tid=1239] Interrupted before TIMEOUT for profile : RDKE_test_profile
2025-11-25T20:46:21.658Z telemetry2_0[1174]:  251125-20:46:21.628531 [mod=T2, lvl=INFO] [tid=1239] get_logdemand ++in
2025-11-25T20:46:21.658Z telemetry2_0[1174]:  251125-20:46:21.628565 [mod=T2, lvl=INFO] [tid=1239] ReportProfiles_TimeoutCb ++in
2025-11-25T20:46:21.658Z telemetry2_0[1174]:  251125-20:46:21.628599 [mod=T2, lvl=INFO] [tid=1239] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-25T20:46:21.658Z telemetry2_0[1174]:  251125-20:46:21.628659 [mod=T2, lvl=INFO] [tid=1239] NotifyTimeout: profile RDKE_test_profile is in Disabled state
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.628695 [mod=T2, lvl=WARN] [tid=1239] Either profile is disabled or report generation still in progress - ignoring the request
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.628737 [mod=T2, lvl=INFO] [tid=1239] ReportProfiles_TimeoutCb --out
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.628768 [mod=T2, lvl=WARN] [tid=1239] Profile : RDKE_test_profile is being removed from scheduler
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.629011 [mod=T2, lvl=INFO] [tid=1272]  tProfile->tId = -181406944 tProfile->name = RDKE_test_profile
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.629068 [mod=T2, lvl=INFO] [tid=1272]  schProfile->name = RDKE_test_profile schProfile->tId = -181406944
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.629182 [mod=T2, lvl=INFO] [tid=1272] Waiting for CollectAndReport to be complete : RDKE_test_profile
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.629283 [mod=T2, lvl=INFO] [tid=1241] CollectAndReport --out Exiting collect and report Thread
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.629514 [mod=T2, lvl=INFO] [tid=1272] removing profile : RDKE_test_profile from profile list
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.630024 [mod=T2, lvl=ERROR] [tid=1272] Failed to remove the file /tmp/t2_caching_file
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.630337 [mod=T2, lvl=ERROR] [tid=1272] Component name list is not initialized . Re-initializing
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.630417 [mod=T2, lvl=INFO] [tid=1272] Starting TimeoutThread for profile : RDKE_test_profile
2025-11-25T20:46:21.659Z telemetry2_0[1174]:  251125-20:46:21.630562 [mod=T2, lvl=INFO] [tid=1272] T2ER isn't initialized or dispatch thread is already running
2025-11-25T20:46:21.660Z telemetry2_0[1174]:  251125-20:46:21.630597 [mod=T2, lvl=INFO] [tid=1272] Successfully enabled profile : RDKE_test_profile
2025-11-25T20:46:21.674Z telemetry2_0[1174]:  251125-20:46:21.630653 [mod=T2, lvl=INFO] [tid=1272] T2ER isn't initialized or dispatch thread is already running
2025-11-25T20:46:21.674Z telemetry2_0[1174]:  251125-20:46:21.630714 [mod=T2, lvl=ERROR] [tid=1272] removeProfileFromDisk,257: command failed
2025-11-25T20:46:21.674Z telemetry2_0[1174]:  251125-20:46:21.630712 [mod=T2, lvl=INFO] [tid=1972] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_test_profile
2025-11-25T20:46:21.674Z telemetry2_0[1174]:  251125-20:46:21.630814 [mod=T2, lvl=INFO] [tid=1972] Waiting for timeref or reporting interval for the profile - RDKE_test_profile is started
2025-11-25T20:46:21.674Z telemetry2_0[1174]:  251125-20:46:21.630759 [mod=T2, lvl=INFO] [tid=1272] profiles.msgpack is removed from disk
2025-11-25T20:46:21.674Z telemetry2_0[1174]:  251125-20:46:21.636635 [mod=T2, lvl=INFO] [tid=1272] :0 RBus Enabled
2025-11-25T20:46:21.674Z telemetry2_0[1174]:  251125-20:46:21.639118 [mod=T2, lvl=INFO] [tid=1272] Successfully downloaded report profiles
2025-11-25T20:46:21.729Z telemetry2_0[1174]:  251125-20:46:21.729716 [mod=T2, lvl=INFO] [tid=1199] :0 Consumer=rbus.routerDiscovery.INBOX.1827 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-25T20:46:21.730Z telemetry2_0[1174]:  251125-20:46:21.729783 [mod=T2, lvl=INFO] [tid=1199] eventSubHandler called:
2025-11-25T20:46:21.730Z telemetry2_0[1174]:    action=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-25T20:46:24.847Z telemetry2_0[1174]:  251125-20:46:24.846825 [mod=T2, lvl=INFO] [tid=1199] :0 Consumer=rbus.rdkvfwupgrader.INBOX.2378 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-25T20:46:24.847Z telemetry2_0[1174]:  251125-20:46:24.846894 [mod=T2, lvl=INFO] [tid=1199] eventSubHandler called:
2025-11-25T20:46:24.847Z telemetry2_0[1174]:    action=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-25T20:46:28.492Z telemetry2_0[1174]:  subscribeSubdocForceReset: [Device.X_RDK_WebConfig.webcfgSubdocForceReset] Component found
2025-11-25T20:46:28.492Z telemetry2_0[1174]:  subscribeSubdocForceReset: subscribing to event Device.X_RDK_WebConfig.webcfgSubdocForceReset
2025-11-25T20:46:28.492Z telemetry2_0[1174]:  251125-20:46:28.492154 [mod=T2, lvl=INFO] [tid=1267] :0 Subscription for Device.X_RDK_WebConfig.webcfgSubdocForceReset::Device.X_RDK_WebConfig.webcfgSubdocForceReset is now active.
2025-11-25T20:46:28.493Z telemetry2_0[1174]:  251125-20:46:28.492227 [mod=T2, lvl=INFO] [tid=1267] :0 Device.X_RDK_WebConfig.webcfgSubdocForceReset subscribe retries succeeded
2025-11-25T20:46:51.593Z telemetry2_0[1174]:  subscribeSubdocForceReset: subscribed to event Device.X_RDK_WebConfig.webcfgSubdocForceReset
2025-11-25T20:46:51.593Z telemetry2_0[1174]:  subscribeSubdocForceReset: job done
2025-11-25T20:46:51.593Z telemetry2_0[1174]:  251125-20:46:51.592948 [mod=T2, lvl=INFO] [tid=1972] TIMEOUT for profile - RDKE_test_profile
2025-11-25T20:46:51.593Z telemetry2_0[1174]:  251125-20:46:51.593002 [mod=T2, lvl=INFO] [tid=1972] ReportProfiles_TimeoutCb ++in
2025-11-25T20:46:51.594Z telemetry2_0[1174]:  251125-20:46:51.593039 [mod=T2, lvl=INFO] [tid=1972] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-25T20:46:51.594Z telemetry2_0[1174]:  251125-20:46:51.593104 [mod=T2, lvl=INFO] [tid=1972] NotifyTimeout: profile RDKE_test_profile is in Enabled state
2025-11-25T20:46:51.594Z telemetry2_0[1174]:  251125-20:46:51.593270 [mod=T2, lvl=INFO] [tid=1972] ReportProfiles_TimeoutCb --out
2025-11-25T20:46:51.594Z telemetry2_0[1174]:  251125-20:46:51.593319 [mod=T2, lvl=INFO] [tid=1972] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_test_profile
2025-11-25T20:46:51.594Z telemetry2_0[1174]:  251125-20:46:51.593371 [mod=T2, lvl=INFO] [tid=1972] Waiting for timeref or reporting interval for the profile - RDKE_test_profile is started
2025-11-25T20:46:51.595Z telemetry2_0[1174]:  251125-20:46:51.594645 [mod=T2, lvl=INFO] [tid=2875] CollectAndReport while Loop -- START
2025-11-25T20:46:51.595Z telemetry2_0[1174]:  251125-20:46:51.594706 [mod=T2, lvl=INFO] [tid=2875] CollectAndReport ++in profileName : RDKE_test_profile
2025-11-25T20:46:52.624Z telemetry2_0[1174]:  251125-20:46:52.624232 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7535952"},{"MemStatTotal":"8010996"},{"CpuUsage":"60"},{"Uptime":"47"},{"NW_DNSMASQ":""},{"Xi_wifiMAC_split":"D8:3A:DD:0D:16:B8"}]}
2025-11-25T20:46:52.624Z telemetry2_0[1174]:  251125-20:46:52.624315 [mod=T2, lvl=INFO] [tid=2875] Report Size = 211
2025-11-25T20:46:52.628Z telemetry2_0[1174]:  251125-20:46:52.628107 [mod=T2, lvl=ERROR] [tid=2875] :0 rbus_get for Device.DeviceInfo.X_COMCAST-COM_CM_MAC failed with error: Entry not found error
2025-11-25T20:46:52.628Z telemetry2_0[1174]:  251125-20:46:52.628170 [mod=T2, lvl=ERROR] [tid=2875] Unable to get Device.DeviceInfo.X_COMCAST-COM_CM_MAC
2025-11-25T20:46:52.628Z telemetry2_0[1174]:  251125-20:46:52.628213 [mod=T2, lvl=ERROR] [tid=2875] Failed to retrieve param : Device.DeviceInfo.X_COMCAST-COM_CM_MAC
2025-11-25T20:46:52.634Z telemetry2_0[1174]:  251125-20:46:52.634235 [mod=T2, lvl=ERROR] [tid=2875] getParameterValue partner id failed
2025-11-25T20:46:53.053Z telemetry2_0[1174]:  251125-20:46:53.053451 [mod=T2, lvl=INFO] [tid=2879] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-25T20:46:53.063Z telemetry2_0[1174]:  251125-20:46:53.063407 [mod=T2, lvl=INFO] [tid=2875] The return status from the child with pid 2879 is CurlStatus : 1
2025-11-25T20:46:53.063Z telemetry2_0[1174]:  251125-20:46:53.063495 [mod=T2, lvl=INFO] [tid=2875] The return status from the child with pid 2879 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 505
2025-11-25T20:46:53.063Z telemetry2_0[1174]:  251125-20:46:53.063532 [mod=T2, lvl=INFO] [tid=2875] Report Sent Successfully over HTTP : 200
2025-11-25T20:46:53.063Z telemetry2_0[1174]:  251125-20:46:53.063581 [mod=T2, lvl=INFO] [tid=2875] Elapsed Time for : RDKE_test_profile = 1.468834943 (Sec.NanoSec)
2025-11-25T20:46:53.064Z telemetry2_0[1174]:  251125-20:46:53.063632 [mod=T2, lvl=INFO] [tid=2875] CollectAndReport while Loop -- END; wait for restart event
2025-11-25T20:46:53.064Z telemetry2_0[1174]:  251125-20:46:53.063664 [mod=T2, lvl=INFO] [tid=2875] CollectAndReport --out
2025-11-25T20:47:21.594Z telemetry2_0[1174]:  251125-20:47:21.593027 [mod=T2, lvl=INFO] [tid=1972] TIMEOUT for profile - RDKE_test_profile
2025-11-25T20:47:21.594Z telemetry2_0[1174]:  251125-20:47:21.593089 [mod=T2, lvl=INFO] [tid=1972] ReportProfiles_TimeoutCb ++in
2025-11-25T20:47:21.594Z telemetry2_0[1174]:  251125-20:47:21.593125 [mod=T2, lvl=INFO] [tid=1972] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-25T20:47:21.594Z telemetry2_0[1174]:  251125-20:47:21.593228 [mod=T2, lvl=INFO] [tid=1972] NotifyTimeout: profile RDKE_test_profile is in Enabled state
2025-11-25T20:47:21.594Z telemetry2_0[1174]:  251125-20:47:21.593263 [mod=T2, lvl=INFO] [tid=1972] Signal Thread To restart
2025-11-25T20:47:21.594Z telemetry2_0[1174]:  251125-20:47:21.593316 [mod=T2, lvl=INFO] [tid=1972] ReportProfiles_TimeoutCb --out
2025-11-25T20:47:21.595Z telemetry2_0[1174]:  251125-20:47:21.593345 [mod=T2, lvl=INFO] [tid=2875] CollectAndReport while Loop -- START
2025-11-25T20:47:21.595Z telemetry2_0[1174]:  251125-20:47:21.593411 [mod=T2, lvl=INFO] [tid=2875] CollectAndReport ++in profileName : RDKE_test_profile
2025-11-25T20:47:21.595Z telemetry2_0[1174]:  251125-20:47:21.593361 [mod=T2, lvl=INFO] [tid=1972] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_test_profile
2025-11-25T20:47:21.595Z telemetry2_0[1174]:  251125-20:47:21.593501 [mod=T2, lvl=INFO] [tid=1972] Waiting for timeref or reporting interval for the profile - RDKE_test_profile is started
2025-11-25T20:47:22.610Z telemetry2_0[1174]:  251125-20:47:22.610289 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7526648"},{"MemStatTotal":"8010996"},{"CpuUsage":"62"},{"Uptime":"77"}]}
2025-11-25T20:47:22.610Z telemetry2_0[1174]:  251125-20:47:22.610384 [mod=T2, lvl=INFO] [tid=2875] Report Size = 152

 

root@raspberrypi4-64-rdke:~#
root@raspberrypi4-64-rdke:~# cat /opt/logs/telemetry2_0.txt.0 | grep cJSON
2025-11-25T20:46:19.475Z telemetry2_0[1174]:  251125-20:46:19.463506 [mod=T2, lvl=INFO] [tid=1241] cJSON Report = {"Report":[{"PREVIOUS_LOG":"1"},{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7782424"},{"MemStatTotal":"8010996"},{"CpuUsage":"97"},{"Uptime":"13"}]}
2025-11-25T20:46:52.624Z telemetry2_0[1174]:  251125-20:46:52.624232 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7535952"},{"MemStatTotal":"8010996"},{"CpuUsage":"60"},{"Uptime":"47"},{"NW_DNSMASQ":""},{"Xi_wifiMAC_split":"D8:3A:DD:0D:16:B8"}]}
2025-11-25T20:47:22.610Z telemetry2_0[1174]:  251125-20:47:22.610289 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7526648"},{"MemStatTotal":"8010996"},{"CpuUsage":"62"},{"Uptime":"77"}]}
2025-11-25T20:54:35.991Z telemetry2_0[1174]:  251125-20:54:35.990452 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7509288"},{"MemStatTotal":"8010996"},{"CpuUsage":"65"},{"Uptime":"107"}]}
2025-11-25T20:55:05.987Z telemetry2_0[1174]:  251125-20:55:05.987445 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7502568"},{"MemStatTotal":"8010996"},{"CpuUsage":"66"},{"Uptime":"137"}]}
2025-11-25T20:55:35.992Z telemetry2_0[1174]:  251125-20:55:35.988595 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7503768"},{"MemStatTotal":"8010996"},{"CpuUsage":"64"},{"Uptime":"167"}]}
2025-11-25T20:56:01.943Z telemetry2_0[1174]:  251125-20:56:01.943042 [mod=T2, lvl=INFO] [tid=3314] cJSON Report = {"searchResult":[{"T2":"1.0"},{"Profile":"RDKV"},{"Time":"2025-11-25 20:56:01"},{"mac":"D8:3A:DD:0D:16:B7"},{"StbIp":"10.0.0.253"},{"PartnerId":"community"},{"Version":"lib32-application-test-image-RPI4-20251030114955"},{"AccountId":"1234"},{"TELEMETRY_STARTED":"1"}]}
2025-11-25T20:56:32.978Z telemetry2_0[1174]:  251125-20:56:32.978163 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7501456"},{"MemStatTotal":"8010996"},{"CpuUsage":"67"},{"Uptime":"224"}]}
2025-11-25T20:57:02.003Z telemetry2_0[1174]:  251125-20:57:02.002867 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7494920"},{"MemStatTotal":"8010996"},{"CpuUsage":"67"},{"Uptime":"253"}]}
2025-11-25T20:57:31.988Z telemetry2_0[1174]:  251125-20:57:31.987777 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7493700"},{"MemStatTotal":"8010996"},{"CpuUsage":"66"},{"Uptime":"283"},{"Xi_wifiMAC_split":"D8:3A:DD:0D:16:B8"}]}
2025-11-25T20:58:01.992Z telemetry2_0[1174]:  251125-20:58:01.991036 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7486376"},{"MemStatTotal":"8010996"},{"CpuUsage":"66"},{"Uptime":"313"}]}
2025-11-25T20:58:31.989Z telemetry2_0[1174]:  251125-20:58:31.989371 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7494388"},{"MemStatTotal":"8010996"},{"CpuUsage":"69"},{"Uptime":"343"}]}
2025-11-25T20:59:01.992Z telemetry2_0[1174]:  251125-20:59:01.992367 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7462156"},{"MemStatTotal":"8010996"},{"CpuUsage":"70"},{"Uptime":"373"}]}
2025-11-25T20:59:31.995Z telemetry2_0[1174]:  251125-20:59:31.993222 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7460816"},{"MemStatTotal":"8010996"},{"CpuUsage":"69"},{"Uptime":"403"}]}
2025-11-25T21:00:02.003Z telemetry2_0[1174]:  251125-21:00:01.994382 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7455232"},{"MemStatTotal":"8010996"},{"CpuUsage":"71"},{"Uptime":"433"}]}
2025-11-25T21:00:31.992Z telemetry2_0[1174]:  251125-21:00:31.991673 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7453428"},{"MemStatTotal":"8010996"},{"CpuUsage":"72"},{"Uptime":"463"}]}
2025-11-25T21:01:01.989Z telemetry2_0[1174]:  251125-21:01:01.988725 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7454064"},{"MemStatTotal":"8010996"},{"CpuUsage":"69"},{"Uptime":"493"}]}
2025-11-25T21:01:32.002Z telemetry2_0[1174]:  251125-21:01:31.997505 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7455300"},{"MemStatTotal":"8010996"},{"CpuUsage":"67"},{"Uptime":"523"}]}
2025-11-25T21:02:01.994Z telemetry2_0[1174]:  251125-21:02:01.994284 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7449980"},{"MemStatTotal":"8010996"},{"CpuUsage":"67"},{"Uptime":"553"}]}
2025-11-25T21:02:32.005Z telemetry2_0[1174]:  251125-21:02:31.995329 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7450364"},{"MemStatTotal":"8010996"},{"CpuUsage":"73"},{"Uptime":"583"}]}
2025-11-25T21:03:01.992Z telemetry2_0[1174]:  251125-21:03:01.992481 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7461552"},{"MemStatTotal":"8010996"},{"CpuUsage":"73"},{"Uptime":"613"},{"SYST_ERR_LogUpload_Failed":"1"}]}
2025-11-25T21:03:31.987Z telemetry2_0[1174]:  251125-21:03:31.987353 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7463496"},{"MemStatTotal":"8010996"},{"CpuUsage":"70"},{"Uptime":"643"}]}
2025-11-25T21:04:02.015Z telemetry2_0[1174]:  251125-21:04:01.997325 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7462388"},{"MemStatTotal":"8010996"},{"CpuUsage":"70"},{"Uptime":"673"}]}
2025-11-25T21:04:32.007Z telemetry2_0[1174]:  251125-21:04:31.998592 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7455464"},{"MemStatTotal":"8010996"},{"CpuUsage":"75"},{"Uptime":"703"}]}
2025-11-25T21:05:01.988Z telemetry2_0[1174]:  251125-21:05:01.987623 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7459540"},{"MemStatTotal":"8010996"},{"CpuUsage":"73"},{"Uptime":"733"}]}
2025-11-25T21:05:31.993Z telemetry2_0[1174]:  251125-21:05:31.993426 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7456168"},{"MemStatTotal":"8010996"},{"CpuUsage":"75"},{"Uptime":"763"}]}
2025-11-25T21:06:01.993Z telemetry2_0[1174]:  251125-21:06:01.992592 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7456568"},{"MemStatTotal":"8010996"},{"CpuUsage":"74"},{"Uptime":"793"}]}
2025-11-25T21:06:31.988Z telemetry2_0[1174]:  251125-21:06:31.987699 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7449588"},{"MemStatTotal":"8010996"},{"CpuUsage":"74"},{"Uptime":"823"}]}
2025-11-25T21:07:01.989Z telemetry2_0[1174]:  251125-21:07:01.989357 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7447200"},{"MemStatTotal":"8010996"},{"CpuUsage":"75"},{"Uptime":"853"}]}
2025-11-25T21:07:31.991Z telemetry2_0[1174]:  251125-21:07:31.990785 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7447964"},{"MemStatTotal":"8010996"},{"CpuUsage":"74"},{"Uptime":"883"}]}
2025-11-25T21:08:02.093Z telemetry2_0[1174]:  251125-21:08:02.077306 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7446524"},{"MemStatTotal":"8010996"},{"CpuUsage":"74"},{"Uptime":"913"}]}
2025-11-25T21:08:02.093Z telemetry2_0[1174]:  251125-21:08:02.077706 [mod=T2, lvl=INFO] [tid=3314] cJSON Report = {"searchResult":[{"T2":"1.0"},{"Profile":"RDKV"},{"Time":"2025-11-25 21:08:00"},{"mac":"D8:3A:DD:0D:16:B7"},{"StbIp":"10.0.0.253"},{"PartnerId":"community"},{"Version":"lib32-application-test-image-RPI4-20251030114955"},{"AccountId":"1234"}]}
root@raspberrypi4-64-rdke:~#