Started by user RDK-Test Running as SYSTEM Building in workspace /var/lib/jenkins/workspace/JOB1 [JOB1] $ /var/lib/jenkins/tools/hudson.tasks.Maven_MavenInstallation/installmaven/bin/mvn -s /mnt/maven/settings.xml clean install -U exec:java -DskipTests=true -DretryByDefault=false -DbuildType=RDK -Dhttps.protocols=TLSv1.1,TLSv1.2 -Dsun.security.ssl.allowUnsafeRenegotiation=true -Dautomatics.properties.file=http://192.168.2.145:8080/AutomaticsProps/automatics.properties [INFO] Scanning for projects... [WARNING] [WARNING] Some problems were encountered while building the effective model for RDKMAutomationRDKBTests:rdkb-automation:jar:0.0.1-SNAPSHOT [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 171 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 314 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 457 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 600 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 743 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 886 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1029 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1172 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1315 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1458 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1601 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1744 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1887 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2030 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2173 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2316 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2459 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2602 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2745 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2888 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3031 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3174 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3317 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3460 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3603 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3746 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3889 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4032 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4175 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4318 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4461 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4604 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4747 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4890 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5033 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5176 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5319 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5462 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5605 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5748 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5891 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6034 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6177 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6320 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6463 [WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6606 [WARNING] [WARNING] It is highly recommended to fix these problems because they threaten the stability of your build. [WARNING] [WARNING] For this reason, future Maven versions might no longer support building such malformed projects. [WARNING] [INFO] [INFO] --------------< RDKMAutomationRDKBTests:rdkb-automation >--------------- [INFO] Building rdkb-automation 0.0.1-SNAPSHOT [INFO] --------------------------------[ jar ]--------------------------------- [INFO] [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ rdkb-automation --- [INFO] Deleting /var/lib/jenkins/workspace/JOB1/target [INFO] [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ rdkb-automation --- [INFO] Using 'UTF-8' encoding to copy filtered resources. [INFO] Copying 3 resources [INFO] [INFO] --- maven-compiler-plugin:3.11.0:compile (default-compile) @ rdkb-automation --- [INFO] No sources to compile [INFO] [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ rdkb-automation --- [INFO] Using 'UTF-8' encoding to copy filtered resources. [INFO] skip non existing resourceDirectory /var/lib/jenkins/workspace/JOB1/src/test/resources [INFO] [INFO] --- maven-compiler-plugin:3.11.0:testCompile (default-testCompile) @ rdkb-automation --- [INFO] Changes detected - recompiling the module! :source [INFO] Compiling 119 source files with javac [debug target 11] to target/test-classes [INFO] /var/lib/jenkins/workspace/JOB1/src/test/java/com/automatics/rdkb/tests/docsis/BroadBandDocsisTest.java: /var/lib/jenkins/workspace/JOB1/src/test/java/com/automatics/rdkb/tests/docsis/BroadBandDocsisTest.java uses unchecked or unsafe operations. [INFO] /var/lib/jenkins/workspace/JOB1/src/test/java/com/automatics/rdkb/tests/docsis/BroadBandDocsisTest.java: Recompile with -Xlint:unchecked for details. [INFO] [INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ rdkb-automation --- [INFO] Tests are skipped. [INFO] [INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ rdkb-automation --- [INFO] Building jar: /var/lib/jenkins/workspace/JOB1/target/rdkb-automation-0.0.1-SNAPSHOT.jar [INFO] [INFO] --- maven-install-plugin:2.4:install (default-install) @ rdkb-automation --- [INFO] Installing /var/lib/jenkins/workspace/JOB1/target/rdkb-automation-0.0.1-SNAPSHOT.jar to /root/.m2/repository/RDKMAutomationRDKBTests/rdkb-automation/0.0.1-SNAPSHOT/rdkb-automation-0.0.1-SNAPSHOT.jar [INFO] Installing /var/lib/jenkins/workspace/JOB1/pom.xml to /root/.m2/repository/RDKMAutomationRDKBTests/rdkb-automation/0.0.1-SNAPSHOT/rdkb-automation-0.0.1-SNAPSHOT.pom [INFO] [INFO] >>> exec-maven-plugin:1.2.1:java (default-cli) > validate @ rdkb-automation >>> [INFO] [INFO] <<< exec-maven-plugin:1.2.1:java (default-cli) < validate @ rdkb-automation <<< [INFO] [INFO] [INFO] --- exec-maven-plugin:1.2.1:java (default-cli) @ rdkb-automation --- 14:00:48,554 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 14:00:48,558 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdOutAppender] 14:00:48,577 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@7:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]] 14:00:48,578 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@8:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]] 14:00:48,578 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 14:00:48,629 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 14:00:48,653 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [deviceSiftingAppender] 14:00:48,655 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@18:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]] 14:00:48,655 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@19:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]] 14:00:48,657 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 14:00:48,659 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 14:00:48,659 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [TraceLogger] 14:00:48,659 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 14:00:48,660 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 14:00:48,660 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SerialTrace] 14:00:48,660 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 14:00:48,660 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 14:00:48,660 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [crash-analysis] 14:00:48,661 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 14:00:48,662 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.apache.http] to false 14:00:48,662 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache.http level set to INFO 14:00:48,662 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[org.apache.http] 14:00:48,662 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ConnectionTrace] to TRACE 14:00:48,662 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ConnectionTrace] to false 14:00:48,662 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [TraceLogger] to Logger[ConnectionTrace] 14:00:48,662 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [SerialTrace] to TRACE 14:00:48,662 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [SerialTrace] to false 14:00:48,662 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SerialTrace] to Logger[SerialTrace] 14:00:48,662 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [crash-analysis] to DEBUG 14:00:48,663 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [crash-analysis] to false 14:00:48,663 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [crash-analysis] to Logger[crash-analysis] 14:00:48,663 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdOutAppender] to Logger[ROOT] 14:00:48,663 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[ROOT] 14:00:48,663 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 14:00:48,663 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@3ea23d02 - Registering current configuration as safe fallback point [INFO][2024-08-19 14:00:48,665][[com.automatics.executor.Starter.main()] - Starting the execution process for the given test cases. 14:00:48,666 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 14:00:48,668 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-test-suite] 14:00:48,669 |-INFO in ch.qos.logback.core.FileAppender[FILE-test-suite] - File property is set to [target/logs/test-suite.log] [INFO][2024-08-19 14:00:48,742][[com.automatics.executor.Starter.main()] - Initializing application context partner-applicationContext.xml [INFO][2024-08-19 14:00:49,814][[com.automatics.executor.Starter.main()] - Initializing application context applicationContext.xml [INFO][2024-08-19 14:00:49,993][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.145:8080/AutomaticsProps/automatics.properties [INFO][2024-08-19 14:00:50,264][[com.automatics.executor.Starter.main()] - Automatics Response - {"message":"Data already fetched from Automatics by http:\/\/192.168.2.231:8080\/job\/python_JOB1\/31 for this job id. Will not be provided again."} [INFO][2024-08-19 14:00:50,280][[com.automatics.executor.Starter.main()] - ***************** Automatics BUILD PARAMETERS ***************** [INFO][2024-08-19 14:00:50,299][[com.automatics.executor.Starter.main()] - message | Data already fetched from Automatics by http://192.168.2.231:8080/job/python_JOB1/31 for this job id. Will not be provided again. [INFO][2024-08-19 14:00:50,300][[com.automatics.executor.Starter.main()] - Shut Down Hook Attached. [INFO][2024-08-19 14:00:50,362][[com.automatics.executor.Starter.main()] - Start Execution Test Suite [INFO][2024-08-19 14:00:50,363][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured. [INFO][2024-08-19 14:00:50,364][[com.automatics.executor.Starter.main()] - Cleaning the trace log directory [INFO][2024-08-19 14:00:50,569][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.145:8080/AutomaticsProps/automatics.properties [INFO][2024-08-19 14:00:50,578][[com.automatics.executor.Starter.main()] - >>>[INIT]: Validating config files [INFO][2024-08-19 14:00:50,579][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.145:8080/AutomaticsProps/deviceProps.json [INFO][2024-08-19 14:00:51,164][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.145:8080/AutomaticsProps/deviceProps.json [INFO][2024-08-19 14:00:51,179][[com.automatics.executor.Starter.main()] - frameworkSupportedModels=Rpi-RDKB,Rpi-RDKV, [INFO][2024-08-19 14:00:51,179][[com.automatics.executor.Starter.main()] - rdkvGWModels= [INFO][2024-08-19 14:00:51,180][[com.automatics.executor.Starter.main()] - rdkvCLModels=Rpi-RDKV, [INFO][2024-08-19 14:00:51,180][[com.automatics.executor.Starter.main()] - rdkbModels=Rpi-RDKB, [INFO][2024-08-19 14:00:51,181][[com.automatics.executor.Starter.main()] - rdkcModels= [INFO][2024-08-19 14:00:51,234][[com.automatics.executor.Starter.main()] - Reading implementation from core for deviceProvider [INFO][2024-08-19 14:00:51,272][[com.automatics.executor.Starter.main()] - Is Account based test: false [INFO][2024-08-19 14:00:51,275][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details [INFO][2024-08-19 14:00:53,304][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8C:13 Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/getDeviceDetails [INFO][2024-08-19 14:00:54,636][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.99","hostIp6Address":null,"clientIpAddress":"192.168.2.99","model":"Rpi-RDKB","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"D8:3A:DD:13:8C:13","mtaMacAddress":"D8:3A:DD:13:8C:13","mtaIpAddress":"","ecmMacAddress":"D8:3A:DD:13:8C:13","ecmIpAddress":"192.168.2.99","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.60","password":"root123","connectedClients":"192.168.2.81","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","connectionType":"SSH","nodePort":"22","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null} WARNING: An illegal reflective access operation has occurred WARNING: Illegal reflective access by com.fasterxml.jackson.databind.util.ClassUtil (file:/root/.m2/repository/com/fasterxml/jackson/core/jackson-databind/2.4.1/jackson-databind-2.4.1.jar) to field java.time.format.DateTimeFormatter.locale WARNING: Please consider reporting this to the maintainers of com.fasterxml.jackson.databind.util.ClassUtil WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations WARNING: All illegal access operations will be denied in a future release [INFO][2024-08-19 14:00:54,934][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details [INFO][2024-08-19 14:00:54,935][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model Rpi-RDKB [INFO][2024-08-19 14:00:54,935][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model Rpi-RDKB [INFO][2024-08-19 14:00:54,935][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model Rpi-RDKB to automatics model Rpi-RDKB [INFO][2024-08-19 14:00:54,936][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device [INFO][2024-08-19 14:00:54,947][[pool-2-thread-1] - INIT-{} Check if device already lockedD8:3A:DD:13:8C:13 [INFO][2024-08-19 14:00:54,958][[pool-2-thread-1] - Fetching lock status for device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/device/allocationStatus [INFO][2024-08-19 14:00:54,968][[pool-2-thread-1] - Response: {"allocationStatus":"AVAILABLE"} [INFO][2024-08-19 14:00:54,970][[pool-2-thread-1] - DeviceConfig allocation status [INFO][2024-08-19 14:00:54,970][[pool-2-thread-1] - [INIT LOG] : Locking device D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:00:54,971][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Locking device [INFO][2024-08-19 14:00:54,978][[pool-2-thread-1] - Locking device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/device/lock [INFO][2024-08-19 14:00:55,028][[pool-2-thread-1] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"} [INFO][2024-08-19 14:00:55,031][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Lock success [INFO][2024-08-19 14:00:55,032][[pool-2-thread-1] - Setting access mechanism for device D8:3A:DD:13:8C:13 SSH [INFO][2024-08-19 14:00:55,032][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Setting access method SSH [INFO][2024-08-19 14:00:55,032][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required [INFO][2024-08-19 14:00:55,032][[pool-2-thread-1] - Setting accessibility check required to false for device D8:3A:DD:13:8C:13 from device config [INFO][2024-08-19 14:00:55,033][[pool-2-thread-1] - Accessibility check required for device D8:3A:DD:13:8C:13 false [INFO][2024-08-19 14:00:55,033][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required is false [INFO][2024-08-19 14:00:55,033][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Assuming device is accessible [INFO][2024-08-19 14:00:55,033][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Is device accessible true [INFO][2024-08-19 14:00:55,033][[pool-2-thread-1] - [INIT LOG] : Wiring device.D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:00:55,033][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Performing provider wiring [INFO][2024-08-19 14:00:55,034][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 PowerProvider wiring [INFO][2024-08-19 14:00:55,034][[pool-2-thread-1] - Reading implementation from core for powerProvider [INFO][2024-08-19 14:00:55,035][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 DeviceConfig Connection Based TraceProvider wiring [INFO][2024-08-19 14:00:55,044][[pool-2-thread-1] - Reading implementation from partner for deviceConnectionProvider [INFO][2024-08-19 14:00:55,047][[pool-2-thread-1] - Additional trace support : null [INFO][2024-08-19 14:00:55,047][[pool-2-thread-1] - settopObj.getModel() = Rpi-RDKB [INFO][2024-08-19 14:00:55,048][[pool-2-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue [INFO][2024-08-19 14:00:55,050][[pool-2-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log [INFO][2024-08-19 14:00:55,051][[pool-2-thread-1] - Crash Analysis not enabled during trace monitoring [INFO][2024-08-19 14:00:55,051][[pool-2-thread-1] - Serial based trace to be initialized: false [INFO][2024-08-19 14:00:55,052][[pool-2-thread-1] - [INIT LOG] : Adding dut to locked list [INFO][2024-08-19 14:00:55,052][[pool-2-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:00:55,052][[pool-2-thread-1] - Dut Added to locked list D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:00:57,944][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads [INFO][2024-08-19 14:00:58,260][[com.automatics.executor.Starter.main()] - Collecting dut info [INFO][2024-08-19 14:00:58,321][[com.automatics.executor.Starter.main()] - Requesting data from http://192.168.2.59:8084/api/rack/service/getmasterconfig?configName=TEST_TYPE_MAP [INFO][2024-08-19 14:00:58,464][[com.automatics.executor.Starter.main()] - {"configName":"TEST_TYPE_MAP","configValue":"QUICK=qt,QUICK_CI=qt,FAST_QUICK=fast_qt,FAST_QUICK_CI=fast_qt,1HOUR=1h,2DAYS=2d,2DAYS_L2=2d_L2,2DAYS_L3=2d_L3,2DAYS_L4=2d_L4,4HOUR=4h,4HOUR_L2=4h_L2,4HOUR_L3=4h_L3,4HOUR_L4=4h_L4,CI=ci,COMPONENT=GROUP_OR_AUTOID,QT=qt,CI_QT=qt,1H=1h,4H=4h,2D=2d,PERFORMANCE=PERFORMANCE,SANITY=1h,SANITY_XI3=1h,SMOKE=4h,SMOKE_L2=4h_L2,SMOKE_L3=4h_L2,SMOKE_L4=4h_L2,SMOKE_XI3=4h,FUNCTIONAL=2d,FUNCTIONAL_L2=2d_L2,FUNCTIONAL_L3=2d_L3,FUNCTIONAL_L4=2d_L4,FUNCTIONAL_XI3=2d","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:45.000+00:00","updatedUser":"root@localhost"} ... ... TestNG 7.0.1 by Cédric Beust (cedric@beust.com) ... [INFO][2024-08-19 14:01:09,654][[com.automatics.executor.Starter.main()] - AutomaticsTapApi instance not available. Creating new instance [INFO][2024-08-19 14:01:09,654][[com.automatics.executor.Starter.main()] - Creating new instance for AutomaticsTapApi [INFO][2024-08-19 14:01:09,654][[com.automatics.executor.Starter.main()] - Reading implementation from partner for deviceConnectionProvider [INFO][2024-08-19 14:01:10,238][[com.automatics.executor.Starter.main()] - >>>[BEFORE_SUITE]: Perform before suite initialization [INFO][2024-08-19 14:01:10,239][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured. [INFO][2024-08-19 14:01:10,239][[com.automatics.executor.Starter.main()] - Adding locked devices to dut object in AutomaticsTestBase [INFO][2024-08-19 14:01:10,239][[com.automatics.executor.Starter.main()] - Locked Settops: 1 [INFO][2024-08-19 14:01:10,241][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Verifying if build loaded in device as expected [INFO][2024-08-19 14:01:10,241][[pool-6-thread-1] - [BEFORE-SUITE:]Expected build in device is rdkb-generic-broadband-image_TDK_nightly-20240603-kirkstone_20240603030301 [INFO][2024-08-19 14:01:10,241][[pool-6-thread-1] - [BEFORE-SUITE:]Verifying if build changed before test [INFO][2024-08-19 14:01:10,241][[pool-6-thread-1] - Skipping build change verification as partner specific initialization is not configured. [INFO][2024-08-19 14:01:10,242][[pool-6-thread-1] - [BEFORE-SUITE:]Build Change Status: NO_CHANGE [INFO][2024-08-19 14:01:10,243][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Build in device is as expected [INFO][2024-08-19 14:01:10,244][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Setting appropritate build appender based on executionMode [INFO][2024-08-19 14:01:10,358][[pool-6-thread-1] - Skipping setting of execution mode in device as partner specific initialization is not configured. [INFO][2024-08-19 14:01:10,359][[pool-6-thread-1] - INIT-D8:3A:DD:13:8C:13 Perform before suite initialization [INFO][2024-08-19 14:01:10,359][[pool-6-thread-1] - Skipping partner specific before suite initialization as it is not configured. [INFO][2024-08-19 14:01:10,359][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Starting device connection trace [INFO][2024-08-19 14:01:10,359][[pool-6-thread-1] - Starting trace with command : tail -F -n 0 /rdklogs/logs/* [INFO][2024-08-19 14:01:10,407][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Starting reading..D83ADD138C13settop_trace.log, 14:01:10,408 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 14:01:10,408 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-D83ADD138C13settop_trace.log] 14:01:10,409 |-INFO in ch.qos.logback.core.FileAppender[FILE-D83ADD138C13settop_trace.log] - File property is set to [target/settoptrace/D83ADD138C13settop_trace.log] [INFO][2024-08-19 14:01:10,410][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Going to connect device for trace monitoring .....! [INFO][2024-08-19 14:01:10,410][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP Address : 192.168.2.99 [INFO][2024-08-19 14:01:10,411][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP6 Address : null [INFO][2024-08-19 14:01:10,411][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host MAC Address : D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:01:10,411][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside Non RDKV client device [INFO][2024-08-19 14:01:10,411][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - getConnection method invoked [INFO][2024-08-19 14:01:10,481][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:01:10,481][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1 [INFO][2024-08-19 14:01:10,515][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Reading server-config.xml from /var/lib/jenkins/workspace/testrepo/server-config.xml [INFO][2024-08-19 14:01:10,644][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set [INFO][2024-08-19 14:01:11,250][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - About to create SSH connection to DeviceIP:192.168.2.99 [INFO][2024-08-19 14:01:11,251][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:01:11,251][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1 [INFO][2024-08-19 14:01:11,264][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set [INFO][2024-08-19 14:01:11,418][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Inside sendTraceCommand [INFO][2024-08-19 14:01:16,433][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:192.168.2.99 [INFO][2024-08-19 14:01:16,436][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Received response: [INFO][2024-08-19 14:01:16,437][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connectiongateway iscom.automatics.providers.connection.SshConnection@392b7b27 [INFO][2024-08-19 14:01:16,437][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - inside null != connectionGateway condition [INFO][2024-08-19 14:01:16,437][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - gateWayDeviceInputStream iscom.jcraft.jsch.Channel$MyPipedInputStream@6d972d9e [INFO][2024-08-19 14:01:16,439][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside else block [INFO][2024-08-19 14:01:16,439][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked [INFO][2024-08-19 14:01:16,439][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Entered into readOutputFromChannel method [INFO][2024-08-19 14:01:16,439][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Is RDKV Client trace false [INFO][2024-08-19 14:01:16,465][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:01:16,465][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1 [INFO][2024-08-19 14:01:16,479][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set [INFO][2024-08-19 14:01:16,615][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established [INFO][2024-08-19 14:01:16,615][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked [INFO][2024-08-19 14:01:16,615][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:01:16,616][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1 [INFO][2024-08-19 14:01:16,630][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set [INFO][2024-08-19 14:01:16,753][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null [INFO][2024-08-19 14:01:16,754][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute: [INFO][2024-08-19 14:01:16,754][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE [INFO][2024-08-19 14:01:16,757][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: [INFO][2024-08-19 14:01:16,905][[PollingThread_26_(D8:3A:DD:13:8C:13)] - <=========================== RESPONSE =======================> CONNECTION_IS_ALIVE <=============================================================> [INFO][2024-08-19 14:01:16,905][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@1ba53477 [INFO][2024-08-19 14:01:16,926][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected [INFO][2024-08-19 14:01:40,407][[pool-6-thread-1] - Is trace required for connected gateway: false [INFO][2024-08-19 14:01:40,408][[pool-6-thread-1] - Is trace required for connected gateway: false [INFO][2024-08-19 14:01:43,243][[com.automatics.executor.Starter.main()] - Starting testing on class: org.testng.TestRunner [INFO][2024-08-19 14:01:43,327][[com.automatics.executor.Starter.main()] - Doing initialization for D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:01:43,365][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Perform before method initialization D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:01:43,366][[TestNG-PoolService-0] - Setting dattime in patter yyyy-MM-dd HH:mm:ss [INFO][2024-08-19 14:01:43,449][[TestNG-PoolService-0] - startTime 2024-08-19 08:31:43 [INFO][2024-08-19 14:01:43,449][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Sending test exection start time to Automatics D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:01:43,457][[TestNG-PoolService-0] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm [INFO][2024-08-19 14:01:43,494][[TestNG-PoolService-0] - {"jobId":456,"automationId":"TC-RDKB-HARV-5006","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":1724056303457,"endDateTimeEST":0} [INFO][2024-08-19 14:01:43,761][[TestNG-PoolService-0] - Capture execution time - Response : HTTP/1.1 200 [INFO][2024-08-19 14:01:43,762][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Skipping partner specific before method initialization as it is not configured. 14:01:43,762 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 14:01:43,762 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-Rpi-RDKB-D83ADD138C13] 14:01:43,763 |-INFO in ch.qos.logback.core.FileAppender[FILE-Rpi-RDKB-D83ADD138C13] - File property is set to [target/logs/Rpi-RDKB-D83ADD138C13.log] [INFO][2024-08-19 14:01:43,764][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Extending allocation before method for testType : GROUP_OR_AUTOID D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:01:43,764][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Get the locked status: false [INFO][2024-08-19 14:01:43,765][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false [INFO][2024-08-19 14:01:43,765][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false [INFO][2024-08-19 14:01:43,782][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - STARTED - testHarvesterInterfaceDevicesWifiStats - com.automatics.device.Device@1ba53477 [INFO][2024-08-19 14:01:43,783][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Getting MDC = D83ADD138C13settop_trace.log [INFO][2024-08-19 14:01:43,783][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Getting MDC = D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:01:43,793][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:43,793][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STARTING TEST CASE:TC-RDKB-HARV-5006 [INFO][2024-08-19 14:01:43,794][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TEST DESCRIPTION:Verify the Wifi Interface Devices Harvester Report generation with log messages. [INFO][2024-08-19 14:01:43,794][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TEST STEPS : [INFO][2024-08-19 14:01:43,794][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 1. Verify starting the Harvester Process in DEBUG Mode. [INFO][2024-08-19 14:01:43,794][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 2. Verify enabling the Interface Devices WiFi Report. [INFO][2024-08-19 14:01:43,794][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 3 Verify the report generation & event notification to Parodus with log message [INFO][2024-08-19 14:01:43,795][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 4 Verify the Parodus receiving event with log message. [INFO][2024-08-19 14:01:43,795][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 5 Verify the Avro Pack log message for starting the radio traffic report. [INFO][2024-08-19 14:01:43,795][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 6 Verify the Avro Pack log message for the number of elements. [INFO][2024-08-19 14:01:43,795][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 7 Verify the Avro Pack log message for starting the report writer. [INFO][2024-08-19 14:01:43,796][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 8 Verify the Avro Pack log message for preparing to serialize data. [INFO][2024-08-19 14:01:43,796][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 9 Verify the Avro Pack log message for reading the schema file. [INFO][2024-08-19 14:01:43,796][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 10 Verify the Avro Pack log message for ending the report writer. [INFO][2024-08-19 14:01:43,796][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 11 Verify the Avro Pack log message for setting the interface devices wifi report. [INFO][2024-08-19 14:01:43,796][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 12 Verify the Avro Pack log message for serialized data size. [INFO][2024-08-19 14:01:43,797][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - 13 Verify the Avro Pack log message for starting the radio traffic report. [INFO][2024-08-19 14:01:43,797][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:43,827][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STARTING METHOD : getCurrentTimeStampOnDevice() [INFO][2024-08-19 14:01:43,827][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:01:43,828][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:01:43,828][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:01:43,828][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:01:43,849][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:01:44,012][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: date +%y%m%d-%H:%M:%S [INFO][2024-08-19 14:01:44,012][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:01:44,137][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> 240819-08:31:44 <=============================================================> [INFO][2024-08-19 14:01:44,137][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: 240819-08:31:44 [INFO][2024-08-19 14:01:44,137][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:01:46,138][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ENDING METHOD : getCurrentTimeStampOnDevice() [INFO][2024-08-19 14:01:46,138][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:46,140][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 1 : DESCRIPTION : VERIFY STARTING THE HARVESTER PROCESS IN DEBUG MODE. [INFO][2024-08-19 14:01:46,140][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 1 : ACTION : EXECUTE COMMAND /usr/bin/harvester -subsys eRT. -DEBUG -LOGFILE /tmp/harvester_log.txt [INFO][2024-08-19 14:01:46,140][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 1 : EXPECTED: HARVESTER PROCESS MUST BE STARTED IN DEBUG MODE. [INFO][2024-08-19 14:01:46,140][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:46,168][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:01:46,168][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:01:46,168][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:01:46,168][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:01:46,182][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:01:46,308][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: pidof "harvester" [INFO][2024-08-19 14:01:46,308][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:01:46,438][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> 465091 <=============================================================> [INFO][2024-08-19 14:01:46,438][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: 465091 [INFO][2024-08-19 14:01:46,438][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:01:46,439][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - PID of 'harvester' is: 465091 [INFO][2024-08-19 14:01:46,442][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 1 : ACTUAL : HARVESTER PROCESS STARTED IN DEBUG MODE WITH PID: null [INFO][2024-08-19 14:01:46,443][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:46,444][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S1][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:01:46,455][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:01:46,456][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Fetching device property: FIRMWARE_VERSION [INFO][2024-08-19 14:01:46,468][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Fetching device props for D8:3A:DD:13:8C:13 for props [FIRMWARE_VERSION] Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/getDeviceProps [INFO][2024-08-19 14:01:46,491][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Response: {"mac":"D8:3A:DD:13:8C:13","HEAD_END":null,"FIRMWARE_VERSION":"RPI-RDKB","ECM_IP_ADDRESS":null,"ESTB_IP_ADDRESS":null} [INFO][2024-08-19 14:01:46,491][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Getting value for property: FIRMWARE_VERSION [INFO][2024-08-19 14:01:46,492][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Value: RPI-RDKB [INFO][2024-08-19 14:01:46,492][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Device property name: FIRMWARE_VERSION value obtained: RPI-RDKB [INFO][2024-08-19 14:01:46,500][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S1","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":true,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:01:46,529][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:01:46,529][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:01:46,529][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:46,530][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 2 : DESCRIPTION : VERIFY ENABLING INTERFACE DEVICES WIFI HARVESTER REPORT. [INFO][2024-08-19 14:01:46,530][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 2 : ACTION : ENABLE INTERFACE DEVICES WIFI REPORT WITH WEBPA Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled [INFO][2024-08-19 14:01:46,531][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 2 : EXPECTED: INTERFACE DEVICES WIFI HARVESTER REPORT MUST BE ENABLED. [INFO][2024-08-19 14:01:46,531][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:46,538][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - WebPa data type: BOOLEAN [INFO][2024-08-19 14:01:46,539][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TR181 parametrs status code added 0 [INFO][2024-08-19 14:01:46,539][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null [INFO][2024-08-19 14:01:46,539][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TR181 Access Method going to use: DMCLI [INFO][2024-08-19 14:01:46,540][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled bool true [INFO][2024-08-19 14:01:46,541][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.99 [INFO][2024-08-19 14:01:46,541][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:01:46,541][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:01:46,554][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:01:46,663][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: dmcli eRT setv Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled bool true [INFO][2024-08-19 14:01:46,664][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:01:46,786][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. setv from/to component(eRT.com.cisco.spvtg.ccsp.Harvester): Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled Execution succeed. <=============================================================> [INFO][2024-08-19 14:01:46,786][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.99 [INFO][2024-08-19 14:01:46,787][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. setv from/to component(eRT.com.cisco.spvtg.ccsp.Harvester): Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled Execution succeed. [INFO][2024-08-19 14:01:46,787][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. setv from/to component(eRT.com.cisco.spvtg.ccsp.Harvester): Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled Execution succeed. [INFO][2024-08-19 14:01:46,787][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TR181 Response : {Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled=Success} [INFO][2024-08-19 14:01:46,787][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success [INFO][2024-08-19 14:01:46,788][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - RESPONSE CODE: 0 [INFO][2024-08-19 14:01:46,788][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - RESPONSE MESSAGE: success [INFO][2024-08-19 14:01:46,803][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - WEBPA PARAM - Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled SET WITH VALUE - true IS SUCCESSFUL: true [INFO][2024-08-19 14:01:46,804][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Status of setting WebPaParameter values: true [INFO][2024-08-19 14:01:46,804][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null [INFO][2024-08-19 14:01:46,804][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TR181 Access Method going to use: DMCLI [INFO][2024-08-19 14:01:46,804][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TR181 Access Method: DMCLI [INFO][2024-08-19 14:01:46,805][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Going to find protocol specific names [INFO][2024-08-19 14:01:46,805][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Going to execute commands [INFO][2024-08-19 14:01:46,805][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.99 [INFO][2024-08-19 14:01:46,805][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:01:46,806][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:01:46,822][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:01:46,940][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: dmcli eRT getv Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled [INFO][2024-08-19 14:01:46,940][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:01:47,063][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.Harvester): Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled Execution succeed. Parameter 1 name: Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled type: bool, value: true <=============================================================> [INFO][2024-08-19 14:01:47,063][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.99 [INFO][2024-08-19 14:01:47,063][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.Harvester): Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled Execution succeed. Parameter 1 name: Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled type: bool, value: true [INFO][2024-08-19 14:01:47,063][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.Harvester): Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled Execution succeed. Parameter 1 name: Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled type: bool, value: true [INFO][2024-08-19 14:01:47,063][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Dmcli param value : true [INFO][2024-08-19 14:01:47,064][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled, protocolSpecificParamName=Device.X_RDKCENTRAL-COM_Report.InterfaceDevicesWifi.Enabled, value=true, datatype=null, statusCode=0] [INFO][2024-08-19 14:01:47,064][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - valueToBePassed: true [INFO][2024-08-19 14:01:47,064][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - response: true [INFO][2024-08-19 14:01:47,065][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Pattern Found = true [INFO][2024-08-19 14:01:47,065][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - true [INFO][2024-08-19 14:01:47,065][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - is patter found for true in target string = true [INFO][2024-08-19 14:01:47,065][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Status of getting WebPaParameter values: true [INFO][2024-08-19 14:01:47,066][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 2 : ACTUAL : ENABLED INTERFACE DEVICES WIFI HARVESTER REPORT USING WEBPA. [INFO][2024-08-19 14:01:47,066][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:47,066][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S2][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:01:47,066][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:01:47,066][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S2","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":true,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:01:47,078][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:01:47,078][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:01:47,078][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:47,079][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 3 : DESCRIPTION : VERIFY REPORT GENERATION & NOTIFICATION TO PARODUS WITH LOG MESSAGE. [INFO][2024-08-19 14:01:47,079][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 3 : ACTION : LOG MESSAGE TO BE VERIFIED: " Sent message successfully to parodus" [INFO][2024-08-19 14:01:47,079][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 3 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:01:47,079][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:01:47,081][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - COMMAND TO BE EXECUTED: grep -i " Sent message successfully to parodus" /rdklogs/logs/Harvesterlog.txt.0|tail -1 [INFO][2024-08-19 14:01:47,081][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:01:47,081][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:01:47,081][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:01:47,081][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:01:47,094][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:01:47,226][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i " Sent message successfully to parodus" /rdklogs/logs/Harvesterlog.txt.0|tail -1 [INFO][2024-08-19 14:01:47,226][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:01:47,348][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> <=============================================================> [INFO][2024-08-19 14:01:47,348][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: [INFO][2024-08-19 14:01:47,348][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:01:47,352][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SEARCH RESPONSE FOR - " Sent message successfully to parodus" IN THE LOG FILE - /rdklogs/logs/Harvesterlog.txt.0 IS : [INFO][2024-08-19 14:01:47,353][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - dateResponse: 240819-08:31:44 [INFO][2024-08-19 14:01:47,353][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - searchResponse: [INFO][2024-08-19 14:01:47,354][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - CAPTURED TIMESTAMP (DATE INSTANCE): Mon Aug 19 08:31:44 IST 2024 [INFO][2024-08-19 14:01:47,354][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG FILE TIMESTAMP AFTER EXTRACTING(strLogMessageDateTime): null [INFO][2024-08-19 14:01:47,354][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG MESSAGE TIMESTAMP (DATE INSTANCE):: null [INFO][2024-08-19 14:01:47,355][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG VALIDATION (USING TIMESTAMP) RESULT: false [INFO][2024-08-19 14:01:47,355][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - IS LOG MESSAGE RECENT: false [INFO][2024-08-19 14:01:47,355][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - RECENT LOG SEARCH RESPONSE: null [INFO][2024-08-19 14:01:47,357][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Waiting for 30 seconds [INFO][2024-08-19 14:02:16,927][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked [INFO][2024-08-19 14:02:16,927][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:16,927][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:16,940][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set [INFO][2024-08-19 14:02:17,074][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established [INFO][2024-08-19 14:02:17,074][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked [INFO][2024-08-19 14:02:17,074][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:17,074][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:17,087][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set [INFO][2024-08-19 14:02:17,202][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null [INFO][2024-08-19 14:02:17,202][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute: [INFO][2024-08-19 14:02:17,202][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE [INFO][2024-08-19 14:02:17,202][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:17,324][[PollingThread_26_(D8:3A:DD:13:8C:13)] - <=========================== RESPONSE =======================> CONNECTION_IS_ALIVE <=============================================================> [INFO][2024-08-19 14:02:17,324][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@1ba53477 [INFO][2024-08-19 14:02:17,325][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected [INFO][2024-08-19 14:02:17,357][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - COMMAND TO BE EXECUTED: grep -i " Sent message successfully to parodus" /rdklogs/logs/Harvesterlog.txt.0|tail -1 [INFO][2024-08-19 14:02:17,358][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:17,358][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:17,358][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:17,358][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:17,371][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:17,520][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i " Sent message successfully to parodus" /rdklogs/logs/Harvesterlog.txt.0|tail -1 [INFO][2024-08-19 14:02:17,521][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:17,643][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> <=============================================================> [INFO][2024-08-19 14:02:17,643][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: [INFO][2024-08-19 14:02:17,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:17,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SEARCH RESPONSE FOR - " Sent message successfully to parodus" IN THE LOG FILE - /rdklogs/logs/Harvesterlog.txt.0 IS : [INFO][2024-08-19 14:02:17,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - dateResponse: 240819-08:31:44 [INFO][2024-08-19 14:02:17,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - searchResponse: [INFO][2024-08-19 14:02:17,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - CAPTURED TIMESTAMP (DATE INSTANCE): Mon Aug 19 08:31:44 IST 2024 [INFO][2024-08-19 14:02:17,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG FILE TIMESTAMP AFTER EXTRACTING(strLogMessageDateTime): null [INFO][2024-08-19 14:02:17,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG MESSAGE TIMESTAMP (DATE INSTANCE):: null [INFO][2024-08-19 14:02:17,645][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG VALIDATION (USING TIMESTAMP) RESULT: false [INFO][2024-08-19 14:02:17,645][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - IS LOG MESSAGE RECENT: false [INFO][2024-08-19 14:02:17,645][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - RECENT LOG SEARCH RESPONSE: null [INFO][2024-08-19 14:02:17,645][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Waiting for 30 seconds [INFO][2024-08-19 14:02:47,645][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - COMMAND TO BE EXECUTED: grep -i " Sent message successfully to parodus" /rdklogs/logs/Harvesterlog.txt.0|tail -1 [INFO][2024-08-19 14:02:47,646][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:47,646][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:47,646][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:47,646][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:47,659][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:47,792][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i " Sent message successfully to parodus" /rdklogs/logs/Harvesterlog.txt.0|tail -1 [INFO][2024-08-19 14:02:47,792][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:47,915][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> 240819-08:32:42.055513 [mod=Harvester, lvl=INFO] [tid=563996] Sent message successfully to parodus <=============================================================> [INFO][2024-08-19 14:02:47,915][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: 240819-08:32:42.055513 [mod=Harvester, lvl=INFO] [tid=563996] Sent message successfully to parodus [INFO][2024-08-19 14:02:47,915][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:47,916][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SEARCH RESPONSE FOR - " Sent message successfully to parodus" IN THE LOG FILE - /rdklogs/logs/Harvesterlog.txt.0 IS : 240819-08:32:42.055513 [mod=Harvester, lvl=INFO] [tid=563996] Sent message successfully to parodus [INFO][2024-08-19 14:02:47,916][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - dateResponse: 240819-08:31:44 [INFO][2024-08-19 14:02:47,916][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - searchResponse: 240819-08:32:42.055513 [mod=Harvester, lvl=INFO] [tid=563996] Sent message successfully to parodus [INFO][2024-08-19 14:02:47,916][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - CAPTURED TIMESTAMP (DATE INSTANCE): Mon Aug 19 08:31:44 IST 2024 [INFO][2024-08-19 14:02:47,916][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG FILE TIMESTAMP AFTER EXTRACTING(strLogMessageDateTime): 240819-08:32:42.055513 [INFO][2024-08-19 14:02:47,916][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG MESSAGE TIMESTAMP (DATE INSTANCE):: Mon Aug 19 08:32:42 IST 2024 [INFO][2024-08-19 14:02:47,917][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG VALIDATION (USING TIMESTAMP) RESULT: true [INFO][2024-08-19 14:02:47,917][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - IS LOG MESSAGE RECENT: true [INFO][2024-08-19 14:02:47,917][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - RECENT LOG SEARCH RESPONSE: 240819-08:32:42.055513 [mod=Harvester, lvl=INFO] [tid=563996] Sent message successfully to parodus [INFO][2024-08-19 14:02:47,918][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 3 : ACTUAL : VERIFIED THE LOG MESSAGE FOR REPORT GENERATION & PARODUS NOTIFICATION EVENT. [INFO][2024-08-19 14:02:47,918][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:47,918][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S3][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:47,919][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:47,919][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S3","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:47,945][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:47,945][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:47,946][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:47,946][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 4 : DESCRIPTION : VERIFY PARODUS RECEIVING EVENT WITH LOG MESSAGE. [INFO][2024-08-19 14:02:47,946][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 4 : ACTION : LOG MESSAGE TO BE VERIFIED: "Received upstream event data: dest '.*raw.kestrel.reports.InterfaceDevicesWifi'" [INFO][2024-08-19 14:02:47,947][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 4 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:47,947][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:47,947][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - command to be executed to get device properties is cat /etc/device.properties | grep [INFO][2024-08-19 14:02:47,948][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:47,948][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:47,948][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:47,948][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:47,982][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:48,135][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: cat /etc/device.properties | grep ATOM_SYNC [INFO][2024-08-19 14:02:48,136][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:48,258][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> <=============================================================> [INFO][2024-08-19 14:02:48,258][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: [INFO][2024-08-19 14:02:48,258][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:48,258][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:48,258][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:48,258][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:48,258][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:48,270][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:48,403][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: cat /etc/device.properties | grep ATOM_SYNC [INFO][2024-08-19 14:02:48,403][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:48,525][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> <=============================================================> [INFO][2024-08-19 14:02:48,525][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: [INFO][2024-08-19 14:02:48,525][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:48,525][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:48,525][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:48,525][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:48,525][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:48,540][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:48,675][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: cat /etc/device.properties | grep ATOM_SYNC [INFO][2024-08-19 14:02:48,675][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:48,797][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> <=============================================================> [INFO][2024-08-19 14:02:48,797][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: [INFO][2024-08-19 14:02:48,797][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:48,798][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - COMMAND TO BE EXECUTED: grep -i "Received upstream event data: dest '.*raw.kestrel.reports.InterfaceDevicesWifi'" /rdklogs/logs/PARODUSlog.txt.0|tail -1 [INFO][2024-08-19 14:02:48,798][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:48,798][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:48,798][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:48,798][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:48,810][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:48,947][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "Received upstream event data: dest '.*raw.kestrel.reports.InterfaceDevicesWifi'" /rdklogs/logs/PARODUSlog.txt.0|tail -1 [INFO][2024-08-19 14:02:48,948][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:49,070][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> 240819-08:32:42.055889 [mod=PARODUS, lvl=INFO] [tid=4538] PARODUS: Received upstream event data: dest 'event:raw.kestrel.reports.InterfaceDevicesWifi' <=============================================================> [INFO][2024-08-19 14:02:49,070][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: 240819-08:32:42.055889 [mod=PARODUS, lvl=INFO] [tid=4538] PARODUS: Received upstream event data: dest 'event:raw.kestrel.reports.InterfaceDevicesWifi' [INFO][2024-08-19 14:02:49,070][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:49,070][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SEARCH RESPONSE FOR - "Received upstream event data: dest '.*raw.kestrel.reports.InterfaceDevicesWifi'" IN THE LOG FILE - /rdklogs/logs/PARODUSlog.txt.0 IS : 240819-08:32:42.055889 [mod=PARODUS, lvl=INFO] [tid=4538] PARODUS: Received upstream event data: dest 'event:raw.kestrel.reports.InterfaceDevicesWifi' [INFO][2024-08-19 14:02:49,070][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - dateResponse: 240819-08:31:44 [INFO][2024-08-19 14:02:49,071][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - searchResponse: 240819-08:32:42.055889 [mod=PARODUS, lvl=INFO] [tid=4538] PARODUS: Received upstream event data: dest 'event:raw.kestrel.reports.InterfaceDevicesWifi' [INFO][2024-08-19 14:02:49,071][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - CAPTURED TIMESTAMP (DATE INSTANCE): Mon Aug 19 08:31:44 IST 2024 [INFO][2024-08-19 14:02:49,071][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG FILE TIMESTAMP AFTER EXTRACTING(strLogMessageDateTime): 240819-08:32:42.055889 [INFO][2024-08-19 14:02:49,071][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG MESSAGE TIMESTAMP (DATE INSTANCE):: Mon Aug 19 08:32:42 IST 2024 [INFO][2024-08-19 14:02:49,071][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - LOG VALIDATION (USING TIMESTAMP) RESULT: true [INFO][2024-08-19 14:02:49,071][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - IS LOG MESSAGE RECENT: true [INFO][2024-08-19 14:02:49,071][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - RECENT LOG SEARCH RESPONSE: 240819-08:32:42.055889 [mod=PARODUS, lvl=INFO] [tid=4538] PARODUS: Received upstream event data: dest 'event:raw.kestrel.reports.InterfaceDevicesWifi' [INFO][2024-08-19 14:02:49,072][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 4 : ACTUAL : VERIFIED THE LOG MESSAGE FOR PARODUS RECEIVING EVENT. [INFO][2024-08-19 14:02:49,072][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:49,072][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S4][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:49,072][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:49,073][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S4","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:49,085][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:49,085][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:49,086][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:49,086][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 5 : DESCRIPTION : VERIFY AVRO PACK LOG MESSAGE - ENTER ASSOCIATED DEVICES REPORT. [INFO][2024-08-19 14:02:49,086][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 5 : ACTION : LOG MESSAGE TO BE VERIFIED: "Harvester harvester_report_associateddevices : ENTER" [INFO][2024-08-19 14:02:49,086][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 5 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:49,086][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:49,086][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:49,087][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:49,087][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:49,087][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:49,099][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:49,241][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "Harvester harvester_report_associateddevices : ENTER" /tmp/harvester_log.txt [INFO][2024-08-19 14:02:49,241][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:49,363][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER <=============================================================> [INFO][2024-08-19 14:02:49,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:242: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : ENTER [INFO][2024-08-19 14:02:49,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:49,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 5 : ACTUAL : VERIFIED THE AVRO PACK LOG MESSAGE - ENTER ASSOCIATED DEVICES REPORT. [INFO][2024-08-19 14:02:49,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:49,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S5][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:49,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:49,365][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S5","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:49,377][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:49,377][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:49,377][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:49,377][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 6 : DESCRIPTION : VERIFY AVRO PACK LOG MESSAGE - NUMBER OF ELEMENTS. [INFO][2024-08-19 14:02:49,377][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 6 : ACTION : LOG MESSAGE TO BE VERIFIED: "numElements = " [INFO][2024-08-19 14:02:49,377][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 6 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:49,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:49,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:49,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:49,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:49,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:49,390][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:49,516][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "numElements = " /tmp/harvester_log.txt [INFO][2024-08-19 14:02:49,517][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:49,643][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 2 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 1 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 1 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 1 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 2 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 2 <=============================================================> [INFO][2024-08-19 14:02:49,643][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 2 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 1 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 1 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 1 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 2 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:248: RDK_LOG_DEBUG, numElements = 2 [INFO][2024-08-19 14:02:49,643][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:49,643][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 6 : ACTUAL : VERIFIED THE AVRO PACK LOG MESSAGE - NUMBER OF ELEMENTS. [INFO][2024-08-19 14:02:49,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:49,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S6][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:49,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:49,644][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S6","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:49,656][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:49,656][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:49,656][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:49,656][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 7 : DESCRIPTION : VERIFY AVRO PACK LOG MESSAGE - ENTER REPORT WRITER. [INFO][2024-08-19 14:02:49,657][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 7 : ACTION : LOG MESSAGE TO BE VERIFIED: "Harvester prepare_writer : ENTER" [INFO][2024-08-19 14:02:49,657][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 7 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:49,657][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:49,657][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:49,657][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:49,657][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:49,657][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:49,668][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:49,901][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "Harvester prepare_writer : ENTER" /tmp/harvester_log.txt [INFO][2024-08-19 14:02:49,902][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:50,024][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER <=============================================================> [INFO][2024-08-19 14:02:50,024][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:136: RDK_LOG_DEBUG, Harvester prepare_writer : ENTER [INFO][2024-08-19 14:02:50,024][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:50,025][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 7 : ACTUAL : VERIFIED THE AVRO PACK LOG MESSAGE - ENTER REPORT WRITER. [INFO][2024-08-19 14:02:50,025][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,025][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S7][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:50,025][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:50,025][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S7","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:50,036][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:50,036][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:50,037][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,037][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 8 : DESCRIPTION : VERIFY AVRO PACK LOG MESSAGE - PREPARE SERIALIZE DATA. [INFO][2024-08-19 14:02:50,037][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 8 : ACTION : LOG MESSAGE TO BE VERIFIED: "Avro prepares to serialize data" [INFO][2024-08-19 14:02:50,037][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 8 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:50,037][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,037][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:50,038][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:50,038][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:50,038][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:50,080][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:50,194][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "Avro prepares to serialize data" /tmp/harvester_log.txt [INFO][2024-08-19 14:02:50,195][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:50,317][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data <=============================================================> [INFO][2024-08-19 14:02:50,317][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:137: RDK_LOG_DEBUG, Avro prepares to serialize data [INFO][2024-08-19 14:02:50,317][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:50,318][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 8 : ACTUAL : VERIFIED THE AVRO PACK LOG MESSAGE - PREPARE SERIALIZE DATA. [INFO][2024-08-19 14:02:50,318][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,318][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S8][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:50,318][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:50,318][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S8","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:50,333][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:50,333][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:50,333][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,334][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 9 : DESCRIPTION : VERIFY AVRO PACK LOG MESSAGE - READ SCHEMA FILE. [INFO][2024-08-19 14:02:50,334][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 9 : ACTION : LOG MESSAGE TO BE VERIFIED: "Read Avro schema file" [INFO][2024-08-19 14:02:50,334][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 9 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:50,335][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,335][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:50,335][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:50,335][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:50,336][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:50,352][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:50,509][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "Read Avro schema file" /tmp/harvester_log.txt [INFO][2024-08-19 14:02:50,509][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:50,632][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:184: RDK_LOG_DEBUG, Read Avro schema file ONCE, lSize = 15913, pbuffer = 0x7f940052b0. ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:184: RDK_LOG_DEBUG, Read Avro schema file ONCE, lSize = 15913, pbuffer = 0x7f94005320. <=============================================================> [INFO][2024-08-19 14:02:50,632][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:184: RDK_LOG_DEBUG, Read Avro schema file ONCE, lSize = 15913, pbuffer = 0x7f940052b0. ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:184: RDK_LOG_DEBUG, Read Avro schema file ONCE, lSize = 15913, pbuffer = 0x7f94005320. [INFO][2024-08-19 14:02:50,632][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:50,633][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 9 : ACTUAL : VERIFIED THE AVRO PACK LOG MESSAGE - READ SCHEMA FILE. [INFO][2024-08-19 14:02:50,633][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,633][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S9][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:50,633][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:50,633][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S9","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:50,647][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:50,647][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:50,647][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,648][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 10 : DESCRIPTION : VERIFY AVRO PACK LOG MESSAGE - EXIT REPORT WRITER. [INFO][2024-08-19 14:02:50,648][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 10 : ACTION : LOG MESSAGE TO BE VERIFIED: "Harvester prepare_writer : EXIT" [INFO][2024-08-19 14:02:50,648][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 10 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:50,648][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,648][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:50,648][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:50,648][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:50,648][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:50,659][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:50,800][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "Harvester prepare_writer : EXIT" /tmp/harvester_log.txt [INFO][2024-08-19 14:02:50,800][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:50,922][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT <=============================================================> [INFO][2024-08-19 14:02:50,922][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:212: RDK_LOG_DEBUG, Harvester prepare_writer : EXIT [INFO][2024-08-19 14:02:50,922][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:50,923][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 10 : ACTUAL : VERIFIED THE AVRO PACK LOG MESSAGE - EXIT REPORT WRITER. [INFO][2024-08-19 14:02:50,923][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,923][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S10][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:50,923][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:50,924][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S10","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:50,936][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:50,936][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:50,936][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,936][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 11 : DESCRIPTION : VERIFY AVRO PACK LOG MESSAGE - SET DESTINATION. [INFO][2024-08-19 14:02:50,936][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 11 : ACTION : LOG MESSAGE TO BE VERIFIED: "event:raw.kestrel.reports.InterfaceDevicesWifi" [INFO][2024-08-19 14:02:50,936][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 11 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:50,936][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:50,937][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:50,937][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:50,937][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:50,937][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:50,948][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:51,056][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "event:raw.kestrel.reports.InterfaceDevicesWifi" /tmp/harvester_log.txt [INFO][2024-08-19 14:02:51,057][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:51,099][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=0fb825d7-c2d6-4dd3-9a06-ca6f832243d3 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=d812fe5c-5c11-45df-bd73-6d1fd9c131c1 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=1f161479-bc72-4dca-8494-7bd21d91fee8 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=042f16e8-3fa2-4c10-8804-2568dbb1a6a5 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=2a0e4b76-2a82-45b9-9843-9f5513b1e892 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=12da6e4e-3515-44ed-bae6-e22fea83761c <=============================================================> [INFO][2024-08-19 14:02:51,100][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=0fb825d7-c2d6-4dd3-9a06-ca6f832243d3 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=d812fe5c-5c11-45df-bd73-6d1fd9c131c1 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=1f161479-bc72-4dca-8494-7bd21d91fee8 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=042f16e8-3fa2-4c10-8804-2568dbb1a6a5 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=2a0e4b76-2a82-45b9-9843-9f5513b1e892 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:902: RDK_LOG_DEBUG, dest: event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:113: RDK_LOG_DEBUG, dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/webpa_interface.c:151: RDK_LOG_DEBUG, wrp_msg->u.event.dest :event:raw.kestrel.reports.InterfaceDevicesWifi ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:909: RDK_LOG_WARN, InterfaceDevicesWifi report sent to Webpa, Destination=event:raw.kestrel.reports.InterfaceDevicesWifi, Transaction-Id=12da6e4e-3515-44ed-bae6-e22fea83761c [INFO][2024-08-19 14:02:51,100][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:51,100][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 11 : ACTUAL : VERIFIED THE AVRO PACK LOG MESSAGE - SET DESTINATION. [INFO][2024-08-19 14:02:51,100][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:51,100][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S11][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:51,100][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:51,101][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S11","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:51,114][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:51,114][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:51,114][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:51,114][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 12 : DESCRIPTION : VERIFY AVRO PACK LOG MESSAGE - SERIALIZED DATA SIZE. [INFO][2024-08-19 14:02:51,114][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 12 : ACTION : LOG MESSAGE TO BE VERIFIED: "AvroSerializedSize" [INFO][2024-08-19 14:02:51,115][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 12 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:51,115][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:51,115][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:51,115][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:51,115][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:51,115][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:51,127][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:51,241][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "AvroSerializedSize" /tmp/harvester_log.txt [INFO][2024-08-19 14:02:51,241][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:51,363][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 257 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 172 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 172 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 172 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 257 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 342 <=============================================================> [INFO][2024-08-19 14:02:51,363][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 257 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 172 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 172 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 172 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 257 ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:906: RDK_LOG_DEBUG, AvroSerializedSize: 342 [INFO][2024-08-19 14:02:51,363][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:51,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 12 : ACTUAL : VERIFIED THE AVRO PACK LOG MESSAGE - SERIALIZED DATA SIZE. [INFO][2024-08-19 14:02:51,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:51,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S12][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:51,364][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:51,365][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S12","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:51,377][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:51,377][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:51,377][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:51,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 13 : DESCRIPTION : VERIFY AVRO PACK LOG MESSAGE - EXIT ASSOCIATED DEVICES REPORT. [INFO][2024-08-19 14:02:51,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 13 : ACTION : LOG MESSAGE TO BE VERIFIED: "Harvester harvester_report_radiotraffic : EXIT" [INFO][2024-08-19 14:02:51,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 13 : EXPECTED: LOG MESSAGE MUST BE PRESENT. [INFO][2024-08-19 14:02:51,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:51,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entering executeCommandUsingSsh method [INFO][2024-08-19 14:02:51,378][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.99 [INFO][2024-08-19 14:02:51,379][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSH Host IP : 192.168.2.99 [INFO][2024-08-19 14:02:51,379][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - SSh connection attempet : 1 [INFO][2024-08-19 14:02:51,391][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Default SSH custom port is set [INFO][2024-08-19 14:02:51,554][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Executing command: grep -i "Harvester harvester_report_associateddevices : EXIT" /tmp/harvester_log.txt [INFO][2024-08-19 14:02:51,554][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Conn is not null sendRecive: [INFO][2024-08-19 14:02:51,676][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - <=========================== RESPONSE =======================> ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT <=============================================================> [INFO][2024-08-19 14:02:51,676][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Received response: ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT ../../../../../../../../../rdkb/components/generic/harvester/source/HarvesterSsp/harvester_associated_devices_avropack.c:913: RDK_LOG_DEBUG, Harvester harvester_report_associateddevices : EXIT [INFO][2024-08-19 14:02:51,676][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exiting executeCommandUsingSsh method [INFO][2024-08-19 14:02:51,677][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - STEP : 13 : ACTUAL : VERIFIED THE AVRO PACK LOG MESSAGE - EXIT ASSOCIATED DEVICES REPORT. [INFO][2024-08-19 14:02:51,677][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ********************************************************************************** [INFO][2024-08-19 14:02:51,677][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-HARV-506] [step Number : S13][ Execution status : PASS] [Error Message : ] [INFO][2024-08-19 14:02:51,677][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Entered into updateExecutionStatus method [INFO][2024-08-19 14:02:51,678][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-HARV-506","stepNumber":"S13","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-HARV-5006"} [ERROR][2024-08-19 14:02:51,690][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id [INFO][2024-08-19 14:02:51,690][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Exited from updateExecutionStatus method [INFO][2024-08-19 14:02:51,690][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - ENDING TEST CASE: TC-RDKB-HARV-5006 [INFO][2024-08-19 14:02:51,735][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - >>>[AFTER_METHOD]: Clear device trace buffer for D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:02:51,740][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-HARV-5006/D83ADD138C13/settoptrace/ [INFO][2024-08-19 14:02:51,765][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - created a new directory true [INFO][2024-08-19 14:02:51,766][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - New trace location from the strings /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log [INFO][2024-08-19 14:02:51,767][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Writing stated from line [INFO][2024-08-19 14:02:51,985][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Mon Aug 19 14:02:51 IST 2024 [INFO][2024-08-19 14:02:51,985][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-HARV-5006/D83ADD138C13/logs/ [INFO][2024-08-19 14:02:51,986][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - created a new directory true [INFO][2024-08-19 14:02:51,986][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Destination Summary file doesn't exist. Creating one! [INFO][2024-08-19 14:02:51,986][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Writing stated from line [INFO][2024-08-19 14:02:52,057][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Mon Aug 19 14:02:52 IST 2024 [INFO][2024-08-19 14:02:52,057][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - >>>[AFTER_METHOD]: Sending test execution completion time for D8:3A:DD:13:8C:13 [INFO][2024-08-19 14:02:52,057][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm [INFO][2024-08-19 14:02:52,057][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - {"jobId":456,"automationId":"TC-RDKB-HARV-5006","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":0,"endDateTimeEST":1724056372057} [INFO][2024-08-19 14:02:52,067][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Capture execution time - Response : HTTP/1.1 200 [INFO][2024-08-19 14:02:52,067][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Restarting Trace in after method [INFO][2024-08-19 14:02:52,067][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Is trace required for connected gateway: false [INFO][2024-08-19 14:02:52,067][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Is trace required for connected gateway: false [INFO][2024-08-19 14:02:52,068][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Going for if build changed : Test Type : GROUP_OR_AUTOID [INFO][2024-08-19 14:02:52,068][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Skipping build change verification as partner specific initialization is not configured. [INFO][2024-08-19 14:02:52,068][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - >>>[AFTER_METHOD]: Build Change Status: NO_CHANGE [INFO][2024-08-19 14:02:52,068][D8:3A:DD:13:8C:13[TC-RDKB-HARV-5006 : D83ADD138C13] - Skipping parter specific after method clean up as it is not configured. ===== Invoked methods AutomaticsTestBase.performBeforeSuiteInit(org.testng.ITestContext)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadbandConnectedDeviceListTest@17cd40cd]org.testng.TestRunner@1051ecac 399327437 AutomaticsTestBase.performBeforeMethodInit([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadbandConnectedDeviceListTest@17cd40cd][Ljava.lang.Object;@7fb23341 [TestResult name={null} status=CREATED method=BroadbandConnectedDeviceListTest.testHarvesterInterfaceDevicesWifiStats(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadbandConnectedDeviceListTest@17cd40cd] output={null}] 399327437 BroadbandConnectedDeviceListTest.testHarvesterInterfaceDevicesWifiStats(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadbandConnectedDeviceListTest@17cd40cd]com.automatics.device.Device@1ba53477 399327437 AutomaticsTestBase.performAfterMethodCleanup([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadbandConnectedDeviceListTest@17cd40cd][Ljava.lang.Object;@7fb23341 [TestResult name=testHarvesterInterfaceDevicesWifiStats status=SUCCESS method=BroadbandConnectedDeviceListTest.testHarvesterInterfaceDevicesWifiStats(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadbandConnectedDeviceListTest@17cd40cd] output={null}] 399327437 ===== PASSED: testHarvesterInterfaceDevicesWifiStats(com.automatics.device.Device@1ba53477) =============================================== MyTest Tests run: 1, Failures: 0, Skips: 0 =============================================== [INFO][2024-08-19 14:02:52,383][[com.automatics.executor.Starter.main()] - Finished testing class: org.testng.TestRunner [INFO][2024-08-19 14:02:52,385][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Performing after suite cleanup [INFO][2024-08-19 14:02:52,385][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured. [INFO][2024-08-19 14:02:52,385][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Locked devices after suite execution 1 [INFO][2024-08-19 14:02:52,385][[com.automatics.executor.Starter.main()] - Value of intial testType : QUICK [INFO][2024-08-19 14:02:52,385][[com.automatics.executor.Starter.main()] - Value of TestType provided by job : GROUP_OR_AUTOID [INFO][2024-08-19 14:02:52,385][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Verifying if build changed after test [INFO][2024-08-19 14:02:52,386][[com.automatics.executor.Starter.main()] - Skipping build change verification as partner specific initialization is not configured. [INFO][2024-08-19 14:02:52,386][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Build Change Status: NO_CHANGE [INFO][2024-08-19 14:02:52,386][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false [ERROR][2024-08-19 14:02:52,386][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Sleep interrupted sleep interrupted [INFO][2024-08-19 14:02:52,421][[com.automatics.executor.Starter.main()] - Connection instance is Null [INFO][2024-08-19 14:02:52,421][[com.automatics.executor.Starter.main()] - Connection instance is Null [INFO][2024-08-19 14:02:52,422][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false [INFO][2024-08-19 14:02:52,422][[com.automatics.executor.Starter.main()] - Skipping parter specific after suite clean up as it is not configured. [ERROR][2024-08-19 14:02:52,439][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - readOutputFromChannel - Exception Details ------ java.io.InterruptedIOException: null at java.base/java.io.PipedInputStream.read(PipedInputStream.java:328) at java.base/java.io.PipedInputStream.read(PipedInputStream.java:377) at java.base/sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:284) at java.base/sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:326) at java.base/sun.nio.cs.StreamDecoder.read(StreamDecoder.java:178) at java.base/java.io.InputStreamReader.read(InputStreamReader.java:181) at java.base/java.io.BufferedReader.fill(BufferedReader.java:161) at java.base/java.io.BufferedReader.readLine(BufferedReader.java:326) at java.base/java.io.BufferedReader.readLine(BufferedReader.java:392) at com.automatics.providers.trace.AbstractTraceProviderImpl.readOutputFromChannel(AbstractTraceProviderImpl.java:820) at com.automatics.providers.trace.AbstractTraceProviderImpl.connectAndRead(AbstractTraceProviderImpl.java:734) at com.automatics.providers.trace.AbstractTraceProviderImpl$ConnectionThread.run(AbstractTraceProviderImpl.java:1135) [INFO][2024-08-19 14:02:52,440][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null [INFO][2024-08-19 14:02:52,440][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null [INFO][2024-08-19 14:02:52,440][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Exited from readOutputFromChannel method [INFO][2024-08-19 14:02:52,443][[com.automatics.executor.Starter.main()] - Releasing device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/device/release [INFO][2024-08-19 14:02:52,495][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"} [INFO][2024-08-19 14:02:52,496][[com.automatics.executor.Starter.main()] - Successfully unlocked RDKB (D8:3A:DD:13:8C:13) mapped components - [INFO][2024-08-19 14:02:52,497][[com.automatics.executor.Starter.main()] - SETTOP - D8:3A:DD:13:8C:13 RELEASED. [INFO][2024-08-19 14:02:52,498][[com.automatics.executor.Starter.main()] - [AFTER-SUITE:]JSON message to Automatics: {"status":"COMPLETED","service":"","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1724056270239,"completionTime":1724056372497,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}} [INFO][2024-08-19 14:02:52,510][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [INFO][2024-08-19 14:01:43,782][456][D8:3A:DD:13:8C:13|TestNG-PoolService-0|com.automatics.executor.AutomaticsTestListener:onTestStart:110] STARTED - testHarvesterInterfaceDevicesWifiStats - com.automatics.device.Device@1ba53477 [INFO][2024-08-19 14:02:52,510][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [DEBUG][2024-08-19 14:01:43,783][456][D8:3A:DD:13:8C:13|TC-RDKB-HARV-5006 : D83ADD138C13|com.automatics.executor.AutomaticsTestListener:onTestStart:150] Started appending log =============================================== MySuite Total tests run: 1, Passes: 1, Failures: 0, Skips: 0 =============================================== [INFO][2024-08-19 14:02:52,725][[com.automatics.executor.Starter.main()] - Updating final execution status to Automatics [INFO][2024-08-19 14:02:52,726][[Thread-78] - ============= MESSAGE SEND TO AUTOMATICS ============== [INFO][2024-08-19 14:02:52,726][[Thread-78] - Tested build name : RPI-RDKB [INFO][2024-08-19 14:02:52,726][[Thread-78] - Final execution status : COMPLETED [INFO][2024-08-19 14:02:52,727][[Thread-78] - Final successful device list : ["D8:3A:DD:13:8C:13"] [INFO][2024-08-19 14:02:52,727][[Thread-78] - Job Id : 456 [INFO][2024-08-19 14:02:52,727][[Thread-78] - ============================================================== [INFO][2024-08-19 14:02:52,728][[Thread-78] - >>>[INIT]: Automatics JSON -> {"status":"COMPLETED","service":"","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1724056270239,"completionTime":1724056372497,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}} [INFO][2024-08-19 14:02:52,728][[Thread-78] - http://192.168.2.59:8080/Automatics/executionResponse.htm [INFO][2024-08-19 14:04:53,041][[Thread-78] - HTTP STATUS LINE : HTTP/1.1 200 [INFO][2024-08-19 14:04:53,058][[Thread-78] - HTTP STATUS CODE : 200 [INFO][2024-08-19 14:04:53,362][[com.automatics.executor.Starter.main()] - ******** EXECUTION COMPLETED ********* [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 04:44 min [INFO] Finished at: 2024-08-19T14:04:53+05:30 [INFO] ------------------------------------------------------------------------ Inside Add Shutdown Hook [INFO][2024-08-19 14:04:53,655][[Thread-1] - Bean testInitializer is not configured. [INFO][2024-08-19 14:04:53,657][[Thread-1] - Closing partner application context [INFO][2024-08-19 14:04:53,732][[Thread-1] - Closing core application context [JOB1] $ /bin/sh -xe /tmp/jenkins15667896142902299915.sh + /var/lib/jenkins/pre-test-config.sh -g com.automatics.providers -a rpi-provider-impl -v 0.0.1-SNAPSHOT Mode: RDKB Dependency already exist. Dependency already exist. Adding dependency com.automatics.providersrpi-provider-impl0.0.1-SNAPSHOT Finished updating pom.xml Archiving artifacts Finished: SUCCESS