Versions Compared

Old Version 1

changes.mady.by.user Unknown User (ashrafbhanu.k)

Saved on

compared with

New Version 2

changes.mady.by.user Ashraf Bhanu Kothapeta

Saved on

Key

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

 

Table of Contents

XConf server configuration procedure:

XConf server URL: https://xconf.rdkcentral.com:19093/admin/ux/

Refer Xconf Reference Setup Credentials for server credentials

Create Formula

  • Login to Xconf Server, go to DCM menu item ,click on the “Formulas” sub menu, and create Formula.


Create Device settings

  • Make the Device setting inside the formula we've created.
  • Check if these settings are now present: Xconf Server → DCM → Device Settings.

Create Upload repository

Create Log upload settings

  • Check this inside: Xconf Server → DCM → LogUpload Settings


Create Permanent profiles

Xconf Server → Telemetry → Permanent Profiles

  • Once you save the permanent profile, you will get a message overlay ‘Profile added to the pending changes’.
  • Then go to Changes → select the profile you create → Click on "Approve selected changes".
  • Then the permanent profile will be listed under Telemetry → Permanent profiles.

Create Targeting rules

  • Create targeting rule via Telemetry -> Targeting rules. Targeting rules is to map the profiles with rules

Client(device) side verification

Configure the below details to dcm.properties and comment out the other details

Code Block
languagebash
themeMidnight
cat /etc/dcm.properties
---------------------------
#BD-1004 : Removed IP/Links for open sourcing
# DCM properties file
# Log server details
LOG_SERVER=xconf.rdkcentral.com
# Log upload server details
DCM_LOG_SERVER=https://xconf.rdkcentral.com/xconf/logupload.php
DCM_LOG_SERVER_URL=https://xconf.rdkcentral.com:19092/loguploader/getT2Settings
# SCP server details
DCM_SCP_SERVER=xconf.rdkcentral.com
# LA server details
DCM_LA_SERVER_URL=https://xconf.rdkcentral.com/xconf/logupload.php

The above configuration is a set of properties that are used to configure the Device Configuration Manager (DCM), which is a system used for managing the configuration of devices on a network.

Here is a brief explanation of each property:

  • LOG_SERVER: The hostname or IP address of the server where log messages generated by the DCM will be sent.
  • DCM_LOG_SERVER: The URL of the server where log messages will be uploaded.
  • DCM_LOG_SERVER_URL: The URL of the server that provides settings for the log uploader.
  • DCM_SCP_SERVER: The hostname or IP address of the server where files will be uploaded via SCP (Secure Copy Protocol).
  • DCM_LA_SERVER_URL: The URL of the server where logs will be uploaded.

Steps for enabling Telemetry 2.0 in device

  • tr181 -s -t boolean -v true Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.Telemetry.Enable
  • tr181 -s -t string -v 2.0.1 Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.Telemetry.Version
  • tr181 -s -t string -v https://xconf.rdkcentral.com:19092/loguploader/getT2Settings Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.Telemetry.ConfigURL
  • Checking if RFCs are loaded :
    • Code Block
      languagebash
      themeMidnight
      tr181 Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.Telemetry.Enable
true
tr181 Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.Telemetry.Version
2.0.1
tr181 Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.Telemetry.ConfigURL
https://xconf.rdkcentral.com:9092/loguploader/getT2Settings

Steps after setting RFCs

  • Sync and reboot the device

  • Check the pid of telemetry2_0 to see if it is up :
    • Code Block
      languagebash
      themeMidnight
      pidof telemetry2_0
8939
  • set Multiprofile using rbuscli with upload server URL as https://xconf.rdkcentral.com/xconf/logupload.php rbuscli (sample given below)
    • Code Block
      languagebash
      themeMidnight
      rbuscli setvalues Device.X_RDKCENTRAL-COM_T2.ReportProfiles string '{
    "profiles": [
        {
            "name": "RDKV_FASIL_Profile",
            "hash": "hash1",
            "value": {
                "Name": "RDKV_FASIL_Profile",
                "Description": "Report to check Eth Parameters",
                "Version": "1",
                "Protocol": "HTTP",
                "EncodingType": "JSON",
                "ReportingInterval": 60,
                "TimeReference": "0001-01-01T00:00:00Z",
                "Parameter": [
           {
         {
   "type": "event",
            "eventName": "FREE_MEM_split",
            "componenttype": "sysintevent",
              "use": "absolute"          }"eventName": "FREE_MEM_split",
            {
            "typecomponent": "eventsysint",
            "eventName": "Xi_wifiMAC_split",
            "componentuse": "sysintabsolute",
                 "use": "absolute"   },
          },
          {
            "type": "event",
            "eventNametype": "INFO_ICMP_RFCdataModel",
            "component": "sysint",
            "usename": "absoluteCmMac",
          },
          {
    "reference": "Device.DeviceInfo.X_COMCAST-COM_CM_MAC"
       "type": "dataModel",
            "name": "mac"},
            "reference": "Device.DeviceInfo.X_COMCAST-COM_STB_MAC"        {
  },
          {
            "type": "dataModel",
                        "name": "TelemetryEnabledClearResetCount",
                        "reference": "Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.Telemetry.Enable"ClearResetCount"
          },
          {},
            "type": "dataModel",
       {
     "name": "CpuUsage",
            "reference": "Device.DeviceInfo.ProcessStatus.CPUUsage"          },
"type": "dataModel",
            {
            "typename": "dataModelDesc",
            "name": "Uptime",
            "reference": "Device.DeviceInfo.UpTimeDescription"
          },
          {},
              "type": "dataModel",
     {
       "name": "FreeMem",
              "reference": "Device.DeviceInfo.MemoryStatus.Total"     "type": "dataModel",
       },
          {
            "typereference": "dataModelDevice.DeviceInfo.FactoryResetCount",
            "name": "TotalMem",
        },
      "reference": "Device.DeviceInfo.MemoryStatus.Free"          }
   {
     ],
        "HTTP": {
          "URLtype": "https://xconf.rdkcentral.com/xconf/logupload.phpdataModel",
          "Compression": "None",
              "Methodname": "POSTMemFree",
          "RequestURIParameter": [
            {
 "reference": "Device.DeviceInfo.Hardware_MemFree"
            "Name": "deviceId",
       },
       "Reference": "Device.DeviceInfo.X_COMCAST-COM_STB_MAC"            },{
            {
              "Nametype": "reportNamedataModel",
              "Reference": "Profile.Name"            }"name": "MemUsed",
            {
              "Namereference": "reportVersion",
Device.DeviceInfo.Hardware_MemUsed"
                 "Reference": "Profile.Version"  },
          }
          ]{
         },
        "JSONEncoding": {
       "type": "dataModel",
             "ReportFormat": "NameValuePair",
          "ReportTimestampname": "Unix-Epoch"HwVer",
           }
      }
    }
  ]
}'
    • To get  "Xi_wifiMAC_split" event, reconnect ethernet to Wi-Fi or vice versa.

