Versions Compared

Old Version 6

changes.mady.by.user Leena Sunthari D

Saved on

compared with

New Version 7

changes.mady.by.user Leena Sunthari D

Saved on

Key

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

...

Code Block
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

Code Blocknoformat
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
 | grep -ine cjson
78:2025-10-03T20:07:08.556Z443Z telemetry2_0[11741177]:  251003-20:07:08.554777443359 [mod=T2, lvl=INFO] [tid=11741207] StartingcJSON Report 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= {"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=11931224] WhoAmIcJSON featureReport is disabled
2025-10-03T20:07:08.562Z telemetry2_0[1174]:  251003-20:07:08.560660 [mod=RBUSCORE= {"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=11932489] cJSON Report RBus Enabled
2025-10-03T20:07:08.563Z= {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7539060"},{"MemStatTotal":"8010996"}]}
406:2025-11-28T19:09:46.440Z telemetry2_0[11741177]:  251003251128-2019:0709:0846.560766440172 [mod=T2, lvl=INFO] [tid=11931224] :0cJSON rbusReport open for component: telemetry2_0
2025-10-03T20:07:08.563Z= {"Report":[]}
412:2025-11-28T19:09:46.455Z telemetry2_0[11741177]:  251003251128-2019:0709:0846.560881453785 [mod=T2, lvl=INFO] [tid=11932489] :0 RBus Enabled
2025-10-03T20:07:08.563ZcJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7531612"},{"MemStatTotal":"8010996"}]}
429:2025-11-28T19:09:47.122Z telemetry2_0[11741177]:  251003251128-2019:0709:0847.560969121936 [mod=T2, lvl=INFO] [tid=11931207] :0cJSON BrokerReport = {"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:~#

 

Code Block
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[1177address: unix:///tmp/rtrouted
2025-10-03T20:07:08.563Z telemetry2_0[1174]:  251003-20:07:08.562272465783 [mod=T2, lvl=INFO] [tid=11931252] :0  rbus open (telemetry2_0) successRBus Enabled
2025-10-03T20:07:08.466Z telemetry2_0[1177]:  Entering rbusInit
2025-10-03T20:07:08.583Z466Z telemetry2_0[11741177]:  251003-20:07:08.580778465892 [mod=T2, lvl=ERRORINFO] [tid=11931252] :0 rbus getParameterValueopen partnerfor id failedcomponent: telemetry2_0_wbcfg
2025-10-03T20:07:08.583Z467Z telemetry2_0[11741177]:  251003-20:07:08.580841466240 [mod=T2, lvl=INFO] [tid=11931252] NonRoot:0 feature isrbus enabled, dropping root privileges for Telemetry 2.0 Processopen (telemetry2_0_wbcfg) success
2025-10-03T20:07:08.589Z467Z telemetry2_0[11741177]:  isWebCfgRbusEnabled: rbus  251003-20enabled is 1
2025-10-03T20:07:08.589087467Z [mod=T2, lvl=INFO] [tid=1193] SEEKMAP folder is present notify the profiles for saved seekmaptelemetry2_0[1177]:  subscribeSubdocForceReset: Discover component for event 'Device.X_RDK_WebConfig.webcfgSubdocForceReset'
2025-10-03T20:07:08.589Z467Z telemetry2_0[11741177]:  251003-20:07:08.589267 [mod=T2, lvl=INFO] [tid=1193] :0 RBus Enabledwebcfg_rbus_discover_component rbusModuleList[Device.X_RDK_WebConfig.webcfgSubdocForceReset]
2025-10-03T20:07:08.589Z467Z telemetry2_0[11741177]:  251003-20:07:08.589416466751 [mod=T2, lvl=INFO] [tid=1193] :0 file /tmp/rbus_subs_telemetry2_01224] The return status from the child with pid 1230 is CurlStatus : 1
2025-10-03T20:07:08.597Z467Z telemetry2_0[11741177]:  251003-20:07:08.597636466826 [mod=T2, lvl=INFO] [tid=1193] :0 Subscription for Device.X_RDKCENTREL-COM.Reloadconfig::Device.X_RDKCENTREL-COM.Reloadconfig is now active.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.597Z467Z telemetry2_0[11741177]:  251003-20:07:08.597710466884 [mod=T2, lvl=INFO] [tid=11931224] Report :0Cached, Device.X_RDKCENTREL-COM.Reloadconfig subscribe retries succeededNo. of reportes cached = 1
2025-10-03T20:07:08.607Z467Z telemetry2_0[11741177]:  251003-20:07:08.606874467354 [mod=T2, lvl=INFO] [tid=11931224] Elapsed T2Time isfor now Ready to Recieve Events: RDKM_RDKE_Profile = 0.74951648 (Sec.NanoSec)
2025-10-03T20:07:08.636Z467Z telemetry2_0[11741177]:  251003-20:07:08.628067467425 [mod=T2, lvl=INFO] [tid=11931224] Filename : DCMresponse.txt Size : 1850 CollectAndReport while Loop -- END; wait for restart event
2025-10-03T20:07:08.636Z467Z telemetry2_0[11741177]:  251003-20:07:08.628231467458 [mod=T2, lvl=INFO] [tid=1193] Returning 1 local configurations1224] CollectAndReport --out
2025-10-03T20:07:08.475Z telemetry2_0[1177]:  isWebCfgRbusEnabled: rbus enabled is 1
2025-10-03T20:07:08.636Z475Z telemetry2_0[11741177]:  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.phpsendWebConfigSignal : rbus_set :: event_name : webconfigSignal ::
2025-10-03T20:07:08.636Z475Z telemetry2_0[11741177]:  251003-20:07:08.628601475330 [mod=T2, lvl=INFOERROR] [tid=1193] Number of tr181params/markers successfully added in profile = 21190] :0 _setInternal for webconfigSignal failed with error: Entry not found error
2025-10-03T20:07:08.636Z913Z telemetry2_0[11741177]:  sendWebConfigSignal  251003-20:07:08.633398 [mod=T2, lvl=ERROR] [tid=1193] Failed to open filerbus_set for webconfigSignal failed & returns with Err: 5
2025-10-03T20:07:08.636Z913Z telemetry2_0[11741177]:  251003-20:07:08.633526 [mod=T2, lvl=INFO] [tid=1193] Starting TimeoutThread for profile : RDKMA_Testwebcfg_rbus_discover_component: (1-1)ret[TRUE]
2025-10-03T20:07:08.636Z913Z telemetry2_0[11741177]:  251003-20:07:08.633821913168 [mod=T2, lvl=INFO] [tid=1193] Successfully set profile : RDKMA_Test:0 Consumer=rbus.sysTimeMgr.INBOX.1309 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-10-03T20:07:08.636Z913Z telemetry2_0[11741177]:  251003-20:07:08.633872913247 [mod=T2, lvl=INFO] [tid=1193] Successfully set new profile: RDKMA_TesteventSubHandler called:
2025-10-03T20:07:08.636Z913Z telemetry2_0[11741177]:  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 action=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:04:31.353Z telemetry2_0[11741177]:  251003251128-2019:0704:0831.634317351741 [mod=T2, lvl=INFO] [tid=1193] loadReportProfilesFromDisk:0 Consumer=rbus.dcmd.INBOX.1178 SUBSCRIBED to event=Device.DCM.Setconfig
2025-1011-03T2028T19:0704:0831.636Z353Z telemetry2_0[11741177]:  251003251128-2019:0704:0831.635005351799 [mod=T2, lvl=INFO] [tid=1193] JSON: loadReportProfilesFromDiskeventSubHandler called:
2025-11-28T19:04:31.353Z telemetry2_0[1177]:    action=subscribe        eventName=Device.DCM.Setconfig
2025-1011-03T2028T19:0704:0831.637Z354Z telemetry2_0[11741177]:  251003251128-2019:0704:0831.635338354792 [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] [tid=1193] :0 Consumer=rbus.dcmd.INBOX.1178 SUBSCRIBED to event=Device.DCM.Processconfig
2025-11-28T19:04:31.355Z telemetry2_0[11741177]:  251003251128-2019:0704:0831.635399354848 [mod=T2, lvl=INFO] [tid=1226] Waiting for timeref or reporting interval for the profile - RDKMA_Test is started
2025-10-03T20:07:08.659Z1193] 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[11741177]:  251003251128-2019:0704:0832.658934350640 [mod=T2, lvl=INFO] [tid=1193] Filename : RDKE_test_profile Size : 1288rbusReloadConf ++in
2025-1011-03T2028T19:0704:0832.660Z351Z telemetry2_0[11741177]:  251003251128-2019:0704:0832.659832350700 [mod=T2, lvl=INFO] [tid=1193] Returning 1 local configurations
2025-10-03T20:07:08.660ZRecieved eventName: Device.X_RDKCENTREL-COM.Reloadconfig, Event type: 3, Event Name: Device.X_RDKCENTREL-COM.Reloadconfig
2025-11-28T19:04:32.351Z telemetry2_0[11741177]:  251003251128-2019:0704:0832.660053350735 [mod=T2, lvl=INFO] [tid=1193] Reporting Adjustments parameters check done successfullyrbusReloadConf --out
2025-1011-03T2028T19:0704:0837.660Z421Z telemetry2_0[11741177]:  251003251128-2019:0704:0837.660216420284 [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.660Z0 Consumer=rbus.rdkvfwupgrader.INBOX.2149 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:04:37.421Z telemetry2_0[11741177]:  251003251128-2019:0704:0837.660253420360 [mod=T2, lvl=INFO] [tid=1193] Number of httpURIReqParam added  = 1
2025-10-03T20:07:08.660ZeventSubHandler called:
2025-11-28T19:04:37.421Z telemetry2_0[1177]:    action=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-28T19:04:40.574Z telemetry2_0[11741177]:   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.663ZsubscribeSubdocForceReset: [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[11741177]:  251003251128-2019:0704:0840.662864573865 [mod=T2, lvl=INFO] [tid=11931252] Successfully created/added new profile : RDKE_test_profile
2025-10-03T20:07:08.663Z:0 Subscription for Device.X_RDK_WebConfig.webcfgSubdocForceReset::Device.X_RDK_WebConfig.webcfgSubdocForceReset is now active.
2025-11-28T19:04:40.574Z telemetry2_0[11741177]:  251003251128-2019:0704:0840.663131573951 [mod=T2, lvl=INFO] [tid=11931252] Starting TimeoutThread for profile : RDKE_test_profile:0 Device.X_RDK_WebConfig.webcfgSubdocForceReset subscribe retries succeeded
2025-1011-03T2028T19:0704:0859.663Z904Z telemetry2_0[11741177]:  subscribeSubdocForceReset: subscribed  251003-20:07:08.663575 [mod=T2, lvl=INFO] [tid=1193] Successfully enabled profile : RDKE_test_profile
2025-10-03T20:07:08.664Zto 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[11741177]:  251003251128-2019:0704:0859.663629904152 [mod=T2, lvl=INFO] [tid=11931225] PreviousTIMEOUT Seekfor is enabled so generate the report forprofile - RDKE_testTEST_profileProfile
2025-1011-03T2028T19:0704:0859.664Z905Z telemetry2_0[11741177]:  251003251128-2019:0704:0859.663686904152 [mod=T2, lvl=INFO] [tid=11931222] TIMEOUT NotifyTimeout:for profile - RDKM_RDKE_test_profile is in Enabled stateProfile
2025-1011-03T2028T19:0704:0859.664Z905Z telemetry2_0[11741177]:  251003251128-2019:0704:0859.663926904247 [mod=T2, lvl=INFO] [tid=11931222] Completed processing 1 profiles on the disk,trying to fetch new/updated profilesReportProfiles_TimeoutCb ++in
2025-1011-03T2028T19:0704:0859.665Z905Z telemetry2_0[11741177]:  251003251128-2019:0704:0859.665006904270 [mod=T2, lvl=INFO] [tid=12411225] CollectAndReport while Loop -- STARTReportProfiles_TimeoutCb ++in
2025-1011-03T2028T19:0704:0859.665Z905Z telemetry2_0[11741177]:  251003251128-2019:0704:0859.665071904286 [mod=T2, lvl=INFO] [tid=12411222] CollectAndReport ++in profileName : RDKE_test_profilecalling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-1011-03T2028T19:0704:0859.676Z905Z telemetry2_0[11741177]:  251003251128-2019:0704:0859.673322904317 [mod=T2, lvl=INFO] [tid=12391225] Waitingcalling forProfileXConf_isNameEqual 30function secform forReportProfiles_TimeoutCb nextand TIMEOUT for profile as reporting interval is taken - RDKE_test_profileline 222
2025-1011-03T2028T19:0706:0817.676Z061Z telemetry2_0[11741177]:  251003251128-2019:0706:0817.673410061432 [mod=T2, lvl=INFO] [tid=12391207] WaitingThis foris timerefa orPrevious reportingLogs intervalReport forsleep therandomly profilefor 1- RDKE_test_profile is started50 sec
2025-1011-03T2028T19:0706:0817.691Z067Z telemetry2_0[11741177]:  251003251128-2019:0706:0817.691313066818 [mod=T2, lvl=INFOERROR] [tid=12471207] process_tmprp_thread: Waiting for event from tr-181
2025-10-03T20:07:08.691Z 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[11741177]:  251003251128-2019:0706:0847.691701073734 [mod=T2, lvl=INFO] [tid=1245] process_rp_thread: Waiting for event from tr-181
2025-10-03T20:07:08.745Z1207] The return status from the child with pid 2462 is CurlStatus : 1
2025-11-28T19:06:47.075Z telemetry2_0[11741177]:  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.744409251128-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=11931207] :0 RBus Enabled
2025-10-03T20:07:08.745Z telemetry2_0[1174]:  Entering rbusInit
2025-10-03T20:07:08.745ZSuccessfully saved grep config to file for profile: RDKMA_Test
2025-11-28T19:06:47.076Z telemetry2_0[11741177]:  251003251128-2019:0706:0847.744505076497 [mod=T2, lvl=INFO] [tid=11931207] :0Elapsed rbusTime open for component: telemetry2_0_wbcfgRDKMA_Test = 4834778.722124728 (Sec.NanoSec)
2025-1011-03T2028T19:0706:0847.746Z076Z telemetry2_0[11741177]:  251003251128-2019:0706:0847.744835076532 [mod=T2, lvl=INFO] [tid=11931207] :0CollectAndReportXconf while rbusLoop open (telemetry2_0_wbcfg) success-- END
2025-1011-03T2028T19:0706:0847.750Z076Z telemetry2_0[11741177]:  isWebCfgRbusEnabled: rbus enabled is 1
2025-10-03T20:07:08.750Z251128-19:06:47.076565 [mod=T2, lvl=INFO] [tid=1207] CollectAndReportXconf --out
2025-11-28T19:06:47.077Z telemetry2_0[11741177]:  sendWebConfigSignal : rbus_set :: event_name : webconfigSignal ::
2025-10-03T20:07:08.750Z251128-19:06:47.076739 [mod=T2, lvl=INFO] [tid=1190] :0 RBus Enabled
2025-11-28T19:06:47.077Z telemetry2_0[11741177]:  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.750Z251128-19:06:47.076829 [mod=T2, lvl=INFO] [tid=1190] No data elements present to unregister
2025-11-28T19:06:47.080Z telemetry2_0[11741177]:  webcfg_rbus_discover_component rbusModuleList[Device.X_RDK_WebConfig.webcfgSubdocForceReset]
2025-10-03T20:07:08.750Z251128-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[11741177]:  webcfg_rbus_discover_component: (1-1)ret[TRUE]
2025-10-03T20:07:08.751Z251128-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[11741177]:  251003251128-2019:0706:0847.749377077916 [mod=T2, lvl=ERRORINFO] [tid=11931190] :0 _setInternal for webconfigSignal failed with error: Entry not found error
2025-10-03T20:07:08.762ZT2ER isn't initialized or dispatch thread is already running
2025-11-28T19:06:47.080Z telemetry2_0[11741177]:  sendWebConfigSignal rbus_set for webconfigSignal failed & returns with Err: 5
2025-10-03T20:07:08.762Z251128-19:06:47.077847 [mod=T2, lvl=INFO] [tid=1222] Signal Thread To restart
2025-11-28T19:06:47.080Z telemetry2_0[11741177]:  251003251128-2019:0706:0847.762692078051 [mod=T2, lvl=INFO] [tid=11931222] :0 RBus EnabledReportProfiles_TimeoutCb --out
2025-1011-03T2028T19:0706:0847.763Z081Z telemetry2_0[11741177]:  251003251128-2019:0706:0847.763713078099 [mod=T2, lvl=INFO] [tid=1193] No data elements present to unregister
2025-10-03T20:07:08.765Z1222] 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[11741177]:  251003251128-2019:0706:0847.765083078148 [mod=T2, lvl=INFO] [tid=1193] T2 is now Ready to be configured for report profiles
2025-10-03T20:07:08.765Z1222] Waiting for timeref or reporting interval for the profile - RDKM_RDKE_Profile is started
2025-11-28T19:06:47.081Z telemetry2_0[11741177]:  251003251128-2019:0706:0847.765476078230 [mod=T2, lvl=INFO] [tid=11931224] T2ERCollectAndReport isn'twhile initializedLoop or dispatch thread is already running-- START
2025-1011-03T2028T19:0706:0847.766Z081Z telemetry2_0[11741177]:  251003251128-2019:0706:0847.766033078254 [mod=T2, lvl=INFO] [tid=11931190] InitProperties is successful
2025-1011-03T2028T19:0706:0847.766Z081Z telemetry2_0[11741177]:  251003251128-2019:0706:0847.766545078275 [mod=T2, lvl=INFO] [tid=11931224] Init ReportProfiles SuccessfulCollectAndReport ++in profileName : RDKM_RDKE_Profile
2025-1011-03T2028T19:0706:0847.767Z081Z telemetry2_0[11741177]:  251003251128-2019:0706:0847.767254078370 [mod=T2, lvl=INFO] [tid=11931190] Init XconfReportProfiles Client SuccessSuccessful
2025-1011-03T2028T19:0706:0847.767Z081Z telemetry2_0[11741177]:  251003251128-2019:0706:0847.767612078777 [mod=T2, lvl=INFO] [tid=11931225] TriggeringNotifyTimeout: XCONF report generation during boot with delayprofile RDKE_TEST_Profile is in Enabled state
2025-1011-03T2028T19:0706:0947.255Z081Z telemetry2_0[11741177]:  251003251128-2019:0706:0947.255654078981 [mod=T2, lvl=INFO] [tid=11991190] :0 Consumer=rbus.sysTimeMgr.INBOX.1334 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdatedInit Xconf Client Success
2025-1011-03T2028T19:0706:0947.255Z081Z telemetry2_0[11741177]:  251003251128-2019:0706:0947.255718079032 [mod=T2, lvl=INFO] [tid=11991190] eventSubHandler called:Triggering XCONF report generation during boot with delay
2025-1011-03T2028T19:0706:0947.255Z083Z telemetry2_0[11741177]:  251128-19:06:47.082608 [mod=T2, actionlvl=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdatedINFO] [tid=1224] cJSON Report = {"Report":[{"NW_DNSMASQ":""},{"Xi_wifiMAC_split":"D8:3A:DD:0D:16:B8"}]}
2025-11-25T2028T19:4606:1947.262Z083Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.262348082686 [mod=T2, lvl=INFO] [tid=11991224] :0Report Size Consumer=rbus.dcmd.INBOX.1175 SUBSCRIBED to event=Device.DCM.Setconfig 71
2025-11-25T2028T19:4606:1947.262Z085Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.262412084718 [mod=T2, lvl=INFO] [tid=11991225] eventSubHandler called:ReportProfiles_TimeoutCb --out
2025-11-25T2028T19:4606:1947.262Z085Z telemetry2_0[11741177]:  251128-19:06:47.084789 [mod=T2,  action=subscribe        eventName=Device.DCM.Setconfiglvl=INFO] [tid=1225] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_TEST_Profile
2025-11-25T2028T19:4606:1947.266Z085Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.266105084840 [mod=T2, lvl=INFO] [tid=1199] :0 Consumer=rbus.dcmd.INBOX.1175 SUBSCRIBED to event=Device.DCM.Processconfig1225] Waiting for timeref or reporting interval for the profile - RDKE_TEST_Profile is started
2025-11-25T2028T19:4606:1947.266Z085Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.266171084945 [mod=T2, lvl=INFO] [tid=11992489] CollectAndReport eventSubHandler called:
2025-11-25T20:46:19.266Z telemetry2_0[1174]:    action=subscribe        eventName=Device.DCM.Processconfigwhile Loop -- START
2025-11-25T2028T19:4606:1947.267Z085Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.267661084983 [mod=T2, lvl=INFO] [tid=11992489] rbusReloadConfCollectAndReport ++in profileName : RDKE_TEST_Profile
2025-11-25T2028T19:4606:1947.268Z088Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.267717087709 [mod=T2, lvl=INFO] [tid=11992488] RecievedSetting eventName: Device.X_RDKCENTREL-COM.Reloadconfig, Event type: 3, Event Name: Device.X_RDKCENTREL-COM.Reloadconfigconfig URL base location to : https://xconf.rdkcentral.com/loguploader/getT2Settings
2025-11-25T2028T19:4606:1947.268Z095Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.267751093989 [mod=T2, lvl=INFOERROR] [tid=11991224] getParameterValue partner rbusReloadConfid --outfailed
2025-11-25T2028T19:4606:1947.474Z106Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.453271106792 [mod=T2, lvl=INFO] [tid=11992489] cJSON :0Report Consumer=rbus.tr69hostif.INBOX.832 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7539060"},{"MemStatTotal":"8010996"}]}
2025-11-25T2028T19:4606:1947.474Z107Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.453342107434 [mod=T2, lvl=INFO] [tid=11992489] eventSubHandler called:Report Size = 118
2025-11-25T2028T19:4606:1947.474Z121Z telemetry2_0[11741177]:  251128-19:06:47.121704 [mod=T2, actionlvl=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdatedERROR] [tid=2489] getParameterValue partner id failed
2025-11-25T2028T19:4606:1947.474Z125Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.460014123393 [mod=T2, lvl=INFO] [tid=12722488] SettingT2: configCurl URLUsing baseXCONF location toURI : 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?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-25T2028T19:4606:1947.475Z125Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.463506125776 [mod=T2, lvl=INFO] [tid=12412488] cJSONdoHttpGet Reportwith = {"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.463580url 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=INFOERROR] [tid=12412488] ReportgetParameterValue Sizepartner =id 173failed
2025-11-25T2028T19:4606:1947.475Z559Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.466457559631 [mod=T2, lvl=ERRORINFO] [tid=12412492] :0 rbus_get for Device.DeviceInfo.X_COMCAST-COM_CM_MAC failed with error: Entry not found errorsendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-25T2028T19:4606:1947.475Z568Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.466509567736 [mod=T2, lvl=ERRORINFO] [tid=12412489] Unable to get Device.DeviceInfo.X_COMCAST-COM_CM_MACThe return status from the child with pid 2492 is CurlStatus : 1
2025-11-25T2028T19:4606:1947.475Z568Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.466549567837 [mod=T2, lvl=ERRORINFO] [tid=1241] Failed to retrieve param : Device.DeviceInfo.X_COMCAST-COM_CM_MAC2489] The return status from the child with pid 2492 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-25T2028T19:4606:1947.498Z568Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.491089567874 [mod=T2, lvl=ERRORINFO] [tid=1241] getParameterValue partner id failed2489] Report Sent Successfully over HTTP : 200
2025-11-25T2028T19:4606:1947.576Z568Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.576646567922 [mod=T2, lvl=INFO] [tid=12722489] T2:Elapsed 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 :373Time for : RDKE_TEST_Profile = 0.482901377 (Sec.NanoSec)
2025-11-25T2028T19:4606:1947.577Z568Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.576749567969 [mod=T2, lvl=INFO] [tid=12722489] doHttpGetCollectAndReport withwhile 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=2Loop -- END; wait for restart event
2025-11-25T2028T19:4606:1947.583Z568Z telemetry2_0[11741177]:  251125251128-2019:4606:1947.583258568001 [mod=T2, lvl=ERRORINFO] [tid=12722489] getParameterValue partner id failedCollectAndReport --out
2025-11-25T2028T19:4606:2047.214Z648Z telemetry2_0[11741177]:  251125251128-2019:4606:2047.213848648027 [mod=T2, lvl=INFO] [tid=15022490] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-25T2028T19:4606:2047.225Z656Z telemetry2_0[11741177]:  251125251128-2019:4606:2047.225257656030 [mod=T2, lvl=INFO] [tid=12411224] The return status from the child with pid 15022490 is CurlStatus : 1
2025-11-25T2028T19:4606:2047.225Z656Z telemetry2_0[11741177]:  251125251128-2019:4606:2047.225375656139 [mod=T2, lvl=INFO] [tid=12411224] The return status from the child with pid 15022490 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 505526
2025-11-25T2028T19:4606:2047.225Z656Z telemetry2_0[11741177]:  251125251128-2019:4606:2047.225415656185 [mod=T2, lvl=INFO] [tid=12411224] Report Sent Successfully over HTTP : 200
2025-11-25T2028T19:4606:2047.225Z656Z telemetry2_0[11741177]:  251125251128-2019:4606:2047.225468656229 [mod=T2, lvl=INFO] [tid=1241] Elapsed Time for : RDKE_test_profile = 4581551.560350782 (Sec.NanoSec)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-25T2028T19:4606:2048.225Z002Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.225535001902 [mod=T2, lvl=INFO] [tid=12412494] CollectAndReport while Loop -- END; wait for restart eventdoHttpGet:826, T2:Telemetry XCONF communication success
2025-11-25T2028T19:4606:2048.225Z010Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.225568010514 [mod=T2, lvl=INFO] [tid=1241] CollectAndReport --out2488] Received profile name : RDKMA_Test with interval of : 900 secs and upload url : http://xconf.rdkcentral.com/xconf/logupload.php
2025-11-25T2028T19:4606:2048.530Z011Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.529339010651 [mod=T2, lvl=INFO] [tid=15332488] doHttpGet:816, T2:Telemetry XCONF communication successNumber of tr181params/markers successfully added in profile = 4
2025-11-25T2028T19:4606:2048.542Z026Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.542536025880 [mod=T2, lvl=INFOERROR] [tid=12722488] ReceivedFailed profileto nameremove : RDKMA_Test with interval of : 900 secs and upload url : http://xconf.rdkcentral.com/xconf/logupload.phpthe file /tmp/t2_caching_file
2025-11-25T2028T19:4606:2048.542Z026Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.542642026023 [mod=T2, lvl=INFOERROR] [tid=12722488] NumberComponent ofname tr181params/markerslist successfullyis addednot ininitialized profile = 2. Re-initializing
2025-11-25T2028T19:4606:2048.552Z026Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.552155026118 [mod=T2, lvl=ERRORINFO] [tid=12722488] Profile Failedexists toalready, removeupdating the config in file /tmp/t2_caching_filesystem
2025-11-25T2028T19:4606:2048.552Z026Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.552300026154 [mod=T2, lvl=ERRORINFO] [tid=12722488] ComponentThere nameare list2 iscached notreports initializedin .the Re-initializingprofile
2025-11-25T2028T19:4606:2048.552Z026Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.552392026192 [mod=T2, lvl=INFO] [tid=12722488] Profilecheck existsthe already,events updatingfrom the config in file system old profile and forward to the new profile
2025-11-25T2028T19:4606:2048.552Z026Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.552447026299 [mod=T2, lvl=INFO] [tid=12722488] removing profile : RDKMA_Test
2025-11-25T2028T19:4606:2048.552Z026Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.552551026458 [mod=T2, lvl=INFO] [tid=12722488] Scheduler already assigned for profile RDKMA_Test , exiting .
2025-11-25T2028T19:4606:2048.553Z026Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.552589026498 [mod=T2, lvl=INFO] [tid=12722488] Successfully set profile : RDKMA_Test
2025-11-25T2028T19:4606:2048.553Z026Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.552632026541 [mod=T2, lvl=INFO] [tid=12722488] T2ER isn't initialized or dispatch thread isn't running
2025-11-25T2028T19:4606:2048.553Z027Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.552680026590 [mod=T2, lvl=INFO] [tid=12722488] :0 RBus Enabled
2025-11-25T2028T19:4606:2048.559Z028Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.559509028268 [mod=T2, lvl=INFO] [tid=12722488] 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.627Z28T19:06:48.028Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.569081028448 [mod=T2, lvl=INFO] [tid=12722488] URLSet DCM flag for Report Profile: https://xconf.rdkcentral.com/loguploader/getTelemetryProfiles251125-20:46:20.626979sending events
2025-11-28T19:06:48.079Z telemetry2_0[1177]:  251128-19:06:48.078880 [mod=T2, lvl=INFO] [tid=12722487] T2:Publishing Curlthe Usingset XCONFconf URIevent Path: https:/opt/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.t2persistentfolder/DCMresponse.txt
2025-11-25T2028T19:4606:2048.627Z079Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.627054079809 [mod=T2, lvl=INFO] [tid=12722487] Publishing doHttpGetthe withProcess 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=2conf event
2025-11-25T2028T19:4606:2048.633Z100Z telemetry2_0[11741177]:  251125251128-2019:4606:2048.633093100699 [mod=T2, lvl=ERRORINFO] [tid=1272] getParameterValue partner id failed2496] sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-25T2028T19:4606:2148.516Z109Z telemetry2_0[11741177]:  251125251128-2019:4606:2148.516064109238 [mod=T2, lvl=INFO] [tid=12711224] PublishingThe return status from the set conf event Path: /opt/.t2persistentfolder/DCMresponse.txt child with pid 2496 is CurlStatus : 1
2025-11-25T2028T19:4606:2148.516Z109Z telemetry2_0[11741177]:  251125251128-2019:4606:2148.516587109326 [mod=T2, lvl=INFO] [tid=12711224] Publishing the Process conf event The return status from the child with pid 2496 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-25T2028T19:4606:2148.616Z109Z telemetry2_0[11741177]:  251125251128-2019:4606:2148.615607109374 [mod=T2, lvl=INFO] [tid=18031224] doHttpGet:816, T2:Telemetry XCONF communication success Report Sent Successfully over HTTP : 200
2025-11-25T2028T19:4606:2148.624Z109Z telemetry2_0[11741177]:  251125251128-2019:4606:2148.624460109751 [mod=T2, lvl=INFO] [tid=12721224] NumberElapsed ofTime reportfor profiles in current configuration is 1: RDKM_RDKE_Profile = 1.31434059 (Sec.NanoSec)
2025-11-25T2028T19:4606:2148.624Z109Z telemetry2_0[11741177]:  251125251128-2019:4606:2148.624582109812 [mod=T2, lvl=INFO] [tid=12721224] :0 RBus Enabled CollectAndReport while Loop -- END; wait for restart event
2025-11-25T2028T19:4606:2148.624Z110Z telemetry2_0[11741177]:  251125251128-2019:4606:2148.624861109846 [mod=T2, lvl=INFO] [tid=12721224] Filename : .CollectAndReport --out
2025-11-25T2028T19:4607:2116.625Z439Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.624933438799 [mod=T2, lvl=INFO] [tid=12721222] TIMEOUT for Filenameprofile :- RDKM_RDKE_test_profileProfile
2025-11-25T2028T19:4607:2116.625Z439Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.624969438798 [mod=T2, lvl=INFO] [tid=12721225] TIMEOUT for Filenameprofile : ..- RDKE_TEST_Profile
2025-11-25T2028T19:4607:2116.625Z439Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.625045438890 [mod=T2, lvl=INFO] [tid=12721222] :0 RBus EnabledReportProfiles_TimeoutCb ++in
2025-11-25T2028T19:4607:2116.625Z439Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.625342438928 [mod=T2, lvl=INFO] [tid=1272] Reporting Adjustments parameters check done successfully1222] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-25T2028T19:4607:2116.625Z439Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.625494438998 [mod=T2, lvl=INFO] [tid=12721222] ProfileNotifyTimeout: Name:profile RDKM_RDKE_test_profileProfile is in Enabled state
2025-11-25T2028T19:4607:2116.625Z439Z telemetry2_0[11741177]:  Configured httpURIReqParam count = 1251128-19:07:16.439032 [mod=T2, lvl=INFO] [tid=1222] Signal Thread To restart
2025-11-25T2028T19:4607:2116.625Z439Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.625528439095 [mod=T2, lvl=INFO] [tid=12721222] Number of httpURIReqParam added  = 1ReportProfiles_TimeoutCb --out
2025-11-25T2028T19:4607:2116.627Z440Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.627494439139 [mod=T2, lvl=INFO] [tid=12721222] Waiting for Number30 ofsec tr181params/markersfor successfullynext addedTIMEOUT infor profile = 9 as reporting interval is taken - RDKM_RDKE_Profile
2025-11-25T2028T19:4607:2116.658Z440Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.628471439188 [mod=T2, lvl=INFO] [tid=1239] Interrupted before TIMEOUT=1222] Waiting for timeref or reporting interval for the profile :- RDKM_RDKE_test_profileProfile is started
2025-11-25T2028T19:4607:2116.658Z440Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.628531439252 [mod=T2, lvl=INFO] [tid=1239] get_logdemand ++in1224] CollectAndReport while Loop -- START
2025-11-25T2028T19:4607:2116.658Z440Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.628565439289 [mod=T2, lvl=INFO] [tid=12391224] ReportProfiles_TimeoutCbCollectAndReport ++in profileName : RDKM_RDKE_Profile
2025-11-25T2028T19:4607:2116.658Z441Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.628599441263 [mod=T2, lvl=INFO] [tid=12391224] callingcJSON ProfileXConf_isNameEqualReport function form ReportProfiles_TimeoutCb and line 222= {"Report":[]}
2025-11-25T2028T19:4607:2116.658Z441Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.628659441325 [mod=T2, lvl=INFOWARN] [tid=12391224] NotifyTimeout: profile RDKE_test_profile is in Disabled stateArray size of Report is 0. Report is empty. Cannot send empty report
2025-11-25T2028T19:4607:2116.659Z441Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.628695441367 [mod=T2, lvl=WARNINFO] [tid=12391224] EitherCollectAndReport profilewhile isLoop disabled-- orEND; reportwait generationfor still in progress - ignoring the requestrestart event
2025-11-25T2028T19:4607:2116.659Z441Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.628737441400 [mod=T2, lvl=INFO] [tid=12391224] ReportProfiles_TimeoutCbCollectAndReport --out
2025-11-25T2028T19:4607:2116.659Z442Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.628768441980 [mod=T2, lvl=WARN] [tid=1239] Profile : RDKE_test_profile is being removed from schedulerINFO] [tid=1225] ReportProfiles_TimeoutCb ++in
2025-11-25T2028T19:4607:2116.659Z442Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.629011442037 [mod=T2, lvl=INFO] [tid=12721225]  tProfile->tId = -181406944 tProfile->name = RDKE_test_profilecalling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-25T2028T19:4607:2116.659Z443Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.629068442103 [mod=T2, lvl=INFO] [tid=12721225]  schProfile->name =NotifyTimeout: profile RDKE_testTEST_profile schProfile->tId = -181406944Profile is in Enabled state
2025-11-25T2028T19:4607:2116.659Z443Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.629182442137 [mod=T2, lvl=INFO] [tid=12721225] WaitingSignal forThread CollectAndReport to be complete : RDKE_test_profileTo restart
2025-11-25T2028T19:4607:2116.659Z443Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.629283442191 [mod=T2, lvl=INFO] [tid=12411225] CollectAndReportReportProfiles_TimeoutCb --out Exiting collect and report Thread
2025-11-25T2028T19:4607:2116.659Z443Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.629514442237 [mod=T2, lvl=INFO] [tid=1272] removing profile :1225] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_test_profile from profile listTEST_Profile
2025-11-25T2028T19:4607:2116.659Z443Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.630024442286 [mod=T2, lvl=ERRORINFO] [tid=1272] Failed to remove1225] Waiting for timeref or reporting interval for the file /tmp/t2_caching_fileprofile - RDKE_TEST_Profile is started
2025-11-25T2028T19:4607:2116.659Z443Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.630337442370 [mod=T2, lvl=ERRORINFO] [tid=12722489] ComponentCollectAndReport namewhile listLoop is not initialized . Re-initializing-- START
2025-11-25T2028T19:4607:2116.659Z443Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.630417442409 [mod=T2, lvl=INFO] [tid=12722489] StartingCollectAndReport TimeoutThread++in forprofileName profile : RDKE_testTEST_profileProfile
2025-11-25T2028T19:4607:2116.659Z453Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.630562453067 [mod=T2, lvl=INFO] [tid=12722489] T2ERcJSON isn'tReport 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= {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7529760"},{"MemStatTotal":"8010996"}]}
2025-11-25T2028T19:4607:2116.674Z453Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.630653453127 [mod=T2, lvl=INFO] [tid=12722489] T2ERReport isn'tSize initialized or dispatch thread is already running= 118
2025-11-25T2028T19:4607:2116.674Z460Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.630714458251 [mod=T2, lvl=ERROR] [tid=12722489] removeProfileFromDisk,257: command getParameterValue partner id failed
2025-11-25T2028T19:4607:2116.674Z899Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.630712899356 [mod=T2, lvl=INFO] [tid=19722627] WaitingsendReportOverHTTP: for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_test_profileUsing xpki Certs connection certname: (null)
2025-11-25T2028T19:4607:2116.674Z907Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.630814906816 [mod=T2, lvl=INFO] [tid=19722489] WaitingThe forreturn timerefstatus orfrom reportingthe intervalchild forwith thepid profile2627 - RDKE_test_profile is startedis CurlStatus : 1
2025-11-25T2028T19:4607:2116.674Z907Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.630759906906 [mod=T2, lvl=INFO] [tid=12722489] profiles.msgpackThe isreturn removedstatus 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 the child with pid 2627 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-25T2028T19:4607:2116.674Z907Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.639118906942 [mod=T2, lvl=INFO] [tid=12722489] Report Sent Successfully downloadedover HTTP report: profiles200
2025-11-25T2028T19:4607:2116.729Z907Z telemetry2_0[11741177]:  251125251128-2019:4607:2116.729716906991 [mod=T2, lvl=INFO] [tid=11992489] :0 Consumer=rbus.routerDiscovery.INBOX.1827 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdatedElapsed Time for : RDKE_TEST_Profile = 0.464545009 (Sec.NanoSec)
2025-11-25T2028T19:4607:2116.730Z907Z telemetry2_0[11741177]:  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.ProfilesUpdated251128-19:07:16.907039 [mod=T2, lvl=INFO] [tid=2489] CollectAndReport while Loop -- END; wait for restart event
2025-11-25T2028T19:4607:2416.847Z907Z telemetry2_0[11741177]:  251125251128-2019:4607:2416.846825907072 [mod=T2, lvl=INFO] [tid=11992489] :0 Consumer=rbus.rdkvfwupgrader.INBOX.2378 SUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdatedCollectAndReport --out
2025-11-25T2028T19:07:46:24.847Z439Z telemetry2_0[11741177]:  251125251128-2019:07:46:24.846894438812 [mod=T2, lvl=INFO] [tid=1199] eventSubHandler called:1222] TIMEOUT for profile - RDKM_RDKE_Profile
2025-11-25T2028T19:07:46:24.847Z439Z telemetry2_0[11741177]:  251128-19:07:46.438817 [mod=T2, actionlvl=subscribe        eventName=Telemetry.ReportProfiles.ProfilesUpdatedINFO] [tid=1225] TIMEOUT for profile - RDKE_TEST_Profile
2025-11-25T2028T19:07:46:28.492Z439Z telemetry2_0[11741177]:  subscribeSubdocForceReset: [Device.X_RDK_WebConfig.webcfgSubdocForceReset] Component found251128-19:07:46.438899 [mod=T2, lvl=INFO] [tid=1222] ReportProfiles_TimeoutCb ++in
2025-11-25T2028T19:07:46:28.492Z439Z telemetry2_0[11741177]:  subscribeSubdocForceReset: subscribing to event Device.X_RDK_WebConfig.webcfgSubdocForceReset251128-19:07:46.438918 [mod=T2, lvl=INFO] [tid=1225] ReportProfiles_TimeoutCb ++in
2025-11-25T2028T19:07:46:28.492Z439Z telemetry2_0[11741177]:  251125251128-2019:07:46:28.492154438937 [mod=T2, lvl=INFO] [tid=12671222] :0 Subscription for Device.X_RDK_WebConfig.webcfgSubdocForceReset::Device.X_RDK_WebConfig.webcfgSubdocForceReset is now active.calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-25T2028T19:07:46:28.493Z439Z telemetry2_0[11741177]:  251125251128-2019:07:46:28.492227438975 [mod=T2, lvl=INFO] [tid=12671225] :0 Device.X_RDK_WebConfig.webcfgSubdocForceReset subscribe retries succeededcalling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-25T2028T19:07:46:51.593Z440Z telemetry2_0[11741177]:  subscribeSubdocForceReset: subscribed to event Device.X_RDK_WebConfig.webcfgSubdocForceReset
2025-11-25T20:46:51.593Z telemetry2_0[1174]:  subscribeSubdocForceReset: job done251128-19:07:46.439040 [mod=T2, lvl=INFO] [tid=1222] NotifyTimeout: profile RDKM_RDKE_Profile is in Enabled state
2025-11-25T2028T19:07:46:51.593Z440Z telemetry2_0[11741177]:  251125251128-2019:07:46:51.592948439073 [mod=T2, lvl=INFO] [tid=19721225] TIMEOUTNotifyTimeout: for profile - RDKE_test_profileTEST_Profile is in Enabled state
2025-11-25T2028T19:07:46:51.593Z440Z telemetry2_0[11741177]:  251125251128-2019:07:46:51.593002439084 [mod=T2, lvl=INFO] [tid=19721222] ReportProfiles_TimeoutCb ++in Signal Thread To restart
2025-11-25T2028T19:07:46:51.594Z440Z telemetry2_0[11741177]:  251125251128-2019:07:46:51.593039439116 [mod=T2, lvl=INFO] [tid=19721225] callingSignal ProfileXConf_isNameEqualThread function form ReportProfiles_TimeoutCb and line 222To restart
2025-11-25T2028T19:07:46:51.594Z440Z telemetry2_0[11741177]:  251125251128-2019:07:46:51.593104439175 [mod=T2, lvl=INFO] [tid=19721225] NotifyTimeout: profile RDKE_test_profile is in Enabled stateReportProfiles_TimeoutCb --out
2025-11-25T2028T19:07:46:51.594Z440Z telemetry2_0[11741177]:  251125251128-2019:07:46:51.593270439193 [mod=T2, lvl=INFO] [tid=19721222] ReportProfiles_TimeoutCb --out
2025-11-25T2028T19:07:46:51.594Z440Z telemetry2_0[11741177]:  251125251128-2019:07:46:51.593319439222 [mod=T2, lvl=INFO] [tid=19721225] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_testTEST_profileProfile
2025-11-25T2028T19:07:46:51.594Z440Z telemetry2_0[11741177]:  251125251128-2019:07:46:51.593371439240 [mod=T2, lvl=INFO] [tid=19721222] Waiting for timeref or reporting interval30 sec 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_profilenext TIMEOUT for profile as reporting interval is taken - RDKM_RDKE_Profile
2025-11-25T2028T19:07:46:52.624Z440Z telemetry2_0[11741177]:  251125251128-2019:07:46:52.624232439271 [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.624Z1225] Waiting for timeref or reporting interval for the profile - RDKE_TEST_Profile is started
2025-11-28T19:07:46.440Z telemetry2_0[11741177]:  251125251128-2019:07:46:52.624315439288 [mod=T2, lvl=INFO] [tid=2875] Report Size = 2111222] Waiting for timeref or reporting interval for the profile - RDKM_RDKE_Profile is started
2025-11-25T2028T19:07:46:52.628Z440Z telemetry2_0[11741177]:  251125251128-2019:07:46:52.628107439334 [mod=T2, lvl=ERRORINFO] [tid=28752489] :0 rbus_get for Device.DeviceInfo.X_COMCAST-COM_CM_MAC failed with error: Entry not found errorCollectAndReport while Loop -- START
2025-11-25T2028T19:07:46:52.628Z441Z telemetry2_0[11741177]:  251125251128-2019:07:46:52.628170439371 [mod=T2, lvl=ERRORINFO] [tid=28752489] Unable to get Device.DeviceInfo.X_COMCAST-COM_CM_MACCollectAndReport ++in profileName : RDKE_TEST_Profile
2025-11-25T2028T19:07:46:52.628Z441Z telemetry2_0[11741177]:  251125251128-2019:07:46:52.628213439380 [mod=T2, lvl=ERRORINFO] [tid=28751224] FailedCollectAndReport towhile retrieveLoop param : Device.DeviceInfo.X_COMCAST-COM_CM_MAC-- START
2025-11-25T2028T19:07:46:52.634Z441Z telemetry2_0[11741177]:  251125251128-2019:07:46:52.634235439433 [mod=T2, lvl=ERRORINFO] [tid=28751224] getParameterValue partner id failedCollectAndReport ++in profileName : RDKM_RDKE_Profile
2025-11-25T2028T19:07:46:53.053Z443Z telemetry2_0[11741177]:  251125251128-2019:07:46:53.053451442181 [mod=T2, lvl=INFO] [tid=28791224] sendReportOverHTTP:cJSON UsingReport xpki Certs connection certname: (null)= {"Report":[]}
2025-11-25T2028T19:07:46:53.063Z443Z telemetry2_0[11741177]:  251125251128-2019:07:46:53.063407442252 [mod=T2, lvl=INFOWARN] [tid=28751224] TheArray returnsize statusof fromReport theis child0. withReport pidis 2879empty. isCannot CurlStatussend :empty 1report
2025-11-25T2028T19:07:46:53.063Z443Z telemetry2_0[11741177]:  251125251128-2019:07:46:53.063495442294 [mod=T2, lvl=INFO] [tid=28751224] TheCollectAndReport returnwhile statusLoop from the child with pid 2879 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 505-- END; wait for restart event
2025-11-25T2028T19:07:46:53.063Z443Z telemetry2_0[11741177]:  251125251128-2019:07:46:53.063532442341 [mod=T2, lvl=INFO] [tid=2875] Report Sent Successfully over HTTP : 2001224] CollectAndReport --out
2025-11-25T2028T19:07:46:53.063Z451Z telemetry2_0[11741177]:  251125251128-2019:07:46:53.063581450892 [mod=T2, lvl=INFO] [tid=28752489] ElapsedcJSON TimeReport for= : RDKE_test_profile = 1.468834943 (Sec.NanoSec){"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7523812"},{"MemStatTotal":"8010996"}]}
2025-11-25T2028T19:07:46:53.064Z451Z telemetry2_0[11741177]:  251125251128-2019:07:46:53.063632450955 [mod=T2, lvl=INFO] [tid=28752489] CollectAndReportReport whileSize Loop -- END; wait for restart event= 118
2025-11-25T2028T19:07:46:53.064Z456Z telemetry2_0[11741177]:  251125251128-2019:07:46:53.063664456521 [mod=T2, lvl=INFOERROR] [tid=28752489] getParameterValue CollectAndReport --outpartner id failed
2025-11-25T2028T19:4707:2146.594Z893Z telemetry2_0[11741177]:  251125251128-2019:4707:2146.593027893057 [mod=T2, lvl=INFO] [tid=19722688] TIMEOUT for profile - RDKE_test_profile sendReportOverHTTP: Using xpki Certs connection certname: (null)
2025-11-25T2028T19:4707:2146.594Z900Z telemetry2_0[11741177]:  251125251128-2019:4707:2146.593089900472 [mod=T2, lvl=INFO] [tid=1972] ReportProfiles_TimeoutCb ++in2489] The return status from the child with pid 2688 is CurlStatus : 1
2025-11-25T2028T19:4707:2146.594Z900Z telemetry2_0[11741177]:  251125251128-2019:4707:2146.593125900562 [mod=T2, lvl=INFO] [tid=1972] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 2222489] The return status from the child with pid 2688 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-25T2028T19:4707:2146.594Z900Z telemetry2_0[11741177]:  251125251128-2019:4707:2146.593228900598 [mod=T2, lvl=INFO] [tid=19722489] NotifyTimeout:Report profile RDKE_test_profile is in Enabled stateSent Successfully over HTTP : 200
2025-11-25T2028T19:4707:2146.594Z901Z telemetry2_0[11741177]:  251125251128-2019:4707:2146.593263900647 [mod=T2, lvl=INFO] [tid=1972] Signal Thread To restart2489] Elapsed Time for : RDKE_TEST_Profile = 0.461233385 (Sec.NanoSec)
2025-11-25T2028T19:4707:2146.594Z901Z telemetry2_0[11741177]:  251125251128-2019:4707:2146.593316900695 [mod=T2, lvl=INFO] [tid=19722489] ReportProfiles_TimeoutCb CollectAndReport while Loop --out END; wait for restart event
2025-11-25T2028T19:4707:2146.595Z901Z telemetry2_0[11741177]:  251125251128-2019:4707:2146.593345900728 [mod=T2, lvl=INFO] [tid=28752489] CollectAndReport while Loop -- STARTout
2025-11-25T2028T19:4707:2158.595Z070Z telemetry2_0[11741177]:  251125251128-2019:4707:2158.593411070385 [mod=T2, lvl=INFO] [tid=28751193] CollectAndReport ++in profileName : RDKE_test_profile:0 Consumer=rbus.rdkvfwupgrader.INBOX.2149 UNSUBSCRIBED to event=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-25T2028T19:4707:2158.595Z071Z telemetry2_0[11741177]:  251125251128-2019:4707:2158.593361070447 [mod=T2, lvl=INFO] [tid=19721193] Waiting for 30 sec for next TIMEOUT for profile as reporting interval is taken - RDKE_test_profileeventSubHandler called:
2025-11-28T19:07:58.071Z telemetry2_0[1177]:    action=unsubscribe      eventName=Telemetry.ReportProfiles.ProfilesUpdated
2025-11-25T2028T19:4708:2116.595Z439Z telemetry2_0[11741177]:  251125251128-2019:4708:2116.593501438826 [mod=T2, lvl=INFO] [tid=19721225] WaitingTIMEOUT for timeref or reporting interval for the profile - RDKE_test_profile is startedTEST_Profile
2025-11-25T2028T19:4708:2216.610Z439Z telemetry2_0[11741177]:  251125251128-2019:4708:2216.610289438887 [mod=T2, lvl=INFO] [tid=28751225] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7526648"},{"MemStatTotal":"8010996"},{"CpuUsage":"62"},{"Uptime":"77"}]}ReportProfiles_TimeoutCb ++in
2025-11-25T2028T19:4708:2216.610Z439Z telemetry2_0[11741177]:  251125251128-2019:4708:2216.610384438926 [mod=T2, lvl=INFO] [tid=28751225] Reportcalling SizeProfileXConf_isNameEqual = 152

 

