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] '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 6749
[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 6892
[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 7035
[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:26:49,978 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback-test.xml] at [jar:file:/root/.m2/repository/com/automatics/apps/automatics-core/2.24.0/automatics-core-2.24.0.jar!/logback-test.xml]
14:26:49,990 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@3203b447 - URL [jar:file:/root/.m2/repository/com/automatics/apps/automatics-core/2.24.0/automatics-core-2.24.0.jar!/logback-test.xml] is not of type file
14:26:50,104 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
14:26:50,107 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdOutAppender]
14:26:50,113 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@7:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]]
14:26:50,114 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@8:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]]
14:26:50,114 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:26:50,130 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
14:26:50,132 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [deviceSiftingAppender]
14:26:50,133 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@18:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]]
14:26:50,133 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@19:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]]
14:26:50,135 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
14:26:50,138 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
14:26:50,139 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [TraceLogger]
14:26:50,139 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
14:26:50,139 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
14:26:50,139 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SerialTrace]
14:26:50,139 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
14:26:50,140 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
14:26:50,140 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [crash-analysis]
14:26:50,140 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
14:26:50,141 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.apache.http] to false
14:26:50,141 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache.http level set to INFO
14:26:50,141 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[org.apache.http]
14:26:50,141 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ConnectionTrace] to TRACE
14:26:50,142 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ConnectionTrace] to false
14:26:50,142 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [TraceLogger] to Logger[ConnectionTrace]
14:26:50,142 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [SerialTrace] to TRACE
14:26:50,142 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [SerialTrace] to false
14:26:50,142 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SerialTrace] to Logger[SerialTrace]
14:26:50,142 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [crash-analysis] to DEBUG
14:26:50,142 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [crash-analysis] to false
14:26:50,142 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [crash-analysis] to Logger[crash-analysis]
14:26:50,142 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdOutAppender] to Logger[ROOT]
14:26:50,142 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[ROOT]
14:26:50,142 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
14:26:50,143 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@1dbe3fcf - Registering current configuration as safe fallback point
[INFO][2024-09-23 14:26:50,144][[com.automatics.executor.Starter.main()] - Starting the execution process for the given test cases.
14:26:50,146 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
14:26:50,148 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-test-suite]
14:26:50,150 |-INFO in ch.qos.logback.core.FileAppender[FILE-test-suite] - File property is set to [target/logs/test-suite.log]
[INFO][2024-09-23 14:26:50,166][[com.automatics.executor.Starter.main()] - Initializing application context partner-applicationContext.xml
[INFO][2024-09-23 14:26:50,454][[com.automatics.executor.Starter.main()] - Initializing application context applicationContext.xml
[INFO][2024-09-23 14:26:50,495][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.145:8080/AutomaticsProps/automatics.properties
[INFO][2024-09-23 14:26:50,544][[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-09-23 14:26:50,547][[com.automatics.executor.Starter.main()] -
***************** Automatics BUILD PARAMETERS *****************
[INFO][2024-09-23 14:26:50,554][[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-09-23 14:26:50,555][[com.automatics.executor.Starter.main()] - Shut Down Hook Attached.
[INFO][2024-09-23 14:26:50,559][[com.automatics.executor.Starter.main()] - Start Execution Test Suite
[INFO][2024-09-23 14:26:50,560][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-09-23 14:26:50,560][[com.automatics.executor.Starter.main()] - Cleaning the trace log directory
[INFO][2024-09-23 14:26:50,582][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.145:8080/AutomaticsProps/automatics.properties
[INFO][2024-09-23 14:26:50,587][[com.automatics.executor.Starter.main()] - >>>[INIT]: Validating config files
[INFO][2024-09-23 14:26:50,588][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.145:8080/AutomaticsProps/deviceProps.json
[INFO][2024-09-23 14:26:50,744][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.145:8080/AutomaticsProps/deviceProps.json
[INFO][2024-09-23 14:26:50,751][[com.automatics.executor.Starter.main()] - frameworkSupportedModels=Rpi-RDKB,Rpi-RDKV,
[INFO][2024-09-23 14:26:50,752][[com.automatics.executor.Starter.main()] - rdkvGWModels=
[INFO][2024-09-23 14:26:50,752][[com.automatics.executor.Starter.main()] - rdkvCLModels=Rpi-RDKV,
[INFO][2024-09-23 14:26:50,752][[com.automatics.executor.Starter.main()] - rdkbModels=Rpi-RDKB,
[INFO][2024-09-23 14:26:50,753][[com.automatics.executor.Starter.main()] - rdkcModels=
[INFO][2024-09-23 14:26:50,766][[com.automatics.executor.Starter.main()] - Reading implementation from core for deviceProvider
[INFO][2024-09-23 14:26:50,780][[com.automatics.executor.Starter.main()] - Is Account based test: false
[INFO][2024-09-23 14:26:50,783][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-09-23 14:26:51,245][[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-09-23 14:26:51,379][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.144","hostIp6Address":null,"clientIpAddress":"192.168.2.144","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.144","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.144","connectedClients":"192.168.2.81","password":"root123","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","nodePort":"22","connectionType":"SSH","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-09-23 14:26:51,415][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-09-23 14:26:51,416][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model Rpi-RDKB
[INFO][2024-09-23 14:26:51,416][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model Rpi-RDKB
[INFO][2024-09-23 14:26:51,416][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model Rpi-RDKB to automatics model Rpi-RDKB
[INFO][2024-09-23 14:26:51,417][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device
[INFO][2024-09-23 14:26:51,420][[pool-2-thread-1] - INIT-{} Check if device already lockedD8:3A:DD:13:8C:13
[INFO][2024-09-23 14:26:51,431][[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-09-23 14:26:51,441][[pool-2-thread-1] - Response: {"allocationStatus":"AVAILABLE"}
[INFO][2024-09-23 14:26:51,443][[pool-2-thread-1] - DeviceConfig allocation status
[INFO][2024-09-23 14:26:51,443][[pool-2-thread-1] - [INIT LOG] : Locking device D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:26:51,443][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Locking device
[INFO][2024-09-23 14:26:51,451][[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-09-23 14:26:51,507][[pool-2-thread-1] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-09-23 14:26:51,511][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Lock success
[INFO][2024-09-23 14:26:51,512][[pool-2-thread-1] - Setting access mechanism for device D8:3A:DD:13:8C:13 SSH
[INFO][2024-09-23 14:26:51,512][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Setting access method SSH
[INFO][2024-09-23 14:26:51,512][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required
[INFO][2024-09-23 14:26:51,513][[pool-2-thread-1] - Setting accessibility check required to false for device D8:3A:DD:13:8C:13 from device config
[INFO][2024-09-23 14:26:51,513][[pool-2-thread-1] - Accessibility check required for device D8:3A:DD:13:8C:13 false
[INFO][2024-09-23 14:26:51,513][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required is false
[INFO][2024-09-23 14:26:51,513][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Assuming device is accessible
[INFO][2024-09-23 14:26:51,513][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Is device accessible true
[INFO][2024-09-23 14:26:51,514][[pool-2-thread-1] - [INIT LOG] : Wiring device.D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:26:51,514][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Performing provider wiring
[INFO][2024-09-23 14:26:51,515][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 PowerProvider wiring
[INFO][2024-09-23 14:26:51,515][[pool-2-thread-1] - Reading implementation from core for powerProvider
[INFO][2024-09-23 14:26:51,516][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 DeviceConfig Connection Based TraceProvider wiring
[INFO][2024-09-23 14:26:51,519][[pool-2-thread-1] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-09-23 14:26:51,522][[pool-2-thread-1] - Additional trace support : null
[INFO][2024-09-23 14:26:51,522][[pool-2-thread-1] - settopObj.getModel() = Rpi-RDKB
[INFO][2024-09-23 14:26:51,522][[pool-2-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue
[INFO][2024-09-23 14:26:51,526][[pool-2-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-09-23 14:26:51,527][[pool-2-thread-1] - Crash Analysis not enabled during trace monitoring
[INFO][2024-09-23 14:26:51,528][[pool-2-thread-1] - Serial based trace to be initialized: false
[INFO][2024-09-23 14:26:51,528][[pool-2-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-09-23 14:26:51,528][[pool-2-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:26:51,529][[pool-2-thread-1] - Dut Added to locked list D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:26:54,418][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads
[INFO][2024-09-23 14:26:54,418][[com.automatics.executor.Starter.main()] - Collecting dut info
[INFO][2024-09-23 14:26:54,419][[com.automatics.executor.Starter.main()] - Requesting data from http://192.168.2.59:8084/api/rack/service/getmasterconfig?configName=TEST_TYPE_MAP
[INFO][2024-09-23 14:26:54,438][[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-09-23 14:26:55,174][[com.automatics.executor.Starter.main()] - AutomaticsTapApi instance not available. Creating new instance
[INFO][2024-09-23 14:26:55,174][[com.automatics.executor.Starter.main()] - Creating new instance for AutomaticsTapApi
[INFO][2024-09-23 14:26:55,174][[com.automatics.executor.Starter.main()] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-09-23 14:26:55,218][[com.automatics.executor.Starter.main()] - >>>[BEFORE_SUITE]: Perform before suite initialization
[INFO][2024-09-23 14:26:55,219][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-09-23 14:26:55,219][[com.automatics.executor.Starter.main()] - Adding locked devices to dut object in AutomaticsTestBase
[INFO][2024-09-23 14:26:55,219][[com.automatics.executor.Starter.main()] - Locked Settops: 1
[INFO][2024-09-23 14:26:55,220][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Verifying if build loaded in device as expected
[INFO][2024-09-23 14:26:55,221][[pool-6-thread-1] - [BEFORE-SUITE:]Expected build in device is rdkb-generic-broadband-image_rdk-next_20240801124800
[INFO][2024-09-23 14:26:55,221][[pool-6-thread-1] - [BEFORE-SUITE:]Verifying if build changed before test
[INFO][2024-09-23 14:26:55,221][[pool-6-thread-1] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-09-23 14:26:55,222][[pool-6-thread-1] - [BEFORE-SUITE:]Build Change Status: NO_CHANGE
[INFO][2024-09-23 14:26:55,222][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Build in device is as expected
[INFO][2024-09-23 14:26:55,222][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Setting appropritate build appender based on executionMode
[INFO][2024-09-23 14:26:55,226][[pool-6-thread-1] - Skipping setting of execution mode in device as partner specific initialization is not configured.
[INFO][2024-09-23 14:26:55,227][[pool-6-thread-1] - INIT-D8:3A:DD:13:8C:13 Perform before suite initialization
[INFO][2024-09-23 14:26:55,227][[pool-6-thread-1] - Skipping partner specific before suite initialization as it is not configured.
[INFO][2024-09-23 14:26:55,227][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Starting device connection trace
[INFO][2024-09-23 14:26:55,228][[pool-6-thread-1] - Starting trace with command : tail -F -n 0 /rdklogs/logs/*
[INFO][2024-09-23 14:26:55,237][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Starting reading..D83ADD138C13settop_trace.log,
14:26:55,237 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
14:26:55,238 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-D83ADD138C13settop_trace.log]
14:26:55,238 |-INFO in ch.qos.logback.core.FileAppender[FILE-D83ADD138C13settop_trace.log] - File property is set to [target/settoptrace/D83ADD138C13settop_trace.log]
[INFO][2024-09-23 14:26:55,239][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Going to connect device for trace monitoring .....!
[INFO][2024-09-23 14:26:55,239][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP Address : 192.168.2.144
[INFO][2024-09-23 14:26:55,239][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP6 Address : null
[INFO][2024-09-23 14:26:55,240][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host MAC Address : D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:26:55,240][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside Non RDKV client device
[INFO][2024-09-23 14:26:55,240][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-09-23 14:26:55,244][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:26:55,244][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-09-23 14:26:55,250][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Reading server-config.xml from /var/lib/jenkins/workspace/testrepo/server-config.xml
[INFO][2024-09-23 14:26:55,274][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-09-23 14:26:55,628][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - About to create SSH connection to DeviceIP:192.168.2.144
[INFO][2024-09-23 14:26:55,628][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:26:55,629][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-09-23 14:26:55,646][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-09-23 14:26:55,877][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Inside sendTraceCommand
[INFO][2024-09-23 14:27:00,885][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:192.168.2.144
[INFO][2024-09-23 14:27:00,889][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Received response:
[INFO][2024-09-23 14:27:00,890][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connectiongateway iscom.automatics.providers.connection.SshConnection@5e609e8f
[INFO][2024-09-23 14:27:00,891][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - inside null != connectionGateway condition
[INFO][2024-09-23 14:27:00,891][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - gateWayDeviceInputStream iscom.jcraft.jsch.Channel$MyPipedInputStream@18e989a1
[INFO][2024-09-23 14:27:00,893][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside else block
[INFO][2024-09-23 14:27:00,893][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-09-23 14:27:00,893][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Entered into readOutputFromChannel method
[INFO][2024-09-23 14:27:00,893][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Is RDKV Client trace false
[INFO][2024-09-23 14:27:00,894][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:27:00,904][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-09-23 14:27:00,922][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-09-23 14:27:01,157][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-09-23 14:27:01,157][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-09-23 14:27:01,157][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:27:01,157][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-09-23 14:27:01,174][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-09-23 14:27:01,387][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-09-23 14:27:01,387][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-09-23 14:27:01,387][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-09-23 14:27:01,390][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-09-23 14:27:01,515][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-09-23 14:27:01,515][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6aface4f
[INFO][2024-09-23 14:27:01,525][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-09-23 14:27:25,237][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-09-23 14:27:25,238][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-09-23 14:27:28,222][[com.automatics.executor.Starter.main()] - Starting testing on class: org.testng.TestRunner
[INFO][2024-09-23 14:27:28,244][[com.automatics.executor.Starter.main()] - Doing initialization for D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:27:28,260][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Perform before method initialization D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:27:28,261][[TestNG-PoolService-0] - Setting dattime in patter yyyy-MM-dd HH:mm:ss
[INFO][2024-09-23 14:27:28,263][[TestNG-PoolService-0] - startTime 2024-09-23 08:57:28
[INFO][2024-09-23 14:27:28,263][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Sending test exection start time to Automatics D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:27:28,264][[TestNG-PoolService-0] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-09-23 14:27:28,265][[TestNG-PoolService-0] - {"jobId":456,"automationId":"TC-RDKB-PARODUS-1002","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":1727081848264,"endDateTimeEST":0}
[INFO][2024-09-23 14:27:28,349][[TestNG-PoolService-0] - Capture execution time - Response : HTTP/1.1 200
[INFO][2024-09-23 14:27:28,350][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Skipping partner specific before method initialization as it is not configured.
14:27:28,350 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
14:27:28,350 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-Rpi-RDKB-D83ADD138C13]
14:27:28,351 |-INFO in ch.qos.logback.core.FileAppender[FILE-Rpi-RDKB-D83ADD138C13] - File property is set to [target/logs/Rpi-RDKB-D83ADD138C13.log]
[INFO][2024-09-23 14:27:28,351][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-09-23 14:27:28,352][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Get the locked status: false
[INFO][2024-09-23 14:27:28,352][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-09-23 14:27:28,352][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-09-23 14:27:28,361][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - STARTED - verifyDeviceManufacturerName - com.automatics.device.Device@6aface4f
[INFO][2024-09-23 14:27:28,361][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Getting MDC = D83ADD138C13settop_trace.log
[INFO][2024-09-23 14:27:28,361][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Getting MDC = D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:27:28,369][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - #######################################################################################
[INFO][2024-09-23 14:27:28,369][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STARTING TEST CASE: TC-RDKB-PARODUS-1002
[INFO][2024-09-23 14:27:28,370][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TEST DESCRIPTION: Verify the device manufacturer name in parodus log and through WebPA, both the values should be same
[INFO][2024-09-23 14:27:28,370][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TEST STEPS :
[INFO][2024-09-23 14:27:28,370][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRE-CONDITION 1:Reboot the device
[INFO][2024-09-23 14:27:28,370][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRE-CONDITION 2:Verify whether Webpa is Up and Running
[INFO][2024-09-23 14:27:28,370][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - 1: Verify the device manufacturer name using WebPA
[INFO][2024-09-23 14:27:28,370][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - 2: Verify the device manufacturer name in Parodus log file
[INFO][2024-09-23 14:27:28,371][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - #######################################################################################
[INFO][2024-09-23 14:27:28,371][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - ################### STARTING PRE-CONFIGURATIONS ###################
[INFO][2024-09-23 14:27:28,371][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRE-CONDITION STEPS
[INFO][2024-09-23 14:27:28,371][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - #######################################################################################
[INFO][2024-09-23 14:27:28,371][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRE-CONDITION 1: DESCRIPTION : Reboot the device
[INFO][2024-09-23 14:27:28,372][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRE-CONDITION 1: ACTION : Execute reboot command on the console
[INFO][2024-09-23 14:27:28,372][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRE-CONDITION 1: EXPECTED : Device is rebooted successfully
[INFO][2024-09-23 14:27:28,372][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - #######################################################################################
[INFO][2024-09-23 14:27:28,372][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STARTING METHOD: rebootAndWaitForIpAccusition()
[INFO][2024-09-23 14:27:28,373][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:27:28,389][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-09-23 14:27:28,598][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Executing command: echo test_connection;
[INFO][2024-09-23 14:27:28,598][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-09-23 14:27:28,721][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] -
<=========================== RESPONSE =======================>
test_connection
<=============================================================>
[INFO][2024-09-23 14:27:28,721][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Closing SSH connection from DeviceIP: 192.168.2.144
[INFO][2024-09-23 14:27:28,721][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Exiting method isDeviceAccessible. Status - true
[INFO][2024-09-23 14:27:28,722][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-09-23 14:27:28,722][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.144
[INFO][2024-09-23 14:27:28,722][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:27:28,722][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-09-23 14:27:28,739][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-09-23 14:27:28,948][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Executing command: /sbin/reboot
[INFO][2024-09-23 14:27:28,948][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-09-23 14:27:29,271][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] -
<=========================== RESPONSE =======================>
<=============================================================>
[INFO][2024-09-23 14:27:29,271][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Received response:
[INFO][2024-09-23 14:27:29,271][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[ERROR][2024-09-23 14:27:29,280][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Exception in waitForEstbIpAcquisition() :
com.automatics.exceptions.FailedTransitionException: RESOURCE_KEY_NOT_FOUND: Failed to find the key in the resource property file.Key not found on Automatics properties : wait.after.hard.reboot.initiated__rpi-rdkb
at com.automatics.tap.AutomaticsTapApi.getSTBPropsValue(AutomaticsTapApi.java:424)
at com.automatics.tap.AutomaticsTapApi.getWaitValue(AutomaticsTapApi.java:1953)
at com.automatics.providers.impl.DeviceAccessValidatorImpl.waitForDeviceIpAcquisition(DeviceAccessValidatorImpl.java:145)
at com.automatics.providers.impl.DeviceAccessValidatorImpl.waitForIpAcquisitionAfterReboot(DeviceAccessValidatorImpl.java:124)
at com.automatics.utils.CommonMethods.waitForEstbIpAcquisition(CommonMethods.java:4009)
at com.automatics.tap.AutomaticsTapApi.waitAfterHardRebootInitiated(AutomaticsTapApi.java:530)
at com.automatics.tap.AutomaticsTapApi.reboot(AutomaticsTapApi.java:6581)
at com.automatics.utils.CommonMethods.rebootAndWaitForIpAccusition(CommonMethods.java:4884)
at com.automatics.rdkb.tests.system.ParodusTest.verifyDeviceManufacturerName(ParodusTest.java:5069)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:134)
at org.testng.internal.TestInvoker.invokeMethod(TestInvoker.java:597)
at org.testng.internal.TestInvoker.invokeTestMethod(TestInvoker.java:173)
at org.testng.internal.TestMethodWithDataProviderMethodWorker.call(TestMethodWithDataProviderMethodWorker.java:77)
at org.testng.internal.TestMethodWithDataProviderMethodWorker.call(TestMethodWithDataProviderMethodWorker.java:15)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
[INFO][2024-09-23 14:27:29,284][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Completed execution of waitForEstbIpAcquisition() : Status : false. Time taken : 0 minutes
[INFO][2024-09-23 14:27:29,285][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:27:29,298][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Default SSH custom port is set
[ERROR][2024-09-23 14:27:29,299][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Exiting occured while running command : CONNECTION: SSH connection failure to the server.
[INFO][2024-09-23 14:27:29,300][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Exiting method isDeviceAccessible. Status - false
[INFO][2024-09-23 14:27:29,300][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - ENDING METHOD: rebootAndWaitForIpAccusition()
[INFO][2024-09-23 14:27:29,300][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRE-CONDITION 1: ACTUAL : Device is rebooted successfully
[INFO][2024-09-23 14:27:29,300][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - #######################################################################################
[INFO][2024-09-23 14:27:29,300][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRECONDITION 2:DESCRIPTION :Verify whether Webpa is Up and Running
[INFO][2024-09-23 14:27:29,300][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRECONDITION 2:ACTION:Verifying Successful webpa Get response ,in case of failure rechecking for 8 minutes
[INFO][2024-09-23 14:27:29,300][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRE-CONDITION 2 : EXPECTED : Webpa process should be Up
[INFO][2024-09-23 14:27:29,300][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - ##########################################################################
[INFO][2024-09-23 14:27:59,306][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-09-23 14:27:59,306][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-09-23 14:27:59,307][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-09-23 14:27:59,307][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-09-23 14:27:59,309][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Going to execute commands
[INFO][2024-09-23 14:27:59,310][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.144
[INFO][2024-09-23 14:27:59,310][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:27:59,310][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-09-23 14:27:59,336][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-09-23 14:28:01,526][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-09-23 14:28:01,526][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:28:01,526][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-09-23 14:28:01,540][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-09-23 14:28:11,564][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1 failed due to CONNECTION: SSH connection failure to the server. for 192.168.2.144. Trying once more..
[INFO][2024-09-23 14:28:17,380][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSh connection attempet : 1 failed due to CONNECTION: SSH connection failure to the server. for 192.168.2.144. Trying once more..
[INFO][2024-09-23 14:28:21,564][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 2
[INFO][2024-09-23 14:28:21,579][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-09-23 14:28:24,586][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 2 failed due to CONNECTION: SSH connection failure to the server. for 192.168.2.144. Trying once more..
[INFO][2024-09-23 14:28:27,381][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSh connection attempet : 2
[INFO][2024-09-23 14:28:27,395][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-09-23 14:28:27,410][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSh connection attempet : 2 failed due to CONNECTION: SSH connection failure to the server. for 192.168.2.144. Trying once more..
[INFO][2024-09-23 14:28:34,587][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 3
[INFO][2024-09-23 14:28:34,600][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-09-23 14:28:34,803][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-09-23 14:28:34,804][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-09-23 14:28:34,804][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:28:34,804][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-09-23 14:28:34,818][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-09-23 14:28:35,020][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-09-23 14:28:35,021][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-09-23 14:28:35,021][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-09-23 14:28:35,021][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-09-23 14:28:35,143][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-09-23 14:28:35,143][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6aface4f
[INFO][2024-09-23 14:28:35,143][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-09-23 14:28:37,410][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSh connection attempet : 3
[INFO][2024-09-23 14:28:37,424][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-09-23 14:28:37,605][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DeviceInfo.SerialNumber
[INFO][2024-09-23 14:28:37,606][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-09-23 14:28:37,728][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter 1 name: Device.DeviceInfo.SerialNumber
type: string, value: 10000000edcb124b
<=============================================================>
[INFO][2024-09-23 14:28:37,728][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.144
[INFO][2024-09-23 14:28:37,728][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter 1 name: Device.DeviceInfo.SerialNumber
type: string, value: 10000000edcb124b
[INFO][2024-09-23 14:28:37,728][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter 1 name: Device.DeviceInfo.SerialNumber
type: string, value: 10000000edcb124b
[INFO][2024-09-23 14:28:37,729][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Dmcli param value : 10000000edcb124b
[INFO][2024-09-23 14:28:37,729][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.SerialNumber, protocolSpecificParamName=Device.DeviceInfo.SerialNumber, value=10000000edcb124b, datatype=null, statusCode=0]
[INFO][2024-09-23 14:28:37,729][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - WEBPA PROCESS VERIFIED WITH WEBPA COMMAND EXECUTION AGAINST SAMPLE PARAMETER: true
[INFO][2024-09-23 14:28:37,730][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - PRE-CONDITION 2 : ACTUAL : Webpa is Up and Running
[INFO][2024-09-23 14:28:37,730][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - ###############################COMPLETED PRE-CONFIGURATIONS###############
[INFO][2024-09-23 14:28:37,730][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - **********************************************************************************
[INFO][2024-09-23 14:28:37,730][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STEP 1: DESCRIPTION : Verify the device manufacturer name using WebPA
[INFO][2024-09-23 14:28:37,730][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STEP 1: ACTION :Execute command: curl -H "Authorization: Bearer " -k https:///api/v2/device/mac:/config?names=Device.DeviceInfo.Manufacturer
[INFO][2024-09-23 14:28:37,730][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STEP 1: EXPECTED : Should be able to get the Device manufacturer name using WebPA Command
[INFO][2024-09-23 14:28:37,730][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - **********************************************************************************
[INFO][2024-09-23 14:28:37,747][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Platform specific resolved property key is : device.manufacturer.name._rpi-rdkb
[INFO][2024-09-23 14:28:37,747][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Manufacturer Name is : Raspberry Pi Foundation
[INFO][2024-09-23 14:28:37,748][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-09-23 14:28:37,748][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-09-23 14:28:37,748][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-09-23 14:28:37,748][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-09-23 14:28:37,749][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Going to execute commands
[INFO][2024-09-23 14:28:37,749][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.144
[INFO][2024-09-23 14:28:37,749][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:28:37,749][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-09-23 14:28:37,764][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-09-23 14:28:37,958][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DeviceInfo.Manufacturer
[INFO][2024-09-23 14:28:37,959][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-09-23 14:28:38,081][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter 1 name: Device.DeviceInfo.Manufacturer
type: string, value: Raspberry Pi Foundation
<=============================================================>
[INFO][2024-09-23 14:28:38,081][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.144
[INFO][2024-09-23 14:28:38,081][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter 1 name: Device.DeviceInfo.Manufacturer
type: string, value: Raspberry Pi Foundation
[INFO][2024-09-23 14:28:38,081][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter 1 name: Device.DeviceInfo.Manufacturer
type: string, value: Raspberry Pi Foundation
[INFO][2024-09-23 14:28:38,081][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Dmcli param value : Raspberry Pi Foundation
[INFO][2024-09-23 14:28:38,084][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.Manufacturer, protocolSpecificParamName=Device.DeviceInfo.Manufacturer, value=Raspberry Pi Foundation, datatype=null, statusCode=0]
[INFO][2024-09-23 14:28:38,084][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - deviceManufacturerName is-Raspberry Pi Foundation
[INFO][2024-09-23 14:28:38,084][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STEP 1: ACTUAL : Device Manufacturer name is Raspberry Pi Foundation
[INFO][2024-09-23 14:28:38,085][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - **********************************************************************************
[INFO][2024-09-23 14:28:38,085][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-PARODUS-102] [step Number : S1][ Execution status : PASS] [Error Message : ]
[INFO][2024-09-23 14:28:38,087][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-09-23 14:28:38,088][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Fetching device property: FIRMWARE_VERSION
[INFO][2024-09-23 14:28:38,096][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : 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-09-23 14:28:38,116][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : 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-09-23 14:28:38,116][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Getting value for property: FIRMWARE_VERSION
[INFO][2024-09-23 14:28:38,117][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Value: RPI-RDKB
[INFO][2024-09-23 14:28:38,117][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Device property name: FIRMWARE_VERSION value obtained: RPI-RDKB
[INFO][2024-09-23 14:28:38,128][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-PARODUS-102","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-PARODUS-1002"}
[ERROR][2024-09-23 14:28:38,160][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : 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-09-23 14:28:38,160][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-09-23 14:28:38,161][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - **********************************************************************************
[INFO][2024-09-23 14:28:38,161][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STEP 2: DESCRIPTION : Verify the device manufacturer name in Parodus log file
[INFO][2024-09-23 14:28:38,161][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STEP 2: ACTION : Execute command: grep -i "hw_manufacturer" /rdklogs/logs/PARODUSlog.txt.0
[INFO][2024-09-23 14:28:38,161][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STEP 2:EXPECTED : Device manufacturer name should be available on the parodus log file
[INFO][2024-09-23 14:28:38,161][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - **********************************************************************************
[INFO][2024-09-23 14:28:38,163][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - ENTERING METHOD compareDeviceManufacturerName
[INFO][2024-09-23 14:28:38,163][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-09-23 14:28:38,163][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.144
[INFO][2024-09-23 14:28:38,163][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSH Host IP : 192.168.2.144
[INFO][2024-09-23 14:28:38,163][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-09-23 14:28:38,180][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-09-23 14:28:38,376][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Executing command: grep -i "hw_manufacturer" /rdklogs/logs/PARODUSlog.txt.0
[INFO][2024-09-23 14:28:38,376][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-09-23 14:28:38,498][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] -
<=========================== RESPONSE =======================>
220428-17:42:44.664007 [mod=PARODUS, lvl=INFO] [tid=1782] PARODUS: hw_manufacturer is Raspberry
<=============================================================>
[INFO][2024-09-23 14:28:38,498][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Received response: 220428-17:42:44.664007 [mod=PARODUS, lvl=INFO] [tid=1782] PARODUS: hw_manufacturer is Raspberry
[INFO][2024-09-23 14:28:38,499][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-09-23 14:28:38,499][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - The Retrieved Device Manufacturer name: 220428-17:42:44.664007 [mod=PARODUS, lvl=INFO] [tid=1782] PARODUS: hw_manufacturer is Raspberry
[INFO][2024-09-23 14:28:38,500][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - The Retrieved Device Manufacturer name: 220428-17:42:44.664007 [mod=PARODUS, lvl=INFO] [tid=1782] PARODUS: hw_manufacturer is Raspberry
, is same as expected Manufacturer name : Raspberry Pi Foundation
[INFO][2024-09-23 14:28:38,500][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - ENDING METHOD compareDeviceManufacturerName
[INFO][2024-09-23 14:28:38,500][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - STEP 2: ACTUAL : Device manufacturer names in Parodus Log file and Webpa are same
[INFO][2024-09-23 14:28:38,500][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - **********************************************************************************
[INFO][2024-09-23 14:28:38,500][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-PARODUS-102] [step Number : S2][ Execution status : PASS] [Error Message : ]
[INFO][2024-09-23 14:28:38,500][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-09-23 14:28:38,501][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-PARODUS-102","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-PARODUS-1002"}
[ERROR][2024-09-23 14:28:38,515][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : 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-09-23 14:28:38,516][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-09-23 14:28:38,516][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - ENDING TEST CASE: TC-RDKB-PARODUS-1002
[INFO][2024-09-23 14:28:38,518][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - >>>[AFTER_METHOD]: Clear device trace buffer for D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:28:38,527][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-PARODUS-1002/D83ADD138C13/settoptrace/
[INFO][2024-09-23 14:28:38,528][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - created a new directory true
[INFO][2024-09-23 14:28:38,529][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - New trace location from the strings /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-09-23 14:28:38,530][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Writing stated from line
[INFO][2024-09-23 14:28:38,591][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Mon Sep 23 14:28:38 IST 2024
[INFO][2024-09-23 14:28:38,591][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-PARODUS-1002/D83ADD138C13/logs/
[INFO][2024-09-23 14:28:38,591][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - created a new directory true
[INFO][2024-09-23 14:28:38,591][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Destination Summary file doesn't exist. Creating one!
[INFO][2024-09-23 14:28:38,592][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Writing stated from line
[INFO][2024-09-23 14:28:38,628][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Mon Sep 23 14:28:38 IST 2024
[INFO][2024-09-23 14:28:38,628][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - >>>[AFTER_METHOD]: Sending test execution completion time for D8:3A:DD:13:8C:13
[INFO][2024-09-23 14:28:38,628][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-09-23 14:28:38,629][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - {"jobId":456,"automationId":"TC-RDKB-PARODUS-1002","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":0,"endDateTimeEST":1727081918628}
[INFO][2024-09-23 14:28:38,641][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Capture execution time - Response : HTTP/1.1 200
[INFO][2024-09-23 14:28:38,642][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Restarting Trace in after method
[INFO][2024-09-23 14:28:38,642][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-09-23 14:28:38,642][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-09-23 14:28:38,642][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Going for if build changed : Test Type : GROUP_OR_AUTOID
[INFO][2024-09-23 14:28:38,643][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-09-23 14:28:38,643][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : D83ADD138C13] - >>>[AFTER_METHOD]: Build Change Status: NO_CHANGE
[INFO][2024-09-23 14:28:38,643][D8:3A:DD:13:8C:13[TC-RDKB-PARODUS-1002 : 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.system.ParodusTest@6f5f8b3f]org.testng.TestRunner@7ae8fbde 1868532543
AutomaticsTestBase.performBeforeMethodInit([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.system.ParodusTest@6f5f8b3f][Ljava.lang.Object;@2941dd87 [TestResult name={null} status=CREATED method=ParodusTest.verifyDeviceManufacturerName(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.system.ParodusTest@6f5f8b3f] output={null}] 1868532543
ParodusTest.verifyDeviceManufacturerName(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.system.ParodusTest@6f5f8b3f]com.automatics.device.Device@6aface4f 1868532543
AutomaticsTestBase.performAfterMethodCleanup([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.system.ParodusTest@6f5f8b3f][Ljava.lang.Object;@2941dd87 [TestResult name=verifyDeviceManufacturerName status=SUCCESS method=ParodusTest.verifyDeviceManufacturerName(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.system.ParodusTest@6f5f8b3f] output={null}] 1868532543
=====
PASSED: verifyDeviceManufacturerName(com.automatics.device.Device@6aface4f)
===============================================
MyTest
Tests run: 1, Failures: 0, Skips: 0
===============================================
[INFO][2024-09-23 14:28:38,669][[com.automatics.executor.Starter.main()] - Finished testing class: org.testng.TestRunner
[INFO][2024-09-23 14:28:38,670][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Performing after suite cleanup
[INFO][2024-09-23 14:28:38,671][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-09-23 14:28:38,671][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Locked devices after suite execution 1
[INFO][2024-09-23 14:28:38,672][[com.automatics.executor.Starter.main()] - Value of intial testType : QUICK
[INFO][2024-09-23 14:28:38,672][[com.automatics.executor.Starter.main()] - Value of TestType provided by job : GROUP_OR_AUTOID
[INFO][2024-09-23 14:28:38,672][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Verifying if build changed after test
[INFO][2024-09-23 14:28:38,672][[com.automatics.executor.Starter.main()] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-09-23 14:28:38,673][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Build Change Status: NO_CHANGE
[INFO][2024-09-23 14:28:38,673][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[INFO][2024-09-23 14:28:38,673][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-09-23 14:28:38,674][[com.automatics.executor.Starter.main()] - Connection instance is Null
[ERROR][2024-09-23 14:28:38,674][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Sleep interrupted sleep interrupted
[INFO][2024-09-23 14:28:38,674][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[INFO][2024-09-23 14:28:38,674][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-09-23 14:28:38,674][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-09-23 14:28:38,674][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Exited from readOutputFromChannel method
[INFO][2024-09-23 14:28:38,675][[com.automatics.executor.Starter.main()] - Skipping parter specific after suite clean up as it is not configured.
[INFO][2024-09-23 14:28:38,680][[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-09-23 14:28:38,739][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-09-23 14:28:38,741][[com.automatics.executor.Starter.main()] - Successfully unlocked RDKB (D8:3A:DD:13:8C:13) mapped components -
[INFO][2024-09-23 14:28:38,741][[com.automatics.executor.Starter.main()] - SETTOP - D8:3A:DD:13:8C:13 RELEASED.
[INFO][2024-09-23 14:28:38,742][[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":1727081815219,"completionTime":1727081918741,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}}
[INFO][2024-09-23 14:28:38,753][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [INFO][2024-09-23 14:27:28,361][456][D8:3A:DD:13:8C:13|TestNG-PoolService-0|com.automatics.executor.AutomaticsTestListener:onTestStart:110] STARTED - verifyDeviceManufacturerName - com.automatics.device.Device@6aface4f
[INFO][2024-09-23 14:28:38,753][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [DEBUG][2024-09-23 14:27:28,361][456][D8:3A:DD:13:8C:13|TC-RDKB-PARODUS-1002 : D83ADD138C13|com.automatics.executor.AutomaticsTestListener:onTestStart:150] Started appending log
===============================================
MySuite
Total tests run: 1, Passes: 1, Failures: 0, Skips: 0
===============================================
[INFO][2024-09-23 14:28:38,800][[com.automatics.executor.Starter.main()] - Updating final execution status to Automatics
[INFO][2024-09-23 14:28:38,801][[Thread-38] - ============= MESSAGE SEND TO AUTOMATICS ==============
[INFO][2024-09-23 14:28:38,801][[Thread-38] - Tested build name : RPI-RDKB
[INFO][2024-09-23 14:28:38,801][[Thread-38] - Final execution status : COMPLETED
[INFO][2024-09-23 14:28:38,801][[Thread-38] - Final successful device list : ["D8:3A:DD:13:8C:13"]
[INFO][2024-09-23 14:28:38,801][[Thread-38] - Job Id : 456
[INFO][2024-09-23 14:28:38,801][[Thread-38] - ==============================================================
[INFO][2024-09-23 14:28:38,802][[Thread-38] - >>>[INIT]: Automatics JSON ->
{"status":"COMPLETED","service":"","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1727081815219,"completionTime":1727081918741,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}}
[INFO][2024-09-23 14:28:38,802][[Thread-38] - http://192.168.2.59:8080/Automatics/executionResponse.htm
[INFO][2024-09-23 14:30:38,934][[Thread-38] - HTTP STATUS LINE : HTTP/1.1 200
[INFO][2024-09-23 14:30:38,935][[Thread-38] - HTTP STATUS CODE : 200
[INFO][2024-09-23 14:30:38,936][[com.automatics.executor.Starter.main()] - ******** EXECUTION COMPLETED *********
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 04:04 min
[INFO] Finished at: 2024-09-23T14:30:38+05:30
[INFO] ------------------------------------------------------------------------
Inside Add Shutdown Hook
[INFO][2024-09-23 14:30:38,943][[Thread-1] - Bean testInitializer is not configured.
[INFO][2024-09-23 14:30:38,943][[Thread-1] - Closing partner application context
[INFO][2024-09-23 14:30:38,945][[Thread-1] - Closing core application context
[JOB1] $ /bin/sh -xe /tmp/jenkins5756012130219854939.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