Telemetry sample logs

    •  "reference": "Device.DeviceInfo.HardwareVersion"
                    },
                    {
                        "type": "dataModel",
                        "name": "MemStatFree",
                        "reference": "Device.DeviceInfo.MemoryStatus.Free"
                    },
                    {
                        "type": "dataModel",
                        "name": "MemStatTotal",
                        "reference": "Device.DeviceInfo.MemoryStatus.Total"
                    },
                    {
                        "type": "dataModel",
                        "name": "MemStatUsed",
                        "reference": "Device.DeviceInfo.MemoryStatus.Used"
                    },
                    {
                        "type": "dataModel",
                        "name": "FrMemThrshld",
                        "reference": "Device.DeviceInfo.MemoryStatus.X_RDKCENTRAL-COM_FreeMemThreshold"
                    },
                    {
                        "type": "dataModel",
                        "name": "CpuUsage",
                        "reference": "Device.DeviceInfo.ProcessStatus.CPUUsage"
                    },
                    {
                        "type": "dataModel",
                        "name": "Uptime",
                        "reference": "Device.DeviceInfo.UpTime"
                    },
                    {
                        "type": "event",
                        "eventName": "FREE_MEM_split",
                        "component": "sysint",
                        "use": "absolute"
                    },
                    {
                        "type": "event",
                        "eventName": "USED_MEM_split",
                        "component": "sysint",
                        "use": "absolute"
                    },
                    {
                        "type": "event",
                        "eventName": "cpuinfo_split",
                        "component": "sysint",
                        "use": "absolute"
                    },
                    {
                        "type": "event",
                        "eventName": "vmstats_split",
                        "component": "sysint",
                        "use": "absolute"
                    },
                    {
                        "type": "grep",
                        "marker": "USED_MEM_split",
                        "search": "USED_MEM:",
                        "logFile": "messages.txt"
                    },
                    {
                        "type": "grep",
                        "marker": "prev_reboot_split",
                        "search": "PreviousRebootInfo:",
                        "logFile": "parodus.log"
                    },
                    {
                        "type": "grep",
                        "marker": "Load_Average",
                        "search": "load average: ",
                        "logFile": "messages.txt"
                    },
                    {
                        "type": "dataModel",
                        "name": "mac",
                        "reference": "Device.DeviceInfo.X_COMCAST-COM_STB_MAC"
                    },
                    {
                        "type": "dataModel",
                        "name": "Time",
                        "reference": "Device.Time.X_RDK_CurrentUTCTime"
                    }
                ],
                "HTTP": {
                    "URL": "https://xconf.rdkcentral.com:19092/telemetry-collector/rdkv-collector",
                    "Compression": "None",
                    "Method": "POST",
                    "RequestURIParameter": [
                        {
                            "Name": "deviceId",
                            "Reference": "Device.DeviceInfo.MACAddress"
                        },
                        {
                            "Name": "reportName",
                            "Reference": "Profile.Name"
                        }
                    ]
                },
                "JSONEncoding": {
                    "ReportFormat": "NameValuePair",
                    "ReportTimestamp": "Unix-Epoch"
                }
            }
        }
    ]
}'

Telemetry sample logs

