View Source

Pre-requisite

Configure the XCONF server with the values

Update the rules and configs in XCONF server. Please refer this Feature Validation(Telemetry) for configuring Telemetry configurations in the RDKM XCONF server.

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 configuration

Update the rules and configs in XCONF server refer Feature Validation(Telemetry)

Client-side verification

root@raspberrypi4-64-rdke:~# cat /opt/.t2persistentfolder/DCMresponse.txt
{"urn:settings:GroupName":"MW_RPi45","urn:settings:CheckOnReboot":true,"urn:settings:TimeZoneMode":"UTC","urn:settings:CheckSchedule:cron":"2 2 * * *","urn:settings:CheckSchedule:DurationMinutes":0,"urn:settings:LogUploadSettings:Message":null,"urn:settings:LogUploadSettings:Name":"RPi45","urn:settings:LogUploadSettings:NumberOfDays":0,"urn:settings:LogUploadSettings:UploadRepositoryName":"m8RPiFullTest45","urn:settings:LogUploadSettings:UploadRepository:URL":"https://xconf.rdkcentral.com/logTelemetryServer/logportal.php","urn:settings:LogUploadSettings:UploadRepository:uploadProtocol":"HTTP","urn:settings:LogUploadSettings:UploadOnReboot":true,"urn:settings:LogUploadSettings:UploadImmediately":false,"urn:settings:LogUploadSettings:upload":true,"urn:settings:LogUploadSettings:UploadSchedule:cron":"5 0 * * *","urn:settings:LogUploadSettings:UploadSchedule:levelone:cron":null,"urn:settings:LogUploadSettings:UploadSchedule:leveltwo:cron":null,"urn:settings:LogUploadSettings:UploadSchedule:levelthree:cron":null,"urn:settings:LogUploadSettings:UploadSchedule:TimeZoneMode":"UTC","urn:settings:LogUploadSettings:UploadSchedule:DurationMinutes":0,"urn:settings:VODSettings:Name":null,"urn:settings:VODSettings:LocationsURL":null,"urn:settings:VODSettings:SRMIPList":null,"urn:settings:TelemetryProfile":{"@type":"PermanentTelemetryProfile","id":"fcda6fb8-953d-4699-85b2-70e6f4824c9e","telemetryProfile":[{"header":"NW_DNSMASQ_STARTED","content":"dnsmasq: starting /usr/bin/dnsmasq","type":"NetworkManager.log","pollingFrequency":"0"},{"header":"SYST_ERR_LogUpload_Failed","content":"sysint","type":"<event>","pollingFrequency":"0"},{"header":"Xi_wifiMAC_split","content":"sysint","type":"<event>","pollingFrequency":"0"},{"header":"Uptime","content":"Device.DeviceInfo.UpTime","type":"dataModel","pollingFrequency":"0"}],"schedule":"3","expires":0,"telemetryProfile:name":"RDKMA_Test","uploadRepository:URL":"http://xconf.rdkcentral.com/xconf/logupload.php","uploadRepository:uploadProtocol":"HTTP"}}

{
    "urn:settings:GroupName": "MW_RPi45",
    "urn:settings:CheckOnReboot": true,
    "urn:settings:TimeZoneMode": "UTC",
    "urn:settings:CheckSchedule:cron": "2 2 * * *",
    "urn:settings:CheckSchedule:DurationMinutes": 0,
    "urn:settings:LogUploadSettings:Message": null,
    "urn:settings:LogUploadSettings:Name": "RPi45",
    "urn:settings:LogUploadSettings:NumberOfDays": 0,
    "urn:settings:LogUploadSettings:UploadRepositoryName": "m8RPiFullTest45",
    "urn:settings:LogUploadSettings:UploadRepository:URL": "https://xconf.rdkcentral.com/logTelemetryServer/logportal.php",
    "urn:settings:LogUploadSettings:UploadRepository:uploadProtocol": "HTTP",
    "urn:settings:LogUploadSettings:UploadOnReboot": true,
    "urn:settings:LogUploadSettings:UploadImmediately": false,
    "urn:settings:LogUploadSettings:upload": true,
    "urn:settings:LogUploadSettings:UploadSchedule:cron": "5 0 * * *",
    "urn:settings:LogUploadSettings:UploadSchedule:levelone:cron": null,
    "urn:settings:LogUploadSettings:UploadSchedule:leveltwo:cron": null,
    "urn:settings:LogUploadSettings:UploadSchedule:levelthree:cron": null,
    "urn:settings:LogUploadSettings:UploadSchedule:TimeZoneMode": "UTC",
    "urn:settings:LogUploadSettings:UploadSchedule:DurationMinutes": 0,
    "urn:settings:VODSettings:Name": null,
    "urn:settings:VODSettings:LocationsURL": null,
    "urn:settings:VODSettings:SRMIPList": null,
    "urn:settings:TelemetryProfile": {
        "@type": "PermanentTelemetryProfile",
        "id": "fcda6fb8-953d-4699-85b2-70e6f4824c9e",
        "telemetryProfile": [
            {
                "header": "NW_DNSMASQ_STARTED",
                "content": "dnsmasq: starting /usr/bin/dnsmasq",
                "type": "NetworkManager.log",
                "pollingFrequency": "0"
            },
            {
                "header": "SYST_ERR_LogUpload_Failed",
                "content": "sysint",
                "type": "<event>",
                "pollingFrequency": "0"
            },
            {
                "header": "Xi_wifiMAC_split",
                "content": "sysint",
                "type": "<event>",
                "pollingFrequency": "0"
            },
            {
                "header": "Uptime",
                "content": "Device.DeviceInfo.UpTime",
                "type": "dataModel",
                "pollingFrequency": "0"
            }
        ],
        "schedule": "3",
        "expires": 0,
        "telemetryProfile:name": "RDKMA_Test",
        "uploadRepository:URL": "http://xconf.rdkcentral.com/xconf/logupload.php",
        "uploadRepository:uploadProtocol": "HTTP"
    }
}

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"
          }
        ],
        "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"
        }
      }
    },
    {
      "name": "RDKM_RDKE_Profile",
      "hash": "hash1",
      "value": {
        "Name": "RDKM_RDKE_Profile",
        "Description": "Report to check Parameters",
        "Version": "0.1",
        "Protocol": "HTTP",
        "EncodingType": "JSON",
        "ReportingInterval": 30,
        "TimeReference": "0001-01-01T00:00:00Z",
        "Parameter": [
          {
            "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"
          }
        ],
        "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:/opt/.t2reportprofiles# ls -l
-rw-rw-rw-    1 non-root non-root       864 Nov 28 14:02 RDKE_TEST_Profile
-rw-rw-rw-    1 non-root non-root       837 Nov 28 14:02 RDKM_RDKE_Profile
root@raspberrypi4-64-rdke:/opt/.t2reportprofiles#
root@raspberrypi4-64-rdke:/opt/.t2reportprofiles# cat 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"}],"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:/opt/.t2reportprofiles#
root@raspberrypi4-64-rdke:/opt/.t2reportprofiles#
root@raspberrypi4-64-rdke:/opt/.t2reportprofiles#
root@raspberrypi4-64-rdke:/opt/.t2reportprofiles#
root@raspberrypi4-64-rdke:/opt/.t2reportprofiles# cat RDKM_RDKE_Profile
{"Name":"RDKM_RDKE_Profile","Description":"Report to check Parameters","Version":"0.1","Protocol":"HTTP","EncodingType":"JSON","ReportingInterval":30,"TimeReference":"0001-01-01T00:00:00Z","Parameter":[{"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"}],"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:/opt/.t2reportprofiles#
root@raspberrypi4-64-rdke:/opt/.t2reportprofiles#
root@raspberrypi4-64-rdke:/opt/.t2reportprofiles#
root@raspberrypi4-64-rdke:/opt/.t2reportprofiles#

Success Logs

root@raspberrypi4-64-rdke:~# cat /opt/logs/telemetry2_0.txt.0 | grep -ine cjson
78:2025-10-03T20:07:08.443Z telemetry2_0[1177]:  251003-20:07:08.443359 [mod=T2, lvl=INFO] [tid=1207] cJSON Report = {"searchResult":[{"T2":"1.0"},{"Profile":"RDKV"},{"Time":"2025-10-03 20:07:08"},{"PREVIOUS_LOG":"1"},{"mac":"D8:3A:DD:0D:16:B7"},{"PartnerId":"community"},{"Version":"lib32-application-test-image-RPI4-20251030114955"},{"AccountId":"\"1234\""}]}
160:2025-11-28T19:06:47.083Z telemetry2_0[1177]:  251128-19:06:47.082608 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[{"NW_DNSMASQ":""},{"Xi_wifiMAC_split":"D8:3A:DD:0D:16:B8"}]}
169:2025-11-28T19:06:47.106Z telemetry2_0[1177]:  251128-19:06:47.106792 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7539060"},{"MemStatTotal":"8010996"}]}
406:2025-11-28T19:09:46.440Z telemetry2_0[1177]:  251128-19:09:46.440172 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[]}
412:2025-11-28T19:09:46.455Z telemetry2_0[1177]:  251128-19:09:46.453785 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7531612"},{"MemStatTotal":"8010996"}]}
429:2025-11-28T19:09:47.122Z telemetry2_0[1177]:  251128-19:09:47.121936 [mod=T2, lvl=INFO] [tid=1207] cJSON Report = {"searchResult":[{"T2":"1.0"},{"Profile":"RDKV"},{"Time":"2025-11-28 19:09:47"},{"mac":"D8:3A:DD:0D:16:B7"},{"StbIp":"64.121.230.164"},{"PartnerId":"community"},{"Version":"lib32-application-test-image-RPI4-20251030114955"},{"AccountId":"\"1234\""},{"NW_DNSMASQ_STARTED":"1"},{"Xi_wifiMAC_split":"D8:3A:DD:0D:16:B8"}]}
900:2025-11-28T19:16:46.442Z telemetry2_0[1177]:  251128-19:16:46.440268 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[{"SYST_ERR_LogUpload_Failed":"1"}]}
905:2025-11-28T19:16:46.460Z telemetry2_0[1177]:  251128-19:16:46.460578 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7522928"},{"MemStatTotal":"8010996"}]}
1144:2025-11-28T19:20:16.441Z telemetry2_0[1177]:  251128-19:20:16.440330 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[]}
1150:2025-11-28T19:20:16.454Z telemetry2_0[1177]:  251128-19:20:16.453730 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7520948"},{"MemStatTotal":"8010996"}]}
1172:2025-11-28T19:20:39.476Z telemetry2_0[1177]:  251128-19:20:39.476041 [mod=T2, lvl=INFO] [tid=1207] cJSON Report = {"searchResult":[{"T2":"1.0"},{"Profile":"RDKV"},{"Time":"2025-11-28 19:20:39"},{"mac":"D8:3A:DD:0D:16:B7"},{"StbIp":"64.121.230.164"},{"PartnerId":"community"},{"Version":"lib32-application-test-image-RPI4-20251030114955"},{"AccountId":"\"1234\""},{"SYST_ERR_LogUpload_Failed":"1"}]}
1201:2025-11-28T19:21:09.494Z telemetry2_0[1177]:  251128-19:21:09.493454 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[]}
1207:2025-11-28T19:21:09.508Z telemetry2_0[1177]:  251128-19:21:09.505686 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7519488"},{"MemStatTotal":"8010996"}]}
root@raspberrypi4-64-rdke:~#