No Format
root@raspberrypi4-64-rdke:~#
root@raspberrypi4-64-rdke:~# cat /opt/logs/telemetry2_0.txt.0 | grep cJSONfunction form ReportProfiles_TimeoutCb and line 222
2025-11-25T2028T19:4608:1916.475Z439Z telemetry2_0[11741177]:   251125251128-2019:4608:1916.463506438992 [mod=T2, lvl=INFO] [tid=12411225] cJSONNotifyTimeout: Report = {"Report":[{"PREVIOUS_LOG":"1"},{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7782424"},{"MemStatTotal":"8010996"},{"CpuUsage":"97"},{"Uptime":"13"}]}profile RDKE_TEST_Profile is in Enabled state
2025-11-25T2028T19:4608:5216.624Z439Z telemetry2_0[11741177]:  251125 251128-2019:4608:5216.624232439026 [mod=T2, lvl=INFO] [tid=28751225] cJSONSignal ReportThread = {"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.610289To 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=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.9904521225] 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=28752489] CollectAndReport cJSONwhile ReportLoop = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7509288"},{"MemStatTotal":"8010996"},{"CpuUsage":"65"},{"Uptime":"107"}]}-- 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-25T2028T19:5508:0516.987Z450Z telemetry2_0[11741177]:   251125251128-2019:5508:0516.987445442565 [mod=T2, lvl=INFO] [tid=28751222] cJSONTIMEOUT for Reportprofile = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7502568"},{"MemStatTotal":"8010996"},{"CpuUsage":"66"},{"Uptime":"137"}]}- 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-25T2028T19:5508:3516.992Z450Z telemetry2_0[11741177]:   251125251128-2019:5508:3516.988595442665 [mod=T2, lvl=INFO] [tid=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7503768"},{"MemStatTotal":"8010996"},{"CpuUsage":"64"},{"Uptime":"167"}]}1222] calling ProfileXConf_isNameEqual function form ReportProfiles_TimeoutCb and line 222
2025-11-25T2028T19:5608:0116.943Z450Z telemetry2_0[11741177]:   251125251128-2019:5608:0116.943042442778 [mod=T2, lvl=INFO] [tid=33141222] cJSONNotifyTimeout: 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"}]}profile RDKM_RDKE_Profile is in Enabled state
2025-11-25T2028T19:5608:3216.978Z450Z telemetry2_0[11741177]:  251125 251128-2019:5608:3216.978163442824 [mod=T2, lvl=INFO] [tid=28751222] cJSONSignal ReportThread = {"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.002867To 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=2875] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7494920"},{"MemStatTotal":"8010996"},{"CpuUsage":"67"},{"Uptime":"253"}]}1222] Waiting for timeref or reporting interval for the profile - RDKM_RDKE_Profile is started
2025-11-25T2028T19:5708:3116.988Z451Z telemetry2_0[11741177]:  251125 251128-2019:5708:3116.987777443059 [mod=T2, lvl=INFO] [tid=28751224] CollectAndReport cJSONwhile ReportLoop = {"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-- 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=28751224] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7486376"},{"MemStatTotal":"8010996"},{"CpuUsage":"66"},{"Uptime":"313"}]}]}
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-25T2028T19:5808:3116.989Z451Z telemetry2_0[11741177]:  251125 251128-2019:5808:3116.989371445383 [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.9923671224] 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=28752489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"74621567532276"},{"MemStatTotal":"8010996"},{"CpuUsage":"70"},{"Uptime":"373"}]}
2025-11-25T2028T19:5908:3116.995Z458Z telemetry2_0[11741177]:   251125251128-2019:5908:3116.993222458690 [mod=T2, lvl=INFO] [tid=28752489] cJSONReport ReportSize = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7460816"},{"MemStatTotal":"8010996"},{"CpuUsage":"69"},{"Uptime":"403"}]}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-25T2128T19:0008:0216.003Z922Z telemetry2_0[11741177]:   251125251128-2119:0008:0116.994382922163 [mod=T2, lvl=INFO] [tid=28752791] 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 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=28752489] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7453428"},{"MemStatTotal":"8010996"},{"CpuUsage":"72"},{"Uptime":"463"}]} The return status from the child with pid 2791 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 526
2025-11-25T2128T19:0108:0116.989Z930Z telemetry2_0[11741177]:  251125 251128-2119:0108:0116.988725930214 [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.9975052489] 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=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.9942842489] 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=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.9953291193] :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=28751222] cJSONTIMEOUT for Reportprofile = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7450364"},{"MemStatTotal":"8010996"},{"CpuUsage":"73"},{"Uptime":"583"}]}- 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-25T2128T19:0308:0146.992Z439Z telemetry2_0[11741177]:  251125 251128-2119:0308:0146.992481438908 [mod=T2, lvl=INFO] [tid=28751222] 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.987353ReportProfiles_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=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.9973251222] 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=28751222] 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 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=28751222] cJSONSignal ReportThread = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7455464"},{"MemStatTotal":"8010996"},{"CpuUsage":"75"},{"Uptime":"703"}]}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-25T2128T19:0508:0146.988Z440Z telemetry2_0[11741177]:  251125 251128-2119:0508:0146.987623439106 [mod=T2, lvl=INFO] [tid=28751222] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7459540"},{"MemStatTotal":"8010996"},{"CpuUsage":"73"},{"Uptime":"733"}]}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-25T2128T19:0508:3146.993Z440Z telemetry2_0[11741177]:  251125 251128-2119:0508:3146.993426439160 [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.9925921222] 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=28751222] 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 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=28752489] CollectAndReport cJSONwhile ReportLoop = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7449588"},{"MemStatTotal":"8010996"},{"CpuUsage":"74"},{"Uptime":"823"}]}-- 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-25T2128T19:0708:0146.989Z444Z telemetry2_0[11741177]:  251125 251128-2119:0708:0146.989357439303 [mod=T2, lvl=INFO] [tid=28751224] CollectAndReport cJSONwhile ReportLoop = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7447200"},{"MemStatTotal":"8010996"},{"CpuUsage":"75"},{"Uptime":"853"}]}-- 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-25T2128T19:0708:3146.991Z445Z telemetry2_0[11741177]:  251125 251128-2119:0708:3146.990785443342 [mod=T2, lvl=INFO] [tid=28751224] cJSON Report = {"Report":[{"NTPServer3":"time2.google.com"},{"HwVer":"d03115"},{"MemStatFree":"7447964"},{"MemStatTotal":"8010996"},{"CpuUsage":"74"},{"Uptime":"883"}]}]}
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-25T2128T19:08:0246.093Z445Z telemetry2_0[11741177]:  251125 251128-2119:08:0246.077306443450 [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.0777061224] 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=33142489] cJSON Report = {"searchResultReport":[{"T2NTPServer3":"1.0"},{"Profile":"RDKV"},{"Time":"2025-11-25 21:08:00"},{"mac":"D8:3A:DD:0D:16:B7"},{"StbIp":"10.0.0.253time2.google.com"},{"PartnerIdHwVer":"communityd03115"},{"VersionMemStatFree":"lib32-application-test-image-RPI4-202510301149557531648"},{"AccountIdMemStatTotal":"12348010996"}]}
root@raspberrypi4-64-rdke:~#

...