Code Block
languagebash
themeMidnight
2024-11-08T08:32:16.065Z telemetry2_0[5432]:  241108-08:32:16.060279 [mod=T2, lvl=DEBUG] [tid=11624] return value of system command to remove /tmp/.t2toplog is success with code 0
2024-11-08T08:32:16.065Z telemetry2_0[5432]:  241108-08:32:16.060340 [mod=T2, lvl=DEBUG] [tid=11624] removeTopOutput --out
2024-11-08T08:32:16.065Z telemetry2_0[5432]:  241108-08:32:16.060365 [mod=T2, lvl=DEBUG] [tid=11624] updateLogSeek ++in
2024-11-08T08:32:16.065Z telemetry2_0[5432]:  241108-08:32:16.060388 [mod=T2, lvl=DEBUG] [tid=11624] Adding seekvalue of 21525 for parodus.log to logSeekMap
2024-11-08T08:32:16.065Z telemetry2_0[5432]:  241108-08:32:16.060413 [mod=T2, lvl=DEBUG] [tid=11624] updateLogSeek --out
2024-11-08T08:32:16.065Z telemetry2_0[5432]:  241108-08:32:16.060435 [mod=T2, lvl=DEBUG] [tid=11624] Execution Count = 7
2024-11-08T08:32:16.065Z telemetry2_0[5432]:  241108-08:32:16.060456 [mod=T2, lvl=DEBUG] [tid=11624] parseMarkerList --out
2024-11-08T08:32:16.065Z telemetry2_0[5432]:  241108-08:32:16.060477 [mod=T2, lvl=DEBUG] [tid=11624] getDCAResultsInVector --out
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.060500 [mod=T2, lvl=DEBUG] [tid=11624] getGrepResults --out
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.060523 [mod=T2, lvl=DEBUG] [tid=11624] encodeGrepResultInJSON ++in
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.060544 [mod=T2, lvl=DEBUG] [tid=11624] encodeGrepResultInJSON --Out
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.060588 [mod=T2, lvl=DEBUG] [tid=11624] encodeEventMarkersInJSON ++in
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.060611 [mod=T2, lvl=DEBUG] [tid=11624] encodeEventMarkersInJSON --Out
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.060631 [mod=T2, lvl=DEBUG] [tid=11624] prepareJSONReport ++in
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.060661 [mod=T2, lvl=DEBUG] [tid=11624] prepareJSONReport --Out
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.060693
Code Block
languagebash
themeMidnight
/lib/rdk/DCMscript.sh : 2024-Jan-22_11-09-52 RFC value for Telemetry 2.0 Enable is true .
/lib/rdk/DCMscript.sh : 2024-Jan-22_11-09-52 Clearing markers from /opt/.telemetry
Jan 22 11:09:52.761848 systemd[1]: Starting To start telemetry2_0...
-- Subject: A start job for unit telemetry2_0.service has begun execution
-- Defined-By: systemd
-- Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- A start job for unit telemetry2_0.service has begun execution.
--
-- The job identifier is 5304.
Jan 22 11:09:52.811090 telemetry2_0[11451]: rdk_dyn_log_initg_dl_socket = 3 __progname = telemetry2_0
Jan 22 11:09:52.812291 telemetry2_0[11451]: Conf file /etc/debug.ini open success
Jan 22 11:09:52.812291 telemetry2_0[11451]: rdk_logger_init /etc/debug.ini Already Stack Level Logging processed... not processing again.
Jan 22 11:09:52.812291 telemetry2_0[11451]: 240122-11:09:52.811568 [mod=T2, lvl=INFO] [tid=11451] Starting Telemetry 2.0 Process
Jan 22 11:09:52.813332 telemetry2_0[11464]: 240122-11:09:52.813214 [mod=T2, lvl=INFO] [tid=11464] Initializing Telemetry 2.0 Component
Jan 22 11:09:52.824023 telemetry2_0[11464]: 240122-11:09:52.813993 [mod=RBUSCORE, lvl=INFO] [tid=11464] RBus Enabled
Jan 22 11:09:52.824023 telemetry2_0[11464]: 240122-11:09:52.814166 [mod=T2, lvl=INFO] [tid=11464] rbus.c:2581 rbus_open(telemetry2_0)
Jan 22 11:09:52.824023 telemetry2_0[11464]: 240122-11:09:52.814372 [mod=T2, lvl=INFO] [tid=11464] rbuscore.c:2289 RBus Enabled
Jan 22 11:09:52.824023 telemetry2_0[11464]: 240122-11:09:52.814480 [mod=T2, lvl=INFO] [tid=11464] rbuscore.c:643 Broker address: unix:///tmp/rtrouted
Jan 22 11:09:52.824023 telemetry2_0[11464]: 240122-11:09:52.815891 [mod=T2, lvl=INFO] [tid=11464] rbus.c:2645 rbus_open(telemetry2_0) success
Jan 22 11:09:52.820354 systemd[1]: Started To start telemetry2_0.
-- Subject: A start job for unit telemetry2_0.service has finished successfully
-- Defined-By: systemd
-- Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- A start job for unit telemetry2_0.service has finished successfully.
--
-- The job identifier is 5304.
Jan 22 11:09:52.853280 telemetry2_0[11464]: 240122-11:09:52.853196 [mod=T2, lvl=ERROR] [tid=11464] getParameterValue partner id failed
Jan 22 11:09:52.853734 telemetry2_0[11464]: 240122-11:09:52.853541 [mod=T2, lvl=INFO] [tid=11464] rbuscore.c:2289 RBus Enabled
Jan 22 11:09:52.853965 telemetry2_0[11464]: 240122-11:09:52.853762 [mod=T2, lvl=INFO] [tid=11464] rbus_subscriptions.c:434 rbusSubscriptions_loadCache: file /tmp/rbus_subs_telemetry2_0
Jan 22 11:09:52.915823 telemetry2_0[11464]: 240122-11:09:52.914487 [mod=T2, lvl=INFO] [tid=11464] NonRoot feature is enabled, dropping root privileges for Telemetry 2.0 Process
Jan 22 11:09:52.961919 telemetry2_0[11464]: 240122-11:09:52.961845 [mod=T2, lvl=INFO] [tid=11464] Returning 0 local configurations
Jan 22 11:09:52.962523 telemetry2_0[11464]: 240122-11:09:52.962480 [mod=T2, lvl=INFO] [tid=11464] Failed to open persistence folder : /tmp/t2reportprofiles/, creating folder
Jan 22 11:09:52.964641 telemetry2_0[11464]: 240122-11:09:52.964575 [mod=T2, lvl=INFO] [tid=11464] loadReportProfilesFromDisk
Jan 22 11:09:52.967312 telemetry2_0[11464]: 240122-11:09:52.966588 [mod=T2, lvl=INFO] [tid=11464] JSON: loadReportProfilesFromDisk
Jan 22 11:09:53.018068 telemetry2_0[11464]: 240122-11:09:53.017901 [mod=T2, lvl=INFO] [tid=11464] Filename : RDKV_FASIL_Profile_1 Size : 1417
Jan 22 11:09:53.018620 telemetry2_0[11464]: 240122-11:09:53.018552 [mod=T2, lvl=INFO] [tid=11464] Returning 1 local configurations
Jan 22 11:09:53.018851 telemetry2_0[11464]: 240122-11:09:53.018802 [mod=T2, lvl=INFO] [tid=11464] Reporting Adjustments parameters check done successfully
Jan 22 11:09:53.018989 telemetry2_0[11464]: 240122-11:09:53.018947 [mod=T2, lvl=INFO] [tid=11464] Profile Name: RDKV_FASIL_Profile_1
Jan 22 11:09:53.018989 telemetry2_0[11464]: Configured httpURIReqParam count = 3
Jan 22 11:09:53.018989 telemetry2_0[11464]: 240122-11:09:53.018972 [mod=T2, lvl=INFO] [tid=11464] Number of httpURIReqParam added  = 3
Jan 22 11:09:53.019276 telemetry2_0[11464]: 240122-11:09:53.019135 [mod=T2, lvl=INFO] [tid=11464] Number of tr181params/markers successfully added in profile = 9
Jan 22 11:09:53.019276 telemetry2_0[11464]: 240122-11:09:53.019207 [mod=T2, lvl=INFO] [tid=11464] Successfully created/added new profile : RDKV_FASIL_Profile_1
Jan 22 11:09:53.019466 telemetry2_0[11464]: 240122-11:09:53.019311 [mod=T2, lvl=INFO] [tid=11464] Starting TimeoutThread for profile : RDKV_FASIL_Profile_1
Jan 22 11:09:53.019980 telemetry2_0[11464]: 240122-11:09:53.019610 [mod=T2, lvl=INFO] [tid=11464] Successfully enabled profile : RDKV_FASIL_Profile_1
Jan 22 11:09:53.019980 telemetry2_0[11464]: 240122-11:09:53.019770 [mod=T2, lvl=INFO] [tid=11464] Completed processing 1 profiles on the disk,trying to fetch new/updated profiles
Jan 22 11:09:53.020887 telemetry2_0[11464]: 240122-11:09:53.020770 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:09:53.020887 telemetry2_0[11464]: 240122-11:09:53.020871 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:09:53.070305 telemetry2_0[11464]: 240122-11:09:53.070167 [mod=T2, lvl=INFO] [tid=11518] process_tmprp_thread: Waiting for event from tr-181
Jan 22 11:09:53.070654 telemetry2_0[11464]: 240122-11:09:53.070379 [mod=T2, lvl=INFO] [tid=11516] process_rp_thread: Waiting for event from tr-181
Jan 22 11:09:53.121458 telemetry2_0[11464]: Inside FUNC register_sub_docs LINE 240
Jan 22 11:09:53.121458 telemetry2_0[11464]: Inside FUNC initMessageQueue LINE 1109
Jan 22 11:09:53.121458 telemetry2_0[11464]: Inside FUNC messageQueueProcessing LINE 899
Jan 22 11:09:53.121458 telemetry2_0[11464]: Inside FUNC display_subDocs LINE 88
Jan 22 11:09:53.121458 telemetry2_0[11464]: Inside FUNC check_component_crash LINE 359, init file is /tmp/telemetry_initialized_bootup
Jan 22 11:09:53.121458 telemetry2_0[11464]: /tmp/telemetry_initialized_bootup file not present, need to send component init event to webconfig
Jan 22 11:09:53.121458 telemetry2_0[11464]: notifyVersion_to_Webconfig : doc name telemetry , doc version 0
Jan 22 11:09:53.121458 telemetry2_0[11464]: 240122-11:09:53.121376 [mod=T2, lvl=INFO] [tid=11464] rbuscore.c:2289 RBus Enabled
Jan 22 11:09:53.121458 telemetry2_0[11464]: Entering rbusInit
Jan 22 11:09:53.121458 telemetry2_0[11464]: 240122-11:09:53.121472 [mod=T2, lvl=INFO] [tid=11464] rbus.c:2581 rbus_open(telemetry2_0_wbcfg)
Jan 22 11:09:53.124375 telemetry2_0[11464]: 240122-11:09:53.121650 [mod=T2, lvl=INFO] [tid=11464] rbus.c:2645 rbus_open(telemetry2_0_wbcfg) success
Jan 22 11:09:53.124375 telemetry2_0[11464]: isWebCfgRbusEnabled: rbus enabled is 1
Jan 22 11:09:53.124375 telemetry2_0[11464]: sendWebConfigSignal : rbus_set :: event_name : webconfigSignal ::
Jan 22 11:09:53.124375 telemetry2_0[11464]: isWebCfgRbusEnabled: rbus enabled is 1
Jan 22 11:09:53.124375 telemetry2_0[11464]: subscribeSubdocForceReset: Discover component for event 'Device.X_RDK_WebConfig.webcfgSubdocForceReset'
Jan 22 11:09:53.124375 telemetry2_0[11464]: webcfg_rbus_discover_component rbusModuleList[Device.X_RDK_WebConfig.webcfgSubdocForceReset]
Jan 22 11:09:53.124375 telemetry2_0[11464]: 240122-11:09:53.122937 [mod=T2, lvl=ERROR] [tid=11464] rbus.c:3541 rbus_set by telemetry2_0_wbcfg failed; Received error 12 from RBUS Daemon for the object webconfigSignal
Jan 22 11:09:53.165503 telemetry2_0[11464]: sendWebConfigSignal rbus_set for webconfigSignal failed & returns with Err: 6
Jan 22 11:09:53.165503 telemetry2_0[11464]: webcfg_rbus_discover_component: (1-1)ret[TRUE]
Jan 22 11:09:53.165503 telemetry2_0[11464]: 240122-11:09:53.165261 [mod=T2, lvl=INFO] [tid=11464] rbuscore.c:2289 RBus Enabled
Jan 22 11:09:53.167127 telemetry2_0[11464]: 240122-11:09:53.166781 [mod=T2, lvl=INFO] [tid=11464] T2ER isn't initialized or dispatch thread is already running
Jan 22 11:09:53.167127 telemetry2_0[11464]: 240122-11:09:53.166846 [mod=T2, lvl=INFO] [tid=11464] Init ReportProfiles Successful
Jan 22 11:09:53.167127 telemetry2_0[11464]: 240122-11:09:53.167034 [mod=T2, lvl=INFO] [tid=11464] Init Xconf Client Success
Jan 22 11:09:53.167127 telemetry2_0[11464]: 240122-11:09:53.167076 [mod=T2, lvl=INFO] [tid=11464] Telemetry 2.0 Component Init Success
Jan 22 11:09:53.173466 telemetry2_0[11464]: 240122-11:09:53.172737 [mod=T2, lvl=INFO] [tid=11535] Setting config URL base location to : https://xconf.rdkcentral.com:19092/loguploader/getT2Settings
Jan 22 11:09:53.371277 telemetry2_0[11464]: 240122-11:09:53.370312 [mod=T2, lvl=ERROR] [tid=11535] Unable to get Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:09:53.371277 telemetry2_0[11464]: 240122-11:09:53.370362 [mod=T2, lvl=ERROR] [tid=11535] Failed to get Value for Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:09:53.371277 telemetry2_0[11464]: 240122-11:09:53.370399 [mod=T2, lvl=INFO] [tid=11535] Waiting for 180 sec before trying fetchRemoteConfiguration, No.of tries : 1
Jan 22 11:09:59.017188 telemetry2_0[11464]: eventSubHandler called:
Jan 22 11:09:59.017188 telemetry2_0[11464]:         action=subscribe
Jan 22 11:09:59.017188 telemetry2_0[11464]:         eventName=Telemetry.ReportProfiles.ProfilesUpdated
Jan 22 11:09:59.017188 telemetry2_0[11464]: provider: eventSubHandler unexpected eventName Telemetry.ReportProfiles.ProfilesUpdated
Jan 22 11:09:59.017188 telemetry2_0[11464]: 240122-11:09:59.015271 [mod=T2, lvl=INFO] [tid=11466] rbus_subscriptions.c:602 rbusSubscriptions_saveCache: saving /tmp/rbus_subs_telemetry2_0
Jan 22 11:10:03.128556 telemetry2_0[11464]: subscribeSubdocForceReset: [Device.X_RDK_WebConfig.webcfgSubdocForceReset] Component found
Jan 22 11:10:03.128556 telemetry2_0[11464]: subscribeSubdocForceReset: subscribing to event Device.X_RDK_WebConfig.webcfgSubdocForceReset
Jan 22 11:10:03.128556 telemetry2_0[11464]: 240122-11:10:03.128472 [mod=T2, lvl=INFO] [tid=11530] rbuscore.c:738 Subscription for Device.X_RDK_WebConfig.webcfgSubdocForceReset::Device.X_RDK_WebConfig.webcfgSubdocForceReset is now active.
Jan 22 11:10:03.130171 telemetry2_0[11464]: 240122-11:10:03.128573 [mod=T2, lvl=INFO] [tid=11530] rbus.c:4438 rbusEvent_SubscribeWithRetries: Device.X_RDK_WebConfig.webcfgSubdocForceReset subscribe retries succeeded
Jan 22 11:10:53.000275 telemetry2_0[11464]: subscribeSubdocForceReset: subscribed to event Device.X_RDK_WebConfig.webcfgSubdocForceReset
Jan 22 11:10:53.000275 telemetry2_0[11464]: subscribeSubdocForceReset: job done
Jan 22 11:10:53.000275 telemetry2_0[11464]: 240122-11:10:53.000171 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:10:53.000275 telemetry2_0[11464]: 240122-11:10:53.000273 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:10:53.002105 telemetry2_0[11464]: 240122-11:10:53.000346 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:10:53.002105 telemetry2_0[11464]: 240122-11:10:53.000495 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:10:53.002105 telemetry2_0[11464]: 240122-11:10:53.000545 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:10:53.002105 telemetry2_0[11464]: 240122-11:10:53.000573 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:10:53.002909 telemetry2_0[11464]: 240122-11:10:53.002798 [mod=T2, lvl=INFO] [tid=15096] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:10:54.048937 telemetry2_0[11464]: 240122-11:10:54.039445 [mod=T2, lvl=INFO] [tid=15096] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"42"},{"Uptime":"164"},{"FreeMem":"2036476"},{"TotalMem":"1244516"}]}
Jan 22 11:10:54.048937 telemetry2_0[11464]: 240122-11:10:54.039552 [mod=T2, lvl=INFO] [tid=15096] Report Size = 148
Jan 22 11:10:54.071143 telemetry2_0[11464]: 240122-11:10:54.070889 [mod=T2, lvl=ERROR] [tid=15096] getParameterValue partner id failed
Jan 22 11:10:54.821498 telemetry2_0[15176]: 240122-11:10:54.821395 [mod=T2, lvl=INFO] [tid=15176] Report Sent Successfully over HTTP : 200
Jan 22 11:10:54.835182 telemetry2_0[11464]: 240122-11:10:54.834200 [mod=T2, lvl=INFO] [tid=15096] Elapsed Time for : RDKV_FASIL_Profile_1 = 1.831256846 (Sec.NanoSec)
Jan 22 11:10:54.835182 telemetry2_0[11464]: 240122-11:10:54.834300 [mod=T2, lvl=INFO] [tid=15096] CollectAndReport --out
Jan 22 11:11:53.002032 telemetry2_0[11464]: 240122-11:11:53.001794 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:11:53.002032 telemetry2_0[11464]: 240122-11:11:53.001864 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:11:53.002032 telemetry2_0[11464]: 240122-11:11:53.001909 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:11:53.006161 telemetry2_0[11464]: 240122-11:11:53.002060 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:11:53.006161 telemetry2_0[11464]: 240122-11:11:53.002101 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:11:53.006161 telemetry2_0[11464]: 240122-11:11:53.002127 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:11:53.006161 telemetry2_0[11464]: 240122-11:11:53.002836 [mod=T2, lvl=INFO] [tid=18707] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:11:54.066321 telemetry2_0[11464]: 240122-11:11:54.065982 [mod=T2, lvl=INFO] [tid=18707] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"75"},{"Uptime":"224"},{"FreeMem":"2036476"},{"TotalMem":"1230780"}]}
Jan 22 11:11:54.066321 telemetry2_0[11464]: 240122-11:11:54.066066 [mod=T2, lvl=INFO] [tid=18707] Report Size = 148
Jan 22 11:11:54.114374 telemetry2_0[11464]: 240122-11:11:54.112985 [mod=T2, lvl=ERROR] [tid=18707] getParameterValue partner id failed
Jan 22 11:11:54.127511 telemetry2_0[19067]: curl failed: Couldn't connect to server
Jan 22 11:11:54.128816 telemetry2_0[19067]: 240122-11:11:54.127515 [mod=T2, lvl=ERROR] [tid=19067] Failed to send report over HTTP, HTTP Response Code : 0
Jan 22 11:11:54.134135 telemetry2_0[11464]: 240122-11:11:54.134029 [mod=T2, lvl=INFO] [tid=18707] Report Cached, No. of reportes cached = 1
Jan 22 11:11:54.135255 telemetry2_0[11464]: 240122-11:11:54.134545 [mod=T2, lvl=INFO] [tid=18707] Elapsed Time for : RDKV_FASIL_Profile_1 = 1.131640503 (Sec.NanoSec)
Jan 22 11:11:54.135255 telemetry2_0[11464]: 240122-11:11:54.134608 [mod=T2, lvl=INFO] [tid=18707] CollectAndReport --out
Jan 22 11:12:53.000364 telemetry2_0[11464]: 240122-11:12:53.000190 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:12:53.004019 telemetry2_0[11464]: 240122-11:12:53.000385 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:12:53.004019 telemetry2_0[11464]: 240122-11:12:53.000530 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:12:53.004019 telemetry2_0[11464]: 240122-11:12:53.000867 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:12:53.004019 telemetry2_0[11464]: 240122-11:12:53.000987 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:12:53.004019 telemetry2_0[11464]: 240122-11:12:53.001176 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:12:53.004019 telemetry2_0[11464]: 240122-11:12:53.000222 [mod=T2, lvl=INFO] [tid=11535] TIMEDOUT -- trying fetchConfigurations again
Jan 22 11:12:53.004019 telemetry2_0[11464]: 240122-11:12:53.003887 [mod=T2, lvl=INFO] [tid=24686] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:12:54.216185 telemetry2_0[11464]: 240122-11:12:54.214768 [mod=T2, lvl=ERROR] [tid=11535] Unable to get Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:12:54.216185 telemetry2_0[11464]: 240122-11:12:54.214847 [mod=T2, lvl=ERROR] [tid=11535] Failed to get Value for Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:12:54.216185 telemetry2_0[11464]: 240122-11:12:54.214911 [mod=T2, lvl=INFO] [tid=11535] Waiting for 180 sec before trying fetchRemoteConfiguration, No.of tries : 2
Jan 22 11:12:54.222990 telemetry2_0[11464]: 240122-11:12:54.222903 [mod=T2, lvl=INFO] [tid=24686] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"12"},{"Uptime":"284"},{"FreeMem":"2036476"},{"TotalMem":"1238468"},{"Xi_wifiMAC_split":"48:46:C1:04:67:42"}]}
Jan 22 11:12:54.223535 telemetry2_0[11464]: 240122-11:12:54.223016 [mod=T2, lvl=INFO] [tid=24686] Report Size = 189
Jan 22 11:12:54.253465 telemetry2_0[11464]: 240122-11:12:54.253364 [mod=T2, lvl=ERROR] [tid=24686] getParameterValue partner id failed
Jan 22 11:12:55.330211 telemetry2_0[24720]: 240122-11:12:55.330035 [mod=T2, lvl=INFO] [tid=24720] Report Sent Successfully over HTTP : 200
Jan 22 11:12:55.342478 telemetry2_0[11464]: 240122-11:12:55.341928 [mod=T2, lvl=INFO] [tid=24686] Trying to send  1 cached reports
Jan 22 11:12:55.373937 telemetry2_0[11464]: 240122-11:12:55.372638 [mod=T2, lvl=ERROR] [tid=24686] getParameterValue partner id failed
Jan 22 11:12:56.372924 telemetry2_0[24854]: 240122-11:12:56.372819 [mod=T2, lvl=INFO] [tid=24854] Report Sent Successfully over HTTP : 200
Jan 22 11:12:56.383873 telemetry2_0[11464]: 240122-11:12:56.383622 [mod=T2, lvl=INFO] [tid=24686] Elapsed Time for : RDKV_FASIL_Profile_1 = 3.379609976 (Sec.NanoSec)
Jan 22 11:12:56.383873 telemetry2_0[11464]: 240122-11:12:56.383712 [mod=T2, lvl=INFO] [tid=24686] CollectAndReport --out
Jan 22 11:13:53.000502 telemetry2_0[11464]: 240122-11:13:53.000134 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:13:53.000502 telemetry2_0[11464]: 240122-11:13:53.000228 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:13:53.000502 telemetry2_0[11464]: 240122-11:13:53.000269 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:13:53.000502 telemetry2_0[11464]: 240122-11:13:53.000401 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:13:53.000502 telemetry2_0[11464]: 240122-11:13:53.000439 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:13:53.000502 telemetry2_0[11464]: 240122-11:13:53.000460 [mod=T2, lvl=INFO] [tid=28175] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:13:53.000502 telemetry2_0[11464]: 240122-11:13:53.000464 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:13:54.033867 telemetry2_0[11464]: 240122-11:13:54.033341 [mod=T2, lvl=INFO] [tid=28175] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"43"},{"Uptime":"344"},{"FreeMem":"2036476"},{"TotalMem":"1228592"}]}
Jan 22 11:13:54.033867 telemetry2_0[11464]: 240122-11:13:54.033422 [mod=T2, lvl=INFO] [tid=28175] Report Size = 148
Jan 22 11:13:54.071442 telemetry2_0[11464]: 240122-11:13:54.068611 [mod=T2, lvl=ERROR] [tid=28175] getParameterValue partner id failed
Jan 22 11:13:55.094465 telemetry2_0[28250]: 240122-11:13:55.094257 [mod=T2, lvl=INFO] [tid=28250] Report Sent Successfully over HTTP : 200
Jan 22 11:13:55.106653 telemetry2_0[11464]: 240122-11:13:55.106438 [mod=T2, lvl=INFO] [tid=28175] Elapsed Time for : RDKV_FASIL_Profile_1 = 2.105887036 (Sec.NanoSec)
Jan 22 11:13:55.106653 telemetry2_0[11464]: 240122-11:13:55.106524 [mod=T2, lvl=INFO] [tid=28175] CollectAndReport --out
Jan 22 11:14:53.000227 telemetry2_0[11464]: 240122-11:14:53.000140 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:14:53.000227 telemetry2_0[11464]: 240122-11:14:53.000219 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:14:53.001644 telemetry2_0[11464]: 240122-11:14:53.000269 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:14:53.001644 telemetry2_0[11464]: 240122-11:14:53.000427 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:14:53.001644 telemetry2_0[11464]: 240122-11:14:53.000469 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:14:53.001644 telemetry2_0[11464]: 240122-11:14:53.000499 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:14:53.001644 telemetry2_0[11464]: 240122-11:14:53.001252 [mod=T2, lvl=INFO] [tid=1023] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:14:54.045316 telemetry2_0[11464]: 240122-11:14:54.045019 [mod=T2, lvl=INFO] [tid=1023] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"39"},{"Uptime":"404"},{"FreeMem":"2036476"},{"TotalMem":"1228320"},{"Xi_wifiMAC_split":"48:46:C1:04:67:42"}]}
Jan 22 11:14:54.045316 telemetry2_0[11464]: 240122-11:14:54.045134 [mod=T2, lvl=INFO] [tid=1023] Report Size = 189
Jan 22 11:14:54.086048 telemetry2_0[11464]: 240122-11:14:54.085360 [mod=T2, lvl=ERROR] [tid=1023] getParameterValue partner id failed
Jan 22 11:14:54.849196 telemetry2_0[1103]: 240122-11:14:54.849089 [mod=T2, lvl=INFO] [tid=1103] Report Sent Successfully over HTTP : 200
Jan 22 11:14:54.861630 telemetry2_0[11464]: 240122-11:14:54.861347 [mod=T2, lvl=INFO] [tid=1023] Elapsed Time for : RDKV_FASIL_Profile_1 = 1.860027219 (Sec.NanoSec)
Jan 22 11:14:54.861630 telemetry2_0[11464]: 240122-11:14:54.861451 [mod=T2, lvl=INFO] [tid=1023] CollectAndReport --out
Jan 22 11:15:53.000443 telemetry2_0[11464]: 240122-11:15:53.000148 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:15:53.000443 telemetry2_0[11464]: 240122-11:15:53.000225 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:15:53.000443 telemetry2_0[11464]: 240122-11:15:53.000274 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:15:53.002231 telemetry2_0[11464]: 240122-11:15:53.002032 [mod=T2, lvl=INFO] [tid=4440] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:15:53.002231 telemetry2_0[11464]: 240122-11:15:53.002032 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:15:53.002231 telemetry2_0[11464]: 240122-11:15:53.002151 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:15:53.002231 telemetry2_0[11464]: 240122-11:15:53.002190 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:15:54.000438 telemetry2_0[11464]: 240122-11:15:54.000172 [mod=T2, lvl=INFO] [tid=11535] TIMEDOUT -- trying fetchConfigurations again
Jan 22 11:15:54.055024 telemetry2_0[11464]: 240122-11:15:54.054938 [mod=T2, lvl=INFO] [tid=4440] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"42"},{"Uptime":"464"},{"FreeMem":"2036476"},{"TotalMem":"1228616"},{"FREE_MEM_split":"1222932"}]}
Jan 22 11:15:54.055024 telemetry2_0[11464]: 240122-11:15:54.055033 [mod=T2, lvl=INFO] [tid=4440] Report Size = 177
Jan 22 11:15:54.230077 telemetry2_0[11464]: 240122-11:15:54.228754 [mod=T2, lvl=ERROR] [tid=11535] Unable to get Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:15:54.230077 telemetry2_0[11464]: 240122-11:15:54.228812 [mod=T2, lvl=ERROR] [tid=11535] Failed to get Value for Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:15:54.230077 telemetry2_0[11464]: 240122-11:15:54.228849 [mod=T2, lvl=INFO] [tid=11535] Waiting for 180 sec before trying fetchRemoteConfiguration, No.of tries : 3
Jan 22 11:15:54.258543 telemetry2_0[11464]: 240122-11:15:54.258481 [mod=T2, lvl=ERROR] [tid=4440] getParameterValue partner id failed
Jan 22 11:15:55.009542 telemetry2_0[4538]: 240122-11:15:55.009318 [mod=T2, lvl=INFO] [tid=4538] Report Sent Successfully over HTTP : 200
Jan 22 11:15:55.026694 telemetry2_0[11464]: 240122-11:15:55.026350 [mod=T2, lvl=INFO] [tid=4440] Elapsed Time for : RDKV_FASIL_Profile_1 = 2.24210929 (Sec.NanoSec)
Jan 22 11:15:55.026694 telemetry2_0[11464]: 240122-11:15:55.026446 [mod=T2, lvl=INFO] [tid=4440] CollectAndReport --out
Jan 22 11:16:53.000815 telemetry2_0[11464]: 240122-11:16:53.000175 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:16:53.000815 telemetry2_0[11464]: 240122-11:16:53.000309 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:16:53.000815 telemetry2_0[11464]: 240122-11:16:53.000418 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:16:53.000815 telemetry2_0[11464]: 240122-11:16:53.000662 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:16:53.000815 telemetry2_0[11464]: 240122-11:16:53.000716 [mod=T2, lvl=INFO] [tid=8040] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:16:53.000815 telemetry2_0[11464]: 240122-11:16:53.000751 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:16:53.004439 telemetry2_0[11464]: 240122-11:16:53.000932 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:16:54.028598 telemetry2_0[11464]: 240122-11:16:54.028322 [mod=T2, lvl=INFO] [tid=8040] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"12"},{"Uptime":"524"},{"FreeMem":"2036476"},{"TotalMem":"1233792"}]}
Jan 22 11:16:54.028598 telemetry2_0[11464]: 240122-11:16:54.028400 [mod=T2, lvl=INFO] [tid=8040] Report Size = 148
Jan 22 11:16:54.063884 telemetry2_0[11464]: 240122-11:16:54.062704 [mod=T2, lvl=ERROR] [tid=8040] getParameterValue partner id failed
Jan 22 11:16:54.829245 telemetry2_0[8052]: 240122-11:16:54.828188 [mod=T2, lvl=INFO] [tid=8052] Report Sent Successfully over HTTP : 200
Jan 22 11:16:54.846231 telemetry2_0[11464]: 240122-11:16:54.845961 [mod=T2, lvl=INFO] [tid=8040] Elapsed Time for : RDKV_FASIL_Profile_1 = 1.845109073 (Sec.NanoSec)
Jan 22 11:16:54.846231 telemetry2_0[11464]: 240122-11:16:54.846064 [mod=T2, lvl=INFO] [tid=8040] CollectAndReport --out
Jan 22 11:17:53.000560 telemetry2_0[11464]: 240122-11:17:53.000149 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:17:53.000560 telemetry2_0[11464]: 240122-11:17:53.000252 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:17:53.000560 telemetry2_0[11464]: 240122-11:17:53.000306 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:17:53.000560 telemetry2_0[11464]: 240122-11:17:53.000462 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:17:53.000560 telemetry2_0[11464]: 240122-11:17:53.000492 [mod=T2, lvl=INFO] [tid=11808] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:17:53.000560 telemetry2_0[11464]: 240122-11:17:53.000515 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:17:53.005659 telemetry2_0[11464]: 240122-11:17:53.000609 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:17:54.029166 telemetry2_0[11464]: 240122-11:17:54.028802 [mod=T2, lvl=INFO] [tid=11808] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"41"},{"Uptime":"584"},{"FreeMem":"2036476"},{"TotalMem":"1216092"}]}
Jan 22 11:17:54.029166 telemetry2_0[11464]: 240122-11:17:54.028906 [mod=T2, lvl=INFO] [tid=11808] Report Size = 148
Jan 22 11:17:54.065263 telemetry2_0[11464]: 240122-11:17:54.063818 [mod=T2, lvl=ERROR] [tid=11808] getParameterValue partner id failed
Jan 22 11:17:54.844294 telemetry2_0[11893]: 240122-11:17:54.844140 [mod=T2, lvl=INFO] [tid=11893] Report Sent Successfully over HTTP : 200
Jan 22 11:17:54.856767 telemetry2_0[11464]: 240122-11:17:54.856227 [mod=T2, lvl=INFO] [tid=11808] Elapsed Time for : RDKV_FASIL_Profile_1 = 1.855641535 (Sec.NanoSec)
Jan 22 11:17:54.857141 telemetry2_0[11464]: 240122-11:17:54.856784 [mod=T2, lvl=INFO] [tid=11808] CollectAndReport --out
Jan 22 11:18:53.000559 telemetry2_0[11464]: 240122-11:18:53.000145 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:18:53.000559 telemetry2_0[11464]: 240122-11:18:53.000241 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:18:53.000559 telemetry2_0[11464]: 240122-11:18:53.000296 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:18:53.000559 telemetry2_0[11464]: 240122-11:18:53.000432 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:18:53.000559 telemetry2_0[11464]: 240122-11:18:53.000482 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:18:53.000559 telemetry2_0[11464]: 240122-11:18:53.000511 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:18:53.002603 telemetry2_0[11464]: 240122-11:18:53.002538 [mod=T2, lvl=INFO] [tid=14954] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:18:54.000836 telemetry2_0[11464]: 240122-11:18:54.000234 [mod=T2, lvl=INFO] [tid=11535] TIMEDOUT -- trying fetchConfigurations again
Jan 22 11:18:54.092113 telemetry2_0[11464]: 240122-11:18:54.091802 [mod=T2, lvl=INFO] [tid=14954] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"40"},{"Uptime":"644"},{"FreeMem":"2036476"},{"TotalMem":"1218120"}]}
Jan 22 11:18:54.092113 telemetry2_0[11464]: 240122-11:18:54.091887 [mod=T2, lvl=INFO] [tid=14954] Report Size = 148
Jan 22 11:18:54.247796 telemetry2_0[11464]: 240122-11:18:54.243241 [mod=T2, lvl=ERROR] [tid=11535] Unable to get Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:18:54.247796 telemetry2_0[11464]: 240122-11:18:54.243301 [mod=T2, lvl=ERROR] [tid=11535] Failed to get Value for Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:18:54.247796 telemetry2_0[11464]: 240122-11:18:54.243337 [mod=T2, lvl=INFO] [tid=11535] Waiting for 180 sec before trying fetchRemoteConfiguration, No.of tries : 4
Jan 22 11:18:54.287811 telemetry2_0[11464]: 240122-11:18:54.286217 [mod=T2, lvl=ERROR] [tid=14954] getParameterValue partner id failed
Jan 22 11:18:55.199170 telemetry2_0[15067]: 240122-11:18:55.198997 [mod=T2, lvl=INFO] [tid=15067] Report Sent Successfully over HTTP : 200
Jan 22 11:18:55.210911 telemetry2_0[11464]: 240122-11:18:55.210805 [mod=T2, lvl=INFO] [tid=14954] Elapsed Time for : RDKV_FASIL_Profile_1 = 2.207965161 (Sec.NanoSec)
Jan 22 11:18:55.210911 telemetry2_0[11464]: 240122-11:18:55.210902 [mod=T2, lvl=INFO] [tid=14954] CollectAndReport --out
Jan 22 11:19:53.000559 telemetry2_0[11464]: 240122-11:19:53.000130 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:19:53.000559 telemetry2_0[11464]: 240122-11:19:53.000212 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:19:53.000559 telemetry2_0[11464]: 240122-11:19:53.000260 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:19:53.000559 telemetry2_0[11464]: 240122-11:19:53.000403 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:19:53.000559 telemetry2_0[11464]: 240122-11:19:53.000431 [mod=T2, lvl=INFO] [tid=18160] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:19:53.000559 telemetry2_0[11464]: 240122-11:19:53.000450 [mod=T2, lvl=INFO] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:19:53.000559 telemetry2_0[11464]: 240122-11:19:53.000481 [mod=T2, lvl=INFO] [tid=11506] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:19:54.039672 telemetry2_0[11464]: 240122-11:19:54.039379 [mod=T2, lvl=INFO] [tid=18160] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"44"},{"Uptime":"704"},{"FreeMem":"2036476"},{"TotalMem":"1216208"}]}
Jan 22 11:19:54.039672 telemetry2_0[11464]: 240122-11:19:54.039470 [mod=T2, lvl=INFO] [tid=18160] Report Size = 148
Jan 22 11:19:54.084871 telemetry2_0[11464]: 240122-11:19:54.083361 [mod=T2, lvl=ERROR] [tid=18160] getParameterValue partner id failed
Jan 22 11:19:54.889748 telemetry2_0[18256]: 240122-11:19:54.889656 [mod=T2, lvl=INFO] [tid=18256] Report Sent Successfully over HTTP : 200
Jan 22 11:19:54.902034 telemetry2_0[11464]: 240122-11:19:54.901815 [mod=T2, lvl=INFO] [tid=18160] Elapsed Time for : RDKV_FASIL_Profile_1 = 1.901287439 (Sec.NanoSec)
Jan 22 11:19:54.902860 telemetry2_0[11464]: 240122-11:19:54.902060 [mod=T2, lvl=INFO] [tid=18160] CollectAndReport --out
Jan 22 11:20:53.000449 telemetry2_0[11464]: 240122-11:20:53.000294 [mod=T2, lvl=INFO] [tid=11506] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:20:53.003471 telemetry2_0[11464]: 240122-11:20:53.000458 [mod=T2, lvl=INFO] [tid=11506] ReportProfiles_TimeoutCb ++in
Jan 22 11:20:53.003471 telemetry2_0[11464]: 240122-11:20:53.000605 [mod=T2, lvl=INFO] [tid=11506] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:20:53.00347111624] cJSON Report = {"Report":[{"Desc":"TR-181, TR-135 and Comcast specific Datamodel Configuration"},{"MemStatFree":"695728"},{"MemStatTotal":"1961476"},{"CpuUsage":"22"},{"Uptime":"808"},{"mac":"00:10:18:65:EA:0E"}]}
2024-11-08T08:32:16.078Z telemetry2_0[114645432]: 240122 241108-1108:2032:5316.000909060732 [mod=T2, lvl=INFO] [tid=1150611624] ReportProfiles_TimeoutCb --out
Jan 22 11:20:53.003471Report Size = 198
2024-11-08T08:32:16.078Z telemetry2_0[114645432]:  240122241108-1108:2032:5316.001026060766 [mod=T2, lvl=INFODEBUG] [tid=11506] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:20:53.00347111624] prepareHttpUrl: Default URL: http://52.0.158.162:8080/telemetry-collector/rdkv-collector
2024-11-08T08:32:16.078Z telemetry2_0[114645432]: 240122 241108-1108:2032:5316.001181060791 [mod=T2, lvl=INFODEBUG] [tid=1150611624] Waiting for timeref or reporting interval for the profile - RDKV_FASIL_Profile_1 is started
Jan 22 11:20:53.003471getParameterValue ++in
2024-11-08T08:32:16.078Z telemetry2_0[114645432]:  240122241108-1108:2032:5316.001334060811 [mod=T2, lvl=INFODEBUG] [tid=2130311624] CollectAndReportgetRbusParameterVal ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:20:54.037015
2024-11-08T08:32:16.078Z telemetry2_0[114645432]: 240122 241108-1108:2032:5416.036930060851 [mod=T2, lvl=INFODEBUG] [tid=2130311624] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"10"},{"Uptime":"764"},{"FreeMem":"2036476"},{"TotalMem":"1218204"},{"FREE_MEM_split":"1206380"}]}
Jan 22 11:20:54.038451 telemetry2_0[11464]: 240122-11:20:54.037068rbus.c:3305 Calling rbus_invokeRemoteMethod2 for [Device.DeviceInfo.MACAddress]
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.061253 [mod=T2, lvl=DEBUG] [tid=11624] rbuscore.c:1143 Cannot reach object Device.DeviceInfo.MACAddress.
2024-11-08T08:32:16.078Z telemetry2_0[5432]:  241108-08:32:16.061290 [mod=T2, lvl=INFOERROR] [tid=21303] Report Size = 177
Jan 22 11:20:54.06704611624] rbus.c:3317 get by telemetry2_0 failed; Received error 17 from RBUS Daemon for the object Device.DeviceInfo.MACAddress
2024-11-08T08:32:16.078Z telemetry2_0[114645432]: 240122 241108-1108:2032:5416.066965061315 [mod=T2, lvl=ERROR] [tid=2130311624] getParameterValueUnable partnerto id failed
Jan 22 11:20:54.841226get Device.DeviceInfo.MACAddress
2024-11-08T08:32:16.078Z telemetry2_0[213125432]:  240122241108-1108:2032:5416.841121061337 [mod=T2, lvl=INFODEBUG] [tid=2131211624] Report Sent Successfully over HTTP : 200
Jan 22 11:20:54.852753getParameterValue --out
2024-11-08T08:32:16.078Z telemetry2_0[114645432]: 240122 241108-1108:2032:5416.852636061358 [mod=T2, lvl=INFOERROR] [tid=2130311624] Failed Elapsedto Timeretrieve forparam : RDKV_FASIL_Profile_1 = 1.851206395 (Sec.NanoSec)
Jan 22 11:20:54.852753Device.DeviceInfo.MACAddress
2024-11-08T08:32:16.083Z telemetry2_0[114645432]: 240122 241108-1108:2032:5416.852749061418 [mod=T2, lvl=INFODEBUG] [tid=2130311624] CollectAndReport --out
Jan 22 11:21:53.000892 prepareHttpUrl: Modified URL: https://xconf.rdkcentral.com:19092/telemetry-collector/rdkv-collector?reportName=RDKV_FASIL_Profile
2024-11-08T08:32:16.083Z telemetry2_0[114645432]: 240122 241108-1108:2132:5316.000783061442 [mod=T2, lvl=INFODEBUG] [tid=1150611624] TIMEOUT for profile - RDKV_FASIL_Profile_1
Jan 22 11:21:53.000892sendReportOverHTTP ++in
2024-11-08T08:32:16.083Z telemetry2_0[114645432]:  240122241108-1108:2132:5316.000888061509 [mod=T2, lvl=INFODEBUG] [tid=1150611624] ReportProfiles_TimeoutCbgetParameterValue ++in
Jan 22 11:21:53.0031432024-11-08T08:32:16.083Z telemetry2_0[114645432]: 240122 241108-1108:2132:5316.000948061532 [mod=T2, lvl=INFODEBUG] [tid=1150611624] NotifyTimeout: profile RDKV_FASIL_Profile_1 is in Enabled state
Jan 22 11:21:53.003143getRbusParameterVal ++in
2024-11-08T08:32:16.083Z telemetry2_0[114645432]: 240122 241108-1108:2132:5316.001125061569 [mod=T2, lvl=INFODEBUG] [tid=11506] ReportProfiles_TimeoutCb --out
Jan 22 11:21:53.00314311624] rbus.c:3305 Calling rbus_invokeRemoteMethod2 for [Device.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId]
2024-11-08T08:32:16.100Z telemetry2_0[114645432]:  240122241108-1108:2132:5316.001179100461 [mod=T2, lvl=INFODEBUG] [tid=1150611624] Waiting for 60 sec for next TIMEOUT for profile as reporting interval is taken - RDKV_FASIL_Profile_1
Jan 22 11:21:53.003143rbus.c:3325 Received response for remote method invocation!
2024-11-08T08:32:16.100Z telemetry2_0[114645432]:  240122241108-1108:2132:5316.001210100582 [mod=T2, lvl=INFODEBUG] [tid=1150611624] Waiting for timeref or reporting interval forrbus.c:3329 Response from the profile - RDKV_FASIL_Profile_1remote method is started
Jan 22 11:21:53.003143[0]!
2024-11-08T08:32:16.100Z telemetry2_0[114645432]: 240122 241108-1108:2132:5316.001386100617 [mod=T2, lvl=INFODEBUG] [tid=2477811624] CollectAndReport ++in profileName : RDKV_FASIL_Profile_1
Jan 22 11:21:54.000402rbus.c:3336 Received valid response!
2024-11-08T08:32:16.100Z telemetry2_0[114645432]: 240122 241108-1108:2132:5416.000148100670 [mod=T2, lvl=INFODEBUG] [tid=11535] TIMEDOUT -- trying fetchConfigurations again
Jan 22 11:21:54.08396011624] Device.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId = community
2024-11-08T08:32:16.102Z telemetry2_0[114645432]:  240122241108-1108:2132:5416.083584100697 [mod=T2, lvl=INFODEBUG] [tid=2477811624] cJSON Report = {"Report":[{"mac":"D4:CF:F9:49:E8:CE"},{"TelemetryEnabled":"true"},{"CpuUsage":"48"},{"Uptime":"824"},{"FreeMem":"2036476"},{"TotalMem":"1212772"}]}
Jan 22 11:21:54.083960 telemetry2_0[11464]: 240122-11:21:54.083655getRbusParameterVal --out
2024-11-08T08:32:16.102Z telemetry2_0[5432]:  241108-08:32:16.100719 [mod=T2, lvl=DEBUG] [tid=11624] getParameterValue --out
2024-11-08T08:32:16.102Z telemetry2_0[5432]:  241108-08:32:16.100747 [mod=T2, lvl=INFOERROR] [tid=2477811624] ReportgetParameterValue Sizepartner =id 148
Jan 22 11:21:54.230848failed
2024-11-08T08:32:16.608Z telemetry2_0[114645432]:  240122241108-1108:2132:5416.227479608618 [mod=T2, lvl=ERRORINFO] [tid=11535] Unable to get Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:21:54.23084811624] The return status from the child with pid 18127 is CurlStatus : 1
2024-11-08T08:32:16.608Z telemetry2_0[114645432]:  240122241108-1108:2132:5416.227933608721 [mod=T2, lvl=ERRORINFO] [tid=11535] Failed to get Value for Device.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.AccountInfo.AccountID
Jan 22 11:21:54.23084811624] The return status from the child with pid 18127 SetopCode: No error; ResponseCode : No error; HTTP_CODE : 200; Line Number : 424
2024-11-08T08:32:16.608Z telemetry2_0[114645432]: 240122 241108-1108:2132:5416.228002608752 [mod=T2, lvl=ERRORINFO] [tid=1153511624] ReachedReport maxSent xconfSuccessfully retryover countsHTTP : 5, Using saved profile if exists until next reboot
Jan 22 11:21:54.230848200
2024-11-08T08:32:16.608Z telemetry2_0[114645432]:  240122241108-1108:2132:5416.228058608776 [mod=T2, lvl=ERRORDEBUG] [tid=1153511624] Failed to fetch updated configuration and no saved configurations on disk for XCONF, uninitializing  the process
Jan 22 11:21:54.262364sendReportOverHTTP --out
2024-11-08T08:32:16.609Z telemetry2_0[114645432]: 240122 241108-1108:2132:5416.260871608805 [mod=T2, lvl=ERRORINFO] [tid=2477811624] getParameterValue partner id failed
Jan 22 11:21:55.046818Elapsed Time for : RDKV_FASIL_Profile = 1.607921134 (Sec.NanoSec)
2024-11-08T08:32:16.609Z telemetry2_0[248855432]: 240122 241108-1108:2132:5516.046699608828 [mod=T2, lvl=INFODEBUG] [tid=2488511624] Report Sentprofile->triggerReportOnCondition Successfullyis over HTTP : 200
Jan 22 11:21:55.057804not set
2024-11-08T08:32:16.609Z telemetry2_0[114645432]: 240122 241108-1108:2132:5516.057583608849 [mod=T2, lvl=INFO] [tid=2477811624] ElapsedCollectAndReport while TimeLoop for-- : RDKV_FASIL_Profile_1 = 2.56141545 (Sec.NanoSec)
Jan 22 11:21:55.057804END; wait for restart event
2024-11-08T08:32:16.609Z telemetry2_0[114645432]:  240122241108-1108:2132:5516.057666608870 [mod=T2, lvl=INFO] [tid=2477811624] CollectAndReport --out