root@raspberrypi4-64-rdke:~# cat /opt/logs/telemetry2_0.txt.0
2025-10-03T20:07:08.287Z telemetry2_0[1177]:  rdk_dyn_log_initg_dl_socket = 3 __progname = telemetry2_0
2025-10-03T20:07:08.288Z telemetry2_0[1177]:  Conf file /etc/debug.ini open success
2025-10-03T20:07:08.288Z telemetry2_0[1177]:  251003-20:07:08.287229 [mod=T2, lvl=INFO] [tid=1177] Starting Telemetry 2.0 Process
2025-10-03T20:07:08.296Z telemetry2_0[1177]:  251003-20:07:08.293416 [mod=T2, lvl=INFO] [tid=1190] Initializing Telemetry 2.0 Component
2025-10-03T20:07:08.296Z telemetry2_0[1177]:  251003-20:07:08.293733 [mod=T2, lvl=INFO] [tid=1190] WhoAmI feature is disabled
2025-10-03T20:07:08.296Z telemetry2_0[1177]:  251003-20:07:08.294324 [mod=RBUSCORE, lvl=INFO] [tid=1190] RBus Enabled
2025-10-03T20:07:08.296Z telemetry2_0[1177]:  251003-20:07:08.294436 [mod=T2, lvl=INFO] [tid=1190] :0 rbus open for component: telemetry2_0
2025-10-03T20:07:08.297Z telemetry2_0[1177]:  251003-20:07:08.294558 [mod=T2, lvl=INFO] [tid=1190] :0 RBus Enabled
2025-10-03T20:07:08.297Z telemetry2_0[1177]:  251003-20:07:08.294644 [mod=T2, lvl=INFO] [tid=1190] :0 Broker address: unix:///tmp/rtrouted
2025-10-03T20:07:08.297Z telemetry2_0[1177]:  251003-20:07:08.295977 [mod=T2, lvl=INFO] [tid=1190] :0  rbus open (telemetry2_0) success
2025-10-03T20:07:08.313Z telemetry2_0[1177]:  251003-20:07:08.312495 [mod=T2, lvl=ERROR] [tid=1190] getParameterValue partner id failed
2025-10-03T20:07:08.313Z telemetry2_0[1177]:  251003-20:07:08.312554 [mod=T2, lvl=INFO] [tid=1190] NonRoot feature is enabled, dropping root privileges for Telemetry 2.0 Process
2025-10-03T20:07:08.323Z telemetry2_0[1177]:  251003-20:07:08.322809 [mod=T2, lvl=INFO] [tid=1190] SEEKMAP folder is present notify the profiles for saved seekmap
2025-10-03T20:07:08.324Z telemetry2_0[1177]:  251003-20:07:08.323482 [mod=T2, lvl=INFO] [tid=1190] :0 RBus Enabled
2025-10-03T20:07:08.324Z telemetry2_0[1177]:  251003-20:07:08.323761 [mod=T2, lvl=INFO] [tid=1190] :0 file /tmp/rbus_subs_telemetry2_0
2025-10-03T20:07:08.330Z telemetry2_0[1177]:  251003-20:07:08.330302 [mod=T2, lvl=INFO] [tid=1190] :0 Subscription for Device.X_RDKCENTREL-COM.Reloadconfig::Device.X_RDKCENTREL-COM.Reloadconfig is now active.
2025-10-03T20:07:08.331Z telemetry2_0[1177]:  251003-20:07:08.330366 [mod=T2, lvl=INFO] [tid=1190] :0 Device.X_RDKCENTREL-COM.Reloadconfig subscribe retries succeeded
2025-10-03T20:07:08.340Z telemetry2_0[1177]:  251003-20:07:08.340004 [mod=T2, lvl=INFO] [tid=1190] T2 is now Ready to Recieve Events
2025-10-03T20:07:08.352Z telemetry2_0[1177]:  251003-20:07:08.348277 [mod=T2, lvl=INFO] [tid=1190] Filename : DCMresponse.txt Size : 2010
2025-10-03T20:07:08.352Z telemetry2_0[1177]:  251003-20:07:08.348438 [mod=T2, lvl=INFO] [tid=1190] Returning 1 local configurations
2025-10-03T20:07:08.352Z telemetry2_0[1177]:  251003-20:07:08.348701 [mod=T2, lvl=INFO] [tid=1190] 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.352Z telemetry2_0[1177]:  251003-20:07:08.348882 [mod=T2, lvl=INFO] [tid=1190] Number of tr181params/markers successfully added in profile = 4
2025-10-03T20:07:08.354Z telemetry2_0[1177]:  251003-20:07:08.353232 [mod=T2, lvl=INFO] [tid=1190] Starting TimeoutThread for profile : RDKMA_Test
2025-10-03T20:07:08.354Z telemetry2_0[1177]:  251003-20:07:08.353563 [mod=T2, lvl=INFO] [tid=1190] Successfully set profile : RDKMA_Test
2025-10-03T20:07:08.354Z telemetry2_0[1177]:  251003-20:07:08.353615 [mod=T2, lvl=INFO] [tid=1190] Successfully set new profile: RDKMA_Test
2025-10-03T20:07:08.354Z telemetry2_0[1177]:  251003-20:07:08.353647 [mod=T2, lvl=INFO] [tid=1190] Previous Seek is enabled so generate the Xconf report
2025-10-03T20:07:08.354Z telemetry2_0[1177]:  251003-20:07:08.354024 [mod=T2, lvl=INFO] [tid=1205] Waiting for 900 sec for next TIMEOUT for profile as reporting interval is taken - RDKMA_Test
2025-10-03T20:07:08.354Z telemetry2_0[1177]:  251003-20:07:08.354090 [mod=T2, lvl=INFO] [tid=1205] Waiting for timeref or reporting interval for the profile - RDKMA_Test is started
2025-10-03T20:07:08.355Z telemetry2_0[1177]:  251003-20:07:08.354277 [mod=T2, lvl=INFO] [tid=1207] CollectAndReportXconf while Loop -- START
2025-10-03T20:07:08.355Z telemetry2_0[1177]:  251003-20:07:08.354327 [mod=T2, lvl=INFO] [tid=1207] CollectAndReportXconf ++in profileName : RDKMA_Test
2025-10-03T20:07:08.359Z telemetry2_0[1177]:  251003-20:07:08.357000 [mod=T2, lvl=INFO] [tid=1190] Reading data from file /opt/.t2cachedmessages//RDKMA_Test
2025-10-03T20:07:08.364Z telemetry2_0[1177]:  251003-20:07:08.359802 [mod=T2, lvl=INFO] [tid=1190] Remove cached report file - /opt/.t2cachedmessages//RDKMA_Test
2025-10-03T20:07:08.364Z telemetry2_0[1177]:  251003-20:07:08.359979 [mod=T2, lvl=INFO] [tid=1190] Failed to open persistence folder : /tmp/t2reportprofiles/, creating folder
2025-10-03T20:07:08.364Z telemetry2_0[1177]:  251003-20:07:08.360156 [mod=T2, lvl=INFO] [tid=1190] loadReportProfilesFromDisk
2025-10-03T20:07:08.364Z telemetry2_0[1177]:  251003-20:07:08.362196 [mod=T2, lvl=INFO] [tid=1190] JSON: loadReportProfilesFromDisk
2025-10-03T20:07:08.384Z telemetry2_0[1177]:  251003-20:07:08.384424 [mod=T2, lvl=INFO] [tid=1190] Filename : RDKM_RDKE_Profile Size : 837
2025-10-03T20:07:08.387Z telemetry2_0[1177]:  251003-20:07:08.387546 [mod=T2, lvl=INFO] [tid=1190] Filename : RDKE_TEST_Profile Size : 864
2025-10-03T20:07:08.388Z telemetry2_0[1177]:  251003-20:07:08.388489 [mod=T2, lvl=INFO] [tid=1190] Returning 2 local configurations
2025-10-03T20:07:08.388Z telemetry2_0[1177]:  251003-20:07:08.388714 [mod=T2, lvl=INFO] [tid=1190] Reporting Adjustments parameters check done successfully
2025-10-03T20:07:08.389Z telemetry2_0[1177]:  251003-20:07:08.388940 [mod=T2, lvl=INFO] [tid=1190] Profile Name: RDKM_RDKE_Profile
2025-10-03T20:07:08.389Z telemetry2_0[1177]:  Configured httpURIReqParam count = 1
2025-10-03T20:07:08.389Z telemetry2_0[1177]:  251003-20:07:08.388981 [mod=T2, lvl=INFO] [tid=1190] Number of httpURIReqParam added  = 1
2025-10-03T20:07:08.389Z telemetry2_0[1177]:  251003-20:07:08.389156 [mod=T2, lvl=INFO] [tid=1190] Number of tr181params/markers successfully added in profile = 3
2025-10-03T20:07:08.391Z telemetry2_0[1177]:  251003-20:07:08.391197 [mod=T2, lvl=INFO] [tid=1190] Successfully created/added new profile : RDKM_RDKE_Profile
2025-10-03T20:07:08.391Z telemetry2_0[1177]:  251003-20:07:08.391376 [mod=T2, lvl=INFO] [tid=1190] Starting TimeoutThread for profile : RDKM_RDKE_Profile
2025-10-03T20:07:08.392Z telemetry2_0[1177]:  251003-20:07:08.391871 [mod=T2, lvl=INFO] [tid=1190] Successfully enabled profile : RDKM_RDKE_Profile
2025-10-03T20:07:08.392Z telemetry2_0[1177]:  251003-20:07:08.391945 [mod=T2, lvl=INFO] [tid=1190] Previous Seek is enabled so generate the report for RDKM_RDKE_Profile
2025-10-03T20:07:08.392Z telemetry2_0[1177]:  251003-20:07:08.392006 [mod=T2, lvl=INFO] [tid=1190] NotifyTimeout: profile RDKM_RDKE_Profile is in Enabled state
2025-10-03T20:07:08.392Z telemetry2_0[1177]:  251003-20:07:08.392048 [mod=T2, lvl=INFO] [tid=1222] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKM_RDKE_Profile
2025-10-03T20:07:08.392Z telemetry2_0[1177]:  251003-20:07:08.392114 [mod=T2, lvl=INFO] [tid=1222] Waiting for timeref or reporting interval for the profile - RDKM_RDKE_Profile is started
2025-10-03T20:07:08.392Z telemetry2_0[1177]:  251003-20:07:08.392311 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- START
2025-10-03T20:07:08.392Z telemetry2_0[1177]:  251003-20:07:08.392357 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport ++in profileName : RDKM_RDKE_Profile
2025-10-03T20:07:08.392Z telemetry2_0[1177]:  251003-20:07:08.392403 [mod=T2, lvl=INFO] [tid=1190] Reporting Adjustments parameters check done successfully
2025-10-03T20:07:08.393Z telemetry2_0[1177]:  251003-20:07:08.392551 [mod=T2, lvl=INFO] [tid=1190] Profile Name: RDKE_TEST_Profile
2025-10-03T20:07:08.393Z telemetry2_0[1177]:  Configured httpURIReqParam count = 1
2025-10-03T20:07:08.393Z telemetry2_0[1177]:  251003-20:07:08.392586 [mod=T2, lvl=INFO] [tid=1190] Number of httpURIReqParam added  = 1
2025-10-03T20:07:08.394Z telemetry2_0[1177]:  251003-20:07:08.392783 [mod=T2, lvl=INFO] [tid=1190] Number of tr181params/markers successfully added in profile = 4
2025-10-03T20:07:08.394Z telemetry2_0[1177]:  251003-20:07:08.393285 [mod=T2, lvl=ERROR] [tid=1190] Failed to open file
2025-10-03T20:07:08.394Z telemetry2_0[1177]:  251003-20:07:08.393335 [mod=T2, lvl=INFO] [tid=1190] Successfully created/added new profile : RDKE_TEST_Profile
2025-10-03T20:07:08.394Z telemetry2_0[1177]:  251003-20:07:08.393396 [mod=T2, lvl=INFO] [tid=1224] Previous Logs report is sent clear the previousSeek flag
2025-10-03T20:07:08.394Z telemetry2_0[1177]:  251003-20:07:08.393413 [mod=T2, lvl=INFO] [tid=1190] Starting TimeoutThread for profile : RDKE_TEST_Profile
2025-10-03T20:07:08.394Z telemetry2_0[1177]:  251003-20:07:08.393442 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[{"PREVIOUS_LOG":"1"}]}
2025-10-03T20:07:08.394Z telemetry2_0[1177]:  251003-20:07:08.393503 [mod=T2, lvl=INFO] [tid=1224] Report Size = 33
2025-10-03T20:07:08.394Z telemetry2_0[1177]:  251003-20:07:08.393693 [mod=T2, lvl=INFO] [tid=1190] T2ER isn't initialized or dispatch thread is already running
2025-10-03T20:07:08.394Z telemetry2_0[1177]:  251003-20:07:08.393733 [mod=T2, lvl=INFO] [tid=1190] Successfully enabled profile : RDKE_TEST_Profile
2025-10-03T20:07:08.395Z telemetry2_0[1177]:  251003-20:07:08.393847 [mod=T2, lvl=INFO] [tid=1190] Completed processing 2 profiles on the disk,trying to fetch new/updated profiles
2025-10-03T20:07:08.398Z telemetry2_0[1177]:  251003-20:07:08.398265 [mod=T2, lvl=INFO] [tid=1225] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_TEST_Profile
2025-10-03T20:07:08.398Z telemetry2_0[1177]:  251003-20:07:08.398341 [mod=T2, lvl=INFO] [tid=1225] Waiting for timeref or reporting interval for the profile - RDKE_TEST_Profile is started
2025-10-03T20:07:08.400Z telemetry2_0[1177]:  251003-20:07:08.400013 [mod=T2, lvl=ERROR] [tid=1207] :0 Failed to get the data. Error : 1
2025-10-03T20:07:08.400Z telemetry2_0[1177]:  251003-20:07:08.400090 [mod=T2, lvl=ERROR] [tid=1207] Failed to retrieve param : Device.DeviceInfo.X_COMCAST-COM_STB_IP
2025-10-03T20:07:08.410Z telemetry2_0[1177]:  251003-20:07:08.408424 [mod=T2, lvl=ERROR] [tid=1224] getParameterValue partner id failed
2025-10-03T20:07:08.421Z telemetry2_0[1177]:  251003-20:07:08.419080 [mod=T2, lvl=INFO] [tid=1234] process_rp_thread: Waiting for event from tr-181
2025-10-03T20:07:08.421Z telemetry2_0[1177]:  251003-20:07:08.419258 [mod=T2, lvl=INFO] [tid=1236] process_tmprp_thread: Waiting for event from tr-181
2025-10-03T20:07:08.441Z telemetry2_0[1177]:  251003-20:07:08.441058 [mod=T2, lvl=INFO] [tid=1207] Fetch complete for TR-181 Object/Parameter Values for parameters
2025-10-03T20:07:08.443Z telemetry2_0[1177]:  251003-20:07:08.442967 [mod=T2, lvl=INFO] [tid=1207] Grep complete for 2 markers
2025-10-03T20:07:08.443Z telemetry2_0[1177]:  251003-20:07:08.443241 [mod=T2, lvl=INFO] [tid=1207] Execution Count = 1
2025-10-03T20:07:08.443Z telemetry2_0[1177]:  251003-20:07:08.443320 [mod=T2, lvl=INFO] [tid=1207] Processing time for profile RDKMA_Test is 0 seconds
2025-10-03T20:07:08.443Z telemetry2_0[1177]:  251003-20:07:08.443359 [mod=T2, lvl=INFO] [tid=1207] cJSON Report = {"searchResult":[{"T2":"1.0"},{"Profile":"RDKV"},{"Time":"2025-10-03 20:07:08"},{"PREVIOUS_LOG":"1"},{"mac":"D8:3A:DD:0D:16:B7"},{"PartnerId":"community"},{"Version":"lib32-application-test-image-RPI4-20251030114955"},{"AccountId":"\"1234\""}]}
2025-10-03T20:07:08.443Z telemetry2_0[1177]:  251003-20:07:08.443396 [mod=T2, lvl=INFO] [tid=1207] Report Size = 244
2025-10-03T20:07:08.450Z telemetry2_0[1177]:  curl failed: Couldn't resolve host name
2025-10-03T20:07:08.465Z telemetry2_0[1177]:  Inside FUNC register_sub_docs LINE 240
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  Inside FUNC initMessageQueue LINE 1124
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  Inside FUNC messageQueueProcessing LINE 899
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  Inside FUNC display_subDocs LINE 88
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  Inside FUNC check_component_crash LINE 359, init file is /tmp/telemetry_initialized_bootup
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  /tmp/telemetry_initialized_bootup file not present, need to send component init event to webconfig
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  notifyVersion_to_Webconfig : doc name telemetry , doc version 0
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  251003-20:07:08.465783 [mod=T2, lvl=INFO] [tid=1252] :0 RBus Enabled
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  Entering rbusInit
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  251003-20:07:08.465892 [mod=T2, lvl=INFO] [tid=1252] :0 rbus open for component: telemetry2_0_wbcfg
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  251003-20:07:08.466240 [mod=T2, lvl=INFO] [tid=1252] :0  rbus open (telemetry2_0_wbcfg) success
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  isWebCfgRbusEnabled: rbus enabled is 1
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  subscribeSubdocForceReset: Discover component for event 'Device.X_RDK_WebConfig.webcfgSubdocForceReset'
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  webcfg_rbus_discover_component rbusModuleList[Device.X_RDK_WebConfig.webcfgSubdocForceReset]
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  251003-20:07:08.466751 [mod=T2, lvl=INFO] [tid=1224] The return status from the child with pid 1230 is CurlStatus : 1
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  251003-20:07:08.466826 [mod=T2, lvl=INFO] [tid=1224] The return status from the child with pid 1230 SetopCode: No error; ResponseCode : Couldn't resolve host name; HTTP_CODE : 0; Line Number : 521
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  251003-20:07:08.466884 [mod=T2, lvl=INFO] [tid=1224] Report Cached, No. of reportes cached = 1
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  251003-20:07:08.467354 [mod=T2, lvl=INFO] [tid=1224] Elapsed Time for : RDKM_RDKE_Profile = 0.74951648 (Sec.NanoSec)
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  251003-20:07:08.467425 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- END; wait for restart event
2025-10-03T20:07:08.467Z telemetry2_0[1177]:  251003-20:07:08.467458 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport --out
2025-10-03T20:07:08.475Z telemetry2_0[1177]:  isWebCfgRbusEnabled: rbus enabled is 1
2025-10-03T20:07:08.475Z telemetry2_0[1177]:  sendWebConfigSignal : rbus_set :: event_name : webconfigSignal ::
2025-10-03T20:07:08.475Z telemetry2_0[1177]:  251003-20:07:08.475330 [mod=T2, lvl=ERROR] [tid=1190] :0 _setInternal for webconfigSignal failed with error: Entry not found error
2025-10-03T20:07:08.913Z telemetry2_0[1177]:  sendWebConfigSignal rbus_set for webconfigSignal failed & returns with Err: 5
2025-10-03T20:07:08.913Z telemetry2_0[1177]:  webcfg_rbus_discover_component: (1-1)ret[TRUE]
2025-10-03T20:07:08.913Z telemetry2_0[1177]:  251003-20:07:08.913168 [mod=T2, lvl=INFO] [tid=1193] :0 Consumer=rbus.sysTimeMgr.INBOX.1309 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-10-03T20:07:08.913Z telemetry2_0[1177]:  251003-20:07:08.913247 [mod=T2, lvl=INFO] [tid=1193] eventSubHandler called:
2025-10-03T20:07:08.913Z telemetry2_0[1177]:    action=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:04:31.353Z telemetry2_0[1177]:  251128-19:04:31.351741 [mod=T2, lvl=INFO] [tid=1193] :0 Consumer=rbus.dcmd.INBOX.1178 SUBSCRIBED to event=Device.DCM.Setconfig
2025-11-28T19:04:31.353Z telemetry2_0[1177]:  251128-19:04:31.351799 [mod=T2, lvl=INFO] [tid=1193] eventSubHandler called:
2025-11-28T19:04:31.353Z telemetry2_0[1177]:    action=subscribe        eventName=Device.DCM.Setconfig
2025-11-28T19:04:31.354Z telemetry2_0[1177]:  251128-19:04:31.354792 [mod=T2, lvl=INFO] [tid=1193] :0 Consumer=rbus.dcmd.INBOX.1178 SUBSCRIBED to event=Device.DCM.Processconfig
2025-11-28T19:04:31.355Z telemetry2_0[1177]:  251128-19:04:31.354848 [mod=T2, lvl=INFO] [tid=1193] eventSubHandler called:
2025-11-28T19:04:31.355Z telemetry2_0[1177]:    action=subscribe        eventName=Device.DCM.Processconfig
2025-11-28T19:04:32.351Z telemetry2_0[1177]:  251128-19:04:32.350640 [mod=T2, lvl=INFO] [tid=1193] rbusReloadConf ++in
2025-11-28T19:04:32.351Z telemetry2_0[1177]:  251128-19:04:32.350700 [mod=T2, lvl=INFO] [tid=1193] Recieved eventName: Device.X_RDKCENTREL-COM.Reloadconfig, Event type: 3, Event Name: Device.X_RDKCENTREL-COM.Reloadconfig
2025-11-28T19:04:32.351Z telemetry2_0[1177]:  251128-19:04:32.350735 [mod=T2, lvl=INFO] [tid=1193] rbusReloadConf --out
2025-11-28T19:04:37.421Z telemetry2_0[1177]:  251128-19:04:37.420284 [mod=T2, lvl=INFO] [tid=1193] :0 Consumer=rbus.rdkvfwupgrader.INBOX.2149 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:04:37.421Z telemetry2_0[1177]:  251128-19:04:37.420360 [mod=T2, lvl=INFO] [tid=1193] eventSubHandler called:
2025-11-28T19:04:37.421Z telemetry2_0[1177]:    action=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:04:40.574Z telemetry2_0[1177]:  subscribeSubdocForceReset: [Device.X_RDK_WebConfig.webcfgSubdocForceReset] Component found
2025-11-28T19:04:40.574Z telemetry2_0[1177]:  subscribeSubdocForceReset: subscribing to event Device.X_RDK_WebConfig.webcfgSubdocForceReset
2025-11-28T19:04:40.574Z telemetry2_0[1177]:  251128-19:04:40.573865 [mod=T2, lvl=INFO] [tid=1252] :0 Subscription for Device.X_RDK_WebConfig.webcfgSubdocForceReset::Device.X_RDK_WebConfig.webcfgSubdocForceReset is now active.
2025-11-28T19:04:40.574Z telemetry2_0[1177]:  251128-19:04:40.573951 [mod=T2, lvl=INFO] [tid=1252] :0 Device.X_RDK_WebConfig.webcfgSubdocForceReset subscribe retries succeeded
2025-11-28T19:04:59.904Z telemetry2_0[1177]:  subscribeSubdocForceReset: subscribed to event Device.X_RDK_WebConfig.webcfgSubdocForceReset
2025-11-28T19:04:59.904Z telemetry2_0[1177]:  subscribeSubdocForceReset: job done
2025-11-28T19:04:59.904Z telemetry2_0[1177]:  251128-19:04:59.904152 [mod=T2, lvl=INFO] [tid=1225] TIMEOUT for profile - RDKE_TEST_Profile
2025-11-28T19:04:59.905Z telemetry2_0[1177]:  251128-19:04:59.904152 [mod=T2, lvl=INFO] [tid=1222] TIMEOUT for profile - RDKM_RDKE_Profile
2025-11-28T19:04:59.905Z telemetry2_0[1177]:  251128-19:04:59.904247 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb ++in
2025-11-28T19:04:59.905Z telemetry2_0[1177]:  251128-19:04:59.904270 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb ++in
2025-11-28T19:04:59.905Z telemetry2_0[1177]:  251128-19:04:59.904286 [mod=T2, lvl=INFO] [tid=1222] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:04:59.905Z telemetry2_0[1177]:  251128-19:04:59.904317 [mod=T2, lvl=INFO] [tid=1225] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:06:17.061Z telemetry2_0[1177]:  251128-19:06:17.061432 [mod=T2, lvl=INFO] [tid=1207] This is a Previous Logs Report sleep randomly for 1-50 sec
2025-11-28T19:06:17.067Z telemetry2_0[1177]:  251128-19:06:17.066818 [mod=T2, lvl=ERROR] [tid=1207] getParameterValue partner id failed
2025-11-28T19:06:47.070Z telemetry2_0[1177]:  curl failed: Timeout was reached
2025-11-28T19:06:47.075Z telemetry2_0[1177]:  251128-19:06:47.073734 [mod=T2, lvl=INFO] [tid=1207] The return status from the child with pid 2462 is CurlStatus : 1
2025-11-28T19:06:47.075Z telemetry2_0[1177]:  251128-19:06:47.073834 [mod=T2, lvl=INFO] [tid=1207] The return status from the child with pid 2462 SetopCode: No error; ResponseCode : Timeout was reached; HTTP_CODE : 0; Line Number : 521
2025-11-28T19:06:47.075Z telemetry2_0[1177]:  251128-19:06:47.074663 [mod=T2, lvl=INFO] [tid=1207] Previous Logs report is sent clear the previousSeek flag
2025-11-28T19:06:47.075Z telemetry2_0[1177]:  251128-19:06:47.074780 [mod=T2, lvl=INFO] [tid=1207] Report Cached, No. of reportes cached = 2
2025-11-28T19:06:47.076Z telemetry2_0[1177]:  251128-19:06:47.076428 [mod=T2, lvl=INFO] [tid=1207] Successfully saved grep config to file for profile: RDKMA_Test
2025-11-28T19:06:47.076Z telemetry2_0[1177]:  251128-19:06:47.076497 [mod=T2, lvl=INFO] [tid=1207] Elapsed Time for : RDKMA_Test = 4834778.722124728 (Sec.NanoSec)
2025-11-28T19:06:47.076Z telemetry2_0[1177]:  251128-19:06:47.076532 [mod=T2, lvl=INFO] [tid=1207] CollectAndReportXconf while Loop -- END
2025-11-28T19:06:47.076Z telemetry2_0[1177]:  251128-19:06:47.076565 [mod=T2, lvl=INFO] [tid=1207] CollectAndReportXconf --out
2025-11-28T19:06:47.077Z telemetry2_0[1177]:  251128-19:06:47.076739 [mod=T2, lvl=INFO] [tid=1190] :0 RBus Enabled
2025-11-28T19:06:47.077Z telemetry2_0[1177]:  251128-19:06:47.076829 [mod=T2, lvl=INFO] [tid=1190] No data elements present to unregister
2025-11-28T19:06:47.080Z telemetry2_0[1177]:  251128-19:06:47.077786 [mod=T2, lvl=INFO] [tid=1222] NotifyTimeout: profile RDKM_RDKE_Profile is in Enabled state
2025-11-28T19:06:47.080Z telemetry2_0[1177]:  251128-19:06:47.077829 [mod=T2, lvl=INFO] [tid=1190] T2 is now Ready to be configured for report profiles
2025-11-28T19:06:47.080Z telemetry2_0[1177]:  251128-19:06:47.077916 [mod=T2, lvl=INFO] [tid=1190] T2ER isn't initialized or dispatch thread is already running
2025-11-28T19:06:47.080Z telemetry2_0[1177]:  251128-19:06:47.077847 [mod=T2, lvl=INFO] [tid=1222] Signal Thread To restart
2025-11-28T19:06:47.080Z telemetry2_0[1177]:  251128-19:06:47.078051 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb --out
2025-11-28T19:06:47.081Z telemetry2_0[1177]:  251128-19:06:47.078099 [mod=T2, lvl=INFO] [tid=1222] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKM_RDKE_Profile
2025-11-28T19:06:47.081Z telemetry2_0[1177]:  251128-19:06:47.078148 [mod=T2, lvl=INFO] [tid=1222] Waiting for timeref or reporting interval for the profile - RDKM_RDKE_Profile is started
2025-11-28T19:06:47.081Z telemetry2_0[1177]:  251128-19:06:47.078230 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- START
2025-11-28T19:06:47.081Z telemetry2_0[1177]:  251128-19:06:47.078254 [mod=T2, lvl=INFO] [tid=1190] InitProperties is successful
2025-11-28T19:06:47.081Z telemetry2_0[1177]:  251128-19:06:47.078275 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport ++in profileName : RDKM_RDKE_Profile
2025-11-28T19:06:47.081Z telemetry2_0[1177]:  251128-19:06:47.078370 [mod=T2, lvl=INFO] [tid=1190] Init ReportProfiles Successful
2025-11-28T19:06:47.081Z telemetry2_0[1177]:  251128-19:06:47.078777 [mod=T2, lvl=INFO] [tid=1225] NotifyTimeout: profile RDKE_TEST_Profile is in Enabled state
2025-11-28T19:06:47.081Z telemetry2_0[1177]:  251128-19:06:47.078981 [mod=T2, lvl=INFO] [tid=1190] Init Xconf Client Success
2025-11-28T19:06:47.081Z telemetry2_0[1177]:  251128-19:06:47.079032 [mod=T2, lvl=INFO] [tid=1190] Triggering XCONF report generation during boot with delay
2025-11-28T19:06:47.083Z telemetry2_0[1177]:  251128-19:06:47.082608 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[{"NW_DNSMASQ":""},{"Xi_wifiMAC_split":"D8:3A:DD:0D:16:B8"}]}
2025-11-28T19:06:47.083Z telemetry2_0[1177]:  251128-19:06:47.082686 [mod=T2, lvl=INFO] [tid=1224] Report Size = 71
2025-11-28T19:06:47.085Z telemetry2_0[1177]:  251128-19:06:47.084718 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb --out
2025-11-28T19:06:47.085Z telemetry2_0[1177]:  251128-19:06:47.084789 [mod=T2, lvl=INFO] [tid=1225] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_TEST_Profile
2025-11-28T19:06:47.085Z telemetry2_0[1177]:  251128-19:06:47.084840 [mod=T2, lvl=INFO] [tid=1225] Waiting for timeref or reporting interval for the profile - RDKE_TEST_Profile is started
2025-11-28T19:06:47.085Z telemetry2_0[1177]:  251128-19:06:47.084945 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- START
2025-11-28T19:06:47.085Z telemetry2_0[1177]:  251128-19:06:47.084983 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport ++in profileName : RDKE_TEST_Profile
2025-11-28T19:06:47.088Z telemetry2_0[1177]:  251128-19:06:47.087709 [mod=T2, lvl=INFO] [tid=2488] Setting config URL base location to : https://xconf.rdkcentral.com/loguploader/getT2Settings
2025-11-28T19:06:47.095Z telemetry2_0[1177]:  251128-19:06:47.093989 [mod=T2, lvl=ERROR] [tid=1224] getParameterValue partner id failed
2025-11-28T19:06:47.106Z telemetry2_0[1177]:  251128-19:06:47.106792 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7539060"},{"MemStatTotal":"8010996"}]}
2025-11-28T19:06:47.107Z telemetry2_0[1177]:  251128-19:06:47.107434 [mod=T2, lvl=INFO] [tid=2489] Report Size = 118
2025-11-28T19:06:47.121Z telemetry2_0[1177]:  251128-19:06:47.121704 [mod=T2, lvl=ERROR] [tid=2489] getParameterValue partner id failed
2025-11-28T19:06:47.125Z telemetry2_0[1177]:  251128-19:06:47.123393 [mod=T2, lvl=INFO] [tid=2488] 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=%221234%22&ecmMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&env=dev&controllerId=2504&channelMapId=2345&vodId=15660&timezone=America%2fNew_York&version=2 with Length :379
2025-11-28T19:06:47.125Z telemetry2_0[1177]:  251128-19:06:47.125776 [mod=T2, lvl=INFO] [tid=2488] 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=%221234%22&ecmMacAddress=D8%3a3A%3aDD%3a0D%3a16%3aB7&env=dev&controllerId=2504&channelMapId=2345&vodId=15660&timezone=America%2fNew_York&version=2
2025-11-28T19:06:47.131Z telemetry2_0[1177]:  251128-19:06:47.131333 [mod=T2, lvl=ERROR] [tid=2488] getParameterValue partner id failed
2025-11-28T19:06:47.559Z telemetry2_0[1177]:  251128-19:06:47.559631 [mod=T2, lvl=INFO] [tid=2492] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-28T19:06:47.568Z telemetry2_0[1177]:  251128-19:06:47.567736 [mod=T2, lvl=INFO] [tid=2489] The return status from the child with pid 2492 is CurlStatus : 1
2025-11-28T19:06:47.568Z telemetry2_0[1177]:  251128-19:06:47.567837 [mod=T2, lvl=INFO] [tid=2489] The return status from the child with pid 2492 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-28T19:06:47.568Z telemetry2_0[1177]:  251128-19:06:47.567874 [mod=T2, lvl=INFO] [tid=2489] Report Sent Successfully over HTTP : 200
2025-11-28T19:06:47.568Z telemetry2_0[1177]:  251128-19:06:47.567922 [mod=T2, lvl=INFO] [tid=2489] Elapsed Time for : RDKE_TEST_Profile = 0.482901377 (Sec.NanoSec)
2025-11-28T19:06:47.568Z telemetry2_0[1177]:  251128-19:06:47.567969 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- END; wait for restart event
2025-11-28T19:06:47.568Z telemetry2_0[1177]:  251128-19:06:47.568001 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport --out
2025-11-28T19:06:47.648Z telemetry2_0[1177]:  251128-19:06:47.648027 [mod=T2, lvl=INFO] [tid=2490] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-28T19:06:47.656Z telemetry2_0[1177]:  251128-19:06:47.656030 [mod=T2, lvl=INFO] [tid=1224] The return status from the child with pid 2490 is CurlStatus : 1
2025-11-28T19:06:47.656Z telemetry2_0[1177]:  251128-19:06:47.656139 [mod=T2, lvl=INFO] [tid=1224] The return status from the child with pid 2490 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-28T19:06:47.656Z telemetry2_0[1177]:  251128-19:06:47.656185 [mod=T2, lvl=INFO] [tid=1224] Report Sent Successfully over HTTP : 200
2025-11-28T19:06:47.656Z telemetry2_0[1177]:  251128-19:06:47.656229 [mod=T2, lvl=INFO] [tid=1224] Trying to send  1 cached reports
2025-11-28T19:06:47.660Z telemetry2_0[1177]:  251128-19:06:47.660766 [mod=T2, lvl=ERROR] [tid=1224] getParameterValue partner id failed
2025-11-28T19:06:48.002Z telemetry2_0[1177]:  251128-19:06:48.001902 [mod=T2, lvl=INFO] [tid=2494] doHttpGet:826, T2:Telemetry XCONF communication success
2025-11-28T19:06:48.010Z telemetry2_0[1177]:  251128-19:06:48.010514 [mod=T2, lvl=INFO] [tid=2488] Received profile name : RDKMA_Test with interval of : 900 secs and upload url : http://xconf.rdkcentral.com/xconf/logupload.php
2025-11-28T19:06:48.011Z telemetry2_0[1177]:  251128-19:06:48.010651 [mod=T2, lvl=INFO] [tid=2488] Number of tr181params/markers successfully added in profile = 4
2025-11-28T19:06:48.026Z telemetry2_0[1177]:  251128-19:06:48.025880 [mod=T2, lvl=ERROR] [tid=2488] Failed to remove the file /tmp/t2_caching_file
2025-11-28T19:06:48.026Z telemetry2_0[1177]:  251128-19:06:48.026023 [mod=T2, lvl=ERROR] [tid=2488] Component name list is not initialized . Re-initializing
2025-11-28T19:06:48.026Z telemetry2_0[1177]:  251128-19:06:48.026118 [mod=T2, lvl=INFO] [tid=2488] Profile exists already, updating the config in file system
2025-11-28T19:06:48.026Z telemetry2_0[1177]:  251128-19:06:48.026154 [mod=T2, lvl=INFO] [tid=2488] There are 2 cached reports in the profile
2025-11-28T19:06:48.026Z telemetry2_0[1177]:  251128-19:06:48.026192 [mod=T2, lvl=INFO] [tid=2488] check the events from the old profile and forward to the new profile
2025-11-28T19:06:48.026Z telemetry2_0[1177]:  251128-19:06:48.026299 [mod=T2, lvl=INFO] [tid=2488] removing profile : RDKMA_Test
2025-11-28T19:06:48.026Z telemetry2_0[1177]:  251128-19:06:48.026458 [mod=T2, lvl=INFO] [tid=2488] Scheduler already assigned for profile RDKMA_Test , exiting .
2025-11-28T19:06:48.026Z telemetry2_0[1177]:  251128-19:06:48.026498 [mod=T2, lvl=INFO] [tid=2488] Successfully set profile : RDKMA_Test
2025-11-28T19:06:48.026Z telemetry2_0[1177]:  251128-19:06:48.026541 [mod=T2, lvl=INFO] [tid=2488] T2ER isn't initialized or dispatch thread isn't running
2025-11-28T19:06:48.027Z telemetry2_0[1177]:  251128-19:06:48.026590 [mod=T2, lvl=INFO] [tid=2488] :0 RBus Enabled
2025-11-28T19:06:48.028Z telemetry2_0[1177]:  251128-19:06:48.028268 [mod=T2, lvl=INFO] [tid=2488] Successfully set new profile : RDKMA_Test
2025-11-28T19:06:48.028Z telemetry2_0[1177]:  251128-19:06:48.028448 [mod=T2, lvl=INFO] [tid=2488] Set DCM flag for sending events
2025-11-28T19:06:48.079Z telemetry2_0[1177]:  251128-19:06:48.078880 [mod=T2, lvl=INFO] [tid=2487] Publishing the set conf event Path: /opt/.t2persistentfolder/DCMresponse.txt
2025-11-28T19:06:48.079Z telemetry2_0[1177]:  251128-19:06:48.079809 [mod=T2, lvl=INFO] [tid=2487] Publishing the Process conf event
2025-11-28T19:06:48.100Z telemetry2_0[1177]:  251128-19:06:48.100699 [mod=T2, lvl=INFO] [tid=2496] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-28T19:06:48.109Z telemetry2_0[1177]:  251128-19:06:48.109238 [mod=T2, lvl=INFO] [tid=1224] The return status from the child with pid 2496 is CurlStatus : 1
2025-11-28T19:06:48.109Z telemetry2_0[1177]:  251128-19:06:48.109326 [mod=T2, lvl=INFO] [tid=1224] The return status from the child with pid 2496 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-28T19:06:48.109Z telemetry2_0[1177]:  251128-19:06:48.109374 [mod=T2, lvl=INFO] [tid=1224] Report Sent Successfully over HTTP : 200
2025-11-28T19:06:48.109Z telemetry2_0[1177]:  251128-19:06:48.109751 [mod=T2, lvl=INFO] [tid=1224] Elapsed Time for : RDKM_RDKE_Profile = 1.31434059 (Sec.NanoSec)
2025-11-28T19:06:48.109Z telemetry2_0[1177]:  251128-19:06:48.109812 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- END; wait for restart event
2025-11-28T19:06:48.110Z telemetry2_0[1177]:  251128-19:06:48.109846 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport --out
2025-11-28T19:07:16.439Z telemetry2_0[1177]:  251128-19:07:16.438799 [mod=T2, lvl=INFO] [tid=1222] TIMEOUT for profile - RDKM_RDKE_Profile
2025-11-28T19:07:16.439Z telemetry2_0[1177]:  251128-19:07:16.438798 [mod=T2, lvl=INFO] [tid=1225] TIMEOUT for profile - RDKE_TEST_Profile
2025-11-28T19:07:16.439Z telemetry2_0[1177]:  251128-19:07:16.438890 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb ++in
2025-11-28T19:07:16.439Z telemetry2_0[1177]:  251128-19:07:16.438928 [mod=T2, lvl=INFO] [tid=1222] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:07:16.439Z telemetry2_0[1177]:  251128-19:07:16.438998 [mod=T2, lvl=INFO] [tid=1222] NotifyTimeout: profile RDKM_RDKE_Profile is in Enabled state
2025-11-28T19:07:16.439Z telemetry2_0[1177]:  251128-19:07:16.439032 [mod=T2, lvl=INFO] [tid=1222] Signal Thread To restart
2025-11-28T19:07:16.439Z telemetry2_0[1177]:  251128-19:07:16.439095 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb --out
2025-11-28T19:07:16.440Z telemetry2_0[1177]:  251128-19:07:16.439139 [mod=T2, lvl=INFO] [tid=1222] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKM_RDKE_Profile
2025-11-28T19:07:16.440Z telemetry2_0[1177]:  251128-19:07:16.439188 [mod=T2, lvl=INFO] [tid=1222] Waiting for timeref or reporting interval for the profile - RDKM_RDKE_Profile is started
2025-11-28T19:07:16.440Z telemetry2_0[1177]:  251128-19:07:16.439252 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- START
2025-11-28T19:07:16.440Z telemetry2_0[1177]:  251128-19:07:16.439289 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport ++in profileName : RDKM_RDKE_Profile
2025-11-28T19:07:16.441Z telemetry2_0[1177]:  251128-19:07:16.441263 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[]}
2025-11-28T19:07:16.441Z telemetry2_0[1177]:  251128-19:07:16.441325 [mod=T2, lvl=WARN] [tid=1224] Array size of Report is 0. Report is empty. Cannot send empty report
2025-11-28T19:07:16.441Z telemetry2_0[1177]:  251128-19:07:16.441367 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- END; wait for restart event
2025-11-28T19:07:16.441Z telemetry2_0[1177]:  251128-19:07:16.441400 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport --out
2025-11-28T19:07:16.442Z telemetry2_0[1177]:  251128-19:07:16.441980 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb ++in
2025-11-28T19:07:16.442Z telemetry2_0[1177]:  251128-19:07:16.442037 [mod=T2, lvl=INFO] [tid=1225] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:07:16.443Z telemetry2_0[1177]:  251128-19:07:16.442103 [mod=T2, lvl=INFO] [tid=1225] NotifyTimeout: profile RDKE_TEST_Profile is in Enabled state
2025-11-28T19:07:16.443Z telemetry2_0[1177]:  251128-19:07:16.442137 [mod=T2, lvl=INFO] [tid=1225] Signal Thread To restart
2025-11-28T19:07:16.443Z telemetry2_0[1177]:  251128-19:07:16.442191 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb --out
2025-11-28T19:07:16.443Z telemetry2_0[1177]:  251128-19:07:16.442237 [mod=T2, lvl=INFO] [tid=1225] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_TEST_Profile
2025-11-28T19:07:16.443Z telemetry2_0[1177]:  251128-19:07:16.442286 [mod=T2, lvl=INFO] [tid=1225] Waiting for timeref or reporting interval for the profile - RDKE_TEST_Profile is started
2025-11-28T19:07:16.443Z telemetry2_0[1177]:  251128-19:07:16.442370 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- START
2025-11-28T19:07:16.443Z telemetry2_0[1177]:  251128-19:07:16.442409 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport ++in profileName : RDKE_TEST_Profile
2025-11-28T19:07:16.453Z telemetry2_0[1177]:  251128-19:07:16.453067 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7529760"},{"MemStatTotal":"8010996"}]}
2025-11-28T19:07:16.453Z telemetry2_0[1177]:  251128-19:07:16.453127 [mod=T2, lvl=INFO] [tid=2489] Report Size = 118
2025-11-28T19:07:16.460Z telemetry2_0[1177]:  251128-19:07:16.458251 [mod=T2, lvl=ERROR] [tid=2489] getParameterValue partner id failed
2025-11-28T19:07:16.899Z telemetry2_0[1177]:  251128-19:07:16.899356 [mod=T2, lvl=INFO] [tid=2627] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-28T19:07:16.907Z telemetry2_0[1177]:  251128-19:07:16.906816 [mod=T2, lvl=INFO] [tid=2489] The return status from the child with pid 2627 is CurlStatus : 1
2025-11-28T19:07:16.907Z telemetry2_0[1177]:  251128-19:07:16.906906 [mod=T2, lvl=INFO] [tid=2489] The return status from the child with pid 2627 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-28T19:07:16.907Z telemetry2_0[1177]:  251128-19:07:16.906942 [mod=T2, lvl=INFO] [tid=2489] Report Sent Successfully over HTTP : 200
2025-11-28T19:07:16.907Z telemetry2_0[1177]:  251128-19:07:16.906991 [mod=T2, lvl=INFO] [tid=2489] Elapsed Time for : RDKE_TEST_Profile = 0.464545009 (Sec.NanoSec)
2025-11-28T19:07:16.907Z telemetry2_0[1177]:  251128-19:07:16.907039 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- END; wait for restart event
2025-11-28T19:07:16.907Z telemetry2_0[1177]:  251128-19:07:16.907072 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport --out
2025-11-28T19:07:46.439Z telemetry2_0[1177]:  251128-19:07:46.438812 [mod=T2, lvl=INFO] [tid=1222] TIMEOUT for profile - RDKM_RDKE_Profile
2025-11-28T19:07:46.439Z telemetry2_0[1177]:  251128-19:07:46.438817 [mod=T2, lvl=INFO] [tid=1225] TIMEOUT for profile - RDKE_TEST_Profile
2025-11-28T19:07:46.439Z telemetry2_0[1177]:  251128-19:07:46.438899 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb ++in
2025-11-28T19:07:46.439Z telemetry2_0[1177]:  251128-19:07:46.438918 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb ++in
2025-11-28T19:07:46.439Z telemetry2_0[1177]:  251128-19:07:46.438937 [mod=T2, lvl=INFO] [tid=1222] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:07:46.439Z telemetry2_0[1177]:  251128-19:07:46.438975 [mod=T2, lvl=INFO] [tid=1225] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439040 [mod=T2, lvl=INFO] [tid=1222] NotifyTimeout: profile RDKM_RDKE_Profile is in Enabled state
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439073 [mod=T2, lvl=INFO] [tid=1225] NotifyTimeout: profile RDKE_TEST_Profile is in Enabled state
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439084 [mod=T2, lvl=INFO] [tid=1222] Signal Thread To restart
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439116 [mod=T2, lvl=INFO] [tid=1225] Signal Thread To restart
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439175 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb --out
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439193 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb --out
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439222 [mod=T2, lvl=INFO] [tid=1225] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_TEST_Profile
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439240 [mod=T2, lvl=INFO] [tid=1222] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKM_RDKE_Profile
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439271 [mod=T2, lvl=INFO] [tid=1225] Waiting for timeref or reporting interval for the profile - RDKE_TEST_Profile is started
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439288 [mod=T2, lvl=INFO] [tid=1222] Waiting for timeref or reporting interval for the profile - RDKM_RDKE_Profile is started
2025-11-28T19:07:46.440Z telemetry2_0[1177]:  251128-19:07:46.439334 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- START
2025-11-28T19:07:46.441Z telemetry2_0[1177]:  251128-19:07:46.439371 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport ++in profileName : RDKE_TEST_Profile
2025-11-28T19:07:46.441Z telemetry2_0[1177]:  251128-19:07:46.439380 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- START
2025-11-28T19:07:46.441Z telemetry2_0[1177]:  251128-19:07:46.439433 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport ++in profileName : RDKM_RDKE_Profile
2025-11-28T19:07:46.443Z telemetry2_0[1177]:  251128-19:07:46.442181 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[]}
2025-11-28T19:07:46.443Z telemetry2_0[1177]:  251128-19:07:46.442252 [mod=T2, lvl=WARN] [tid=1224] Array size of Report is 0. Report is empty. Cannot send empty report
2025-11-28T19:07:46.443Z telemetry2_0[1177]:  251128-19:07:46.442294 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- END; wait for restart event
2025-11-28T19:07:46.443Z telemetry2_0[1177]:  251128-19:07:46.442341 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport --out
2025-11-28T19:07:46.451Z telemetry2_0[1177]:  251128-19:07:46.450892 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7523812"},{"MemStatTotal":"8010996"}]}
2025-11-28T19:07:46.451Z telemetry2_0[1177]:  251128-19:07:46.450955 [mod=T2, lvl=INFO] [tid=2489] Report Size = 118
2025-11-28T19:07:46.456Z telemetry2_0[1177]:  251128-19:07:46.456521 [mod=T2, lvl=ERROR] [tid=2489] getParameterValue partner id failed
2025-11-28T19:07:46.893Z telemetry2_0[1177]:  251128-19:07:46.893057 [mod=T2, lvl=INFO] [tid=2688] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-28T19:07:46.900Z telemetry2_0[1177]:  251128-19:07:46.900472 [mod=T2, lvl=INFO] [tid=2489] The return status from the child with pid 2688 is CurlStatus : 1
2025-11-28T19:07:46.900Z telemetry2_0[1177]:  251128-19:07:46.900562 [mod=T2, lvl=INFO] [tid=2489] The return status from the child with pid 2688 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-28T19:07:46.900Z telemetry2_0[1177]:  251128-19:07:46.900598 [mod=T2, lvl=INFO] [tid=2489] Report Sent Successfully over HTTP : 200
2025-11-28T19:07:46.901Z telemetry2_0[1177]:  251128-19:07:46.900647 [mod=T2, lvl=INFO] [tid=2489] Elapsed Time for : RDKE_TEST_Profile = 0.461233385 (Sec.NanoSec)
2025-11-28T19:07:46.901Z telemetry2_0[1177]:  251128-19:07:46.900695 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- END; wait for restart event
2025-11-28T19:07:46.901Z telemetry2_0[1177]:  251128-19:07:46.900728 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport --out
2025-11-28T19:07:58.070Z telemetry2_0[1177]:  251128-19:07:58.070385 [mod=T2, lvl=INFO] [tid=1193] :0 Consumer=rbus.rdkvfwupgrader.INBOX.2149 UNSUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:07:58.071Z telemetry2_0[1177]:  251128-19:07:58.070447 [mod=T2, lvl=INFO] [tid=1193] eventSubHandler called:
2025-11-28T19:07:58.071Z telemetry2_0[1177]:    action=unsubscribe      eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:08:16.439Z telemetry2_0[1177]:  251128-19:08:16.438826 [mod=T2, lvl=INFO] [tid=1225] TIMEOUT for profile - RDKE_TEST_Profile
2025-11-28T19:08:16.439Z telemetry2_0[1177]:  251128-19:08:16.438887 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb ++in
2025-11-28T19:08:16.439Z telemetry2_0[1177]:  251128-19:08:16.438926 [mod=T2, lvl=INFO] [tid=1225] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:08:16.439Z telemetry2_0[1177]:  251128-19:08:16.438992 [mod=T2, lvl=INFO] [tid=1225] NotifyTimeout: profile RDKE_TEST_Profile is in Enabled state
2025-11-28T19:08:16.439Z telemetry2_0[1177]:  251128-19:08:16.439026 [mod=T2, lvl=INFO] [tid=1225] Signal Thread To restart
2025-11-28T19:08:16.440Z telemetry2_0[1177]:  251128-19:08:16.439080 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb --out
2025-11-28T19:08:16.440Z telemetry2_0[1177]:  251128-19:08:16.439125 [mod=T2, lvl=INFO] [tid=1225] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_TEST_Profile
2025-11-28T19:08:16.440Z telemetry2_0[1177]:  251128-19:08:16.439172 [mod=T2, lvl=INFO] [tid=1225] Waiting for timeref or reporting interval for the profile - RDKE_TEST_Profile is started
2025-11-28T19:08:16.440Z telemetry2_0[1177]:  251128-19:08:16.439240 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- START
2025-11-28T19:08:16.440Z telemetry2_0[1177]:  251128-19:08:16.439277 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport ++in profileName : RDKE_TEST_Profile
2025-11-28T19:08:16.450Z telemetry2_0[1177]:  251128-19:08:16.442565 [mod=T2, lvl=INFO] [tid=1222] TIMEOUT for profile - RDKM_RDKE_Profile
2025-11-28T19:08:16.450Z telemetry2_0[1177]:  251128-19:08:16.442629 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb ++in
2025-11-28T19:08:16.450Z telemetry2_0[1177]:  251128-19:08:16.442665 [mod=T2, lvl=INFO] [tid=1222] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:08:16.450Z telemetry2_0[1177]:  251128-19:08:16.442778 [mod=T2, lvl=INFO] [tid=1222] NotifyTimeout: profile RDKM_RDKE_Profile is in Enabled state
2025-11-28T19:08:16.450Z telemetry2_0[1177]:  251128-19:08:16.442824 [mod=T2, lvl=INFO] [tid=1222] Signal Thread To restart
2025-11-28T19:08:16.450Z telemetry2_0[1177]:  251128-19:08:16.442879 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb --out
2025-11-28T19:08:16.451Z telemetry2_0[1177]:  251128-19:08:16.442922 [mod=T2, lvl=INFO] [tid=1222] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKM_RDKE_Profile
2025-11-28T19:08:16.451Z telemetry2_0[1177]:  251128-19:08:16.442970 [mod=T2, lvl=INFO] [tid=1222] Waiting for timeref or reporting interval for the profile - RDKM_RDKE_Profile is started
2025-11-28T19:08:16.451Z telemetry2_0[1177]:  251128-19:08:16.443059 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- START
2025-11-28T19:08:16.451Z telemetry2_0[1177]:  251128-19:08:16.443095 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport ++in profileName : RDKM_RDKE_Profile
2025-11-28T19:08:16.451Z telemetry2_0[1177]:  251128-19:08:16.445247 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[]}
2025-11-28T19:08:16.451Z telemetry2_0[1177]:  251128-19:08:16.445339 [mod=T2, lvl=WARN] [tid=1224] Array size of Report is 0. Report is empty. Cannot send empty report
2025-11-28T19:08:16.451Z telemetry2_0[1177]:  251128-19:08:16.445383 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- END; wait for restart event
2025-11-28T19:08:16.451Z telemetry2_0[1177]:  251128-19:08:16.445418 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport --out
2025-11-28T19:08:16.458Z telemetry2_0[1177]:  251128-19:08:16.458622 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7532276"},{"MemStatTotal":"8010996"}]}
2025-11-28T19:08:16.458Z telemetry2_0[1177]:  251128-19:08:16.458690 [mod=T2, lvl=INFO] [tid=2489] Report Size = 118
2025-11-28T19:08:16.464Z telemetry2_0[1177]:  251128-19:08:16.463715 [mod=T2, lvl=ERROR] [tid=2489] getParameterValue partner id failed
2025-11-28T19:08:16.922Z telemetry2_0[1177]:  251128-19:08:16.922163 [mod=T2, lvl=INFO] [tid=2791] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-28T19:08:16.930Z telemetry2_0[1177]:  251128-19:08:16.930090 [mod=T2, lvl=INFO] [tid=2489] The return status from the child with pid 2791 is CurlStatus : 1
2025-11-28T19:08:16.930Z telemetry2_0[1177]:  251128-19:08:16.930178 [mod=T2, lvl=INFO] [tid=2489] The return status from the child with pid 2791 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-28T19:08:16.930Z telemetry2_0[1177]:  251128-19:08:16.930214 [mod=T2, lvl=INFO] [tid=2489] Report Sent Successfully over HTTP : 200
2025-11-28T19:08:16.930Z telemetry2_0[1177]:  251128-19:08:16.930263 [mod=T2, lvl=INFO] [tid=2489] Elapsed Time for : RDKE_TEST_Profile = 0.490947344 (Sec.NanoSec)
2025-11-28T19:08:16.930Z telemetry2_0[1177]:  251128-19:08:16.930310 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- END; wait for restart event
2025-11-28T19:08:16.930Z telemetry2_0[1177]:  251128-19:08:16.930346 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport --out
2025-11-28T19:08:44.543Z telemetry2_0[1177]:  251128-19:08:44.542857 [mod=T2, lvl=INFO] [tid=1193] :0 Consumer=rbus.routerDiscovery.INBOX.1924 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:08:44.543Z telemetry2_0[1177]:  251128-19:08:44.542927 [mod=T2, lvl=INFO] [tid=1193] eventSubHandler called:
2025-11-28T19:08:44.543Z telemetry2_0[1177]:    action=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:08:46.439Z telemetry2_0[1177]:  251128-19:08:46.438822 [mod=T2, lvl=INFO] [tid=1222] TIMEOUT for profile - RDKM_RDKE_Profile
2025-11-28T19:08:46.439Z telemetry2_0[1177]:  251128-19:08:46.438827 [mod=T2, lvl=INFO] [tid=1225] TIMEOUT for profile - RDKE_TEST_Profile
2025-11-28T19:08:46.439Z telemetry2_0[1177]:  251128-19:08:46.438908 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb ++in
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.438926 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb ++in
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.438947 [mod=T2, lvl=INFO] [tid=1222] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.438970 [mod=T2, lvl=INFO] [tid=1225] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.439011 [mod=T2, lvl=INFO] [tid=1222] NotifyTimeout: profile RDKM_RDKE_Profile is in Enabled state
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.439032 [mod=T2, lvl=INFO] [tid=1225] NotifyTimeout: profile RDKE_TEST_Profile is in Enabled state
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.439053 [mod=T2, lvl=INFO] [tid=1222] Signal Thread To restart
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.439066 [mod=T2, lvl=INFO] [tid=1225] Signal Thread To restart
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.439106 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb --out
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.439117 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb --out
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.439160 [mod=T2, lvl=INFO] [tid=1222] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKM_RDKE_Profile
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.439191 [mod=T2, lvl=INFO] [tid=1225] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_TEST_Profile
2025-11-28T19:08:46.440Z telemetry2_0[1177]:  251128-19:08:46.439209 [mod=T2, lvl=INFO] [tid=1222] Waiting for timeref or reporting interval for the profile - RDKM_RDKE_Profile is started
2025-11-28T19:08:46.441Z telemetry2_0[1177]:  251128-19:08:46.439239 [mod=T2, lvl=INFO] [tid=1225] Waiting for timeref or reporting interval for the profile - RDKE_TEST_Profile is started
2025-11-28T19:08:46.441Z telemetry2_0[1177]:  251128-19:08:46.439300 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- START
2025-11-28T19:08:46.441Z telemetry2_0[1177]:  251128-19:08:46.439344 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport ++in profileName : RDKE_TEST_Profile
2025-11-28T19:08:46.444Z telemetry2_0[1177]:  251128-19:08:46.439303 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- START
2025-11-28T19:08:46.444Z telemetry2_0[1177]:  251128-19:08:46.442429 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport ++in profileName : RDKM_RDKE_Profile
2025-11-28T19:08:46.445Z telemetry2_0[1177]:  251128-19:08:46.443342 [mod=T2, lvl=INFO] [tid=1224] cJSON Report = {"Report":[]}
2025-11-28T19:08:46.445Z telemetry2_0[1177]:  251128-19:08:46.443409 [mod=T2, lvl=WARN] [tid=1224] Array size of Report is 0. Report is empty. Cannot send empty report
2025-11-28T19:08:46.445Z telemetry2_0[1177]:  251128-19:08:46.443450 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport while Loop -- END; wait for restart event
2025-11-28T19:08:46.445Z telemetry2_0[1177]:  251128-19:08:46.443481 [mod=T2, lvl=INFO] [tid=1224] CollectAndReport --out
2025-11-28T19:08:46.454Z telemetry2_0[1177]:  251128-19:08:46.454006 [mod=T2, lvl=INFO] [tid=2489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7531648"},{"MemStatTotal":"8010996"}]}