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.12: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] [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 --- 17:45:00,347 |-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.27.0/automatics-core-2.27.0.jar!/logback-test.xml] 17:45:00,357 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@17a87382 - URL [jar:file:/root/.m2/repository/com/automatics/apps/automatics-core/2.27.0/automatics-core-2.27.0.jar!/logback-test.xml] is not of type file 17:45:00,463 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 17:45:00,465 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdOutAppender] 17:45:00,472 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@7:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]] 17:45:00,472 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@8:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]] 17:45:00,473 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 17:45:00,488 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 17:45:00,491 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [deviceSiftingAppender] 17:45:00,492 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@18:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]] 17:45:00,493 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@19:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]] 17:45:00,495 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 17:45:00,498 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 17:45:00,498 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [TraceLogger] 17:45:00,498 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 17:45:00,499 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 17:45:00,499 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SerialTrace] 17:45:00,499 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 17:45:00,499 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 17:45:00,499 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [crash-analysis] 17:45:00,499 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 17:45:00,500 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.apache.http] to false 17:45:00,500 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache.http level set to INFO 17:45:00,501 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[org.apache.http] 17:45:00,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ConnectionTrace] to TRACE 17:45:00,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ConnectionTrace] to false 17:45:00,501 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [TraceLogger] to Logger[ConnectionTrace] 17:45:00,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [SerialTrace] to TRACE 17:45:00,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [SerialTrace] to false 17:45:00,501 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SerialTrace] to Logger[SerialTrace] 17:45:00,502 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [crash-analysis] to DEBUG 17:45:00,502 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [crash-analysis] to false 17:45:00,502 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [crash-analysis] to Logger[crash-analysis] 17:45:00,502 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdOutAppender] to Logger[ROOT] 17:45:00,502 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[ROOT] 17:45:00,502 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 17:45:00,503 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@4560bf2c - Registering current configuration as safe fallback point [INFO][2024-12-05 17:45:00,504][[com.automatics.executor.Starter.main()] - Starting the execution process for the given test cases. 17:45:00,506 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 17:45:00,508 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-test-suite] 17:45:00,509 |-INFO in ch.qos.logback.core.FileAppender[FILE-test-suite] - File property is set to [target/logs/test-suite.log] [INFO][2024-12-05 17:45:00,523][[com.automatics.executor.Starter.main()] - Initializing application context partner-applicationContext.xml [INFO][2024-12-05 17:45:00,796][[com.automatics.executor.Starter.main()] - Initializing application context applicationContext.xml [INFO][2024-12-05 17:45:00,839][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.12:8080/AutomaticsProps/automatics.properties [INFO][2024-12-05 17:45:00,872][[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-12-05 17:45:00,875][[com.automatics.executor.Starter.main()] - ***************** Automatics BUILD PARAMETERS ***************** [INFO][2024-12-05 17:45:00,882][[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-12-05 17:45:00,883][[com.automatics.executor.Starter.main()] - Shut Down Hook Attached. [INFO][2024-12-05 17:45:00,886][[com.automatics.executor.Starter.main()] - Start Execution Test Suite [INFO][2024-12-05 17:45:00,887][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured. [INFO][2024-12-05 17:45:00,888][[com.automatics.executor.Starter.main()] - Cleaning the trace log directory [INFO][2024-12-05 17:45:00,911][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.12:8080/AutomaticsProps/automatics.properties [INFO][2024-12-05 17:45:00,916][[com.automatics.executor.Starter.main()] - >>>[INIT]: Validating config files [INFO][2024-12-05 17:45:00,917][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.12:8080/AutomaticsProps/deviceProps.json [INFO][2024-12-05 17:45:01,068][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.12:8080/AutomaticsProps/deviceProps.json [INFO][2024-12-05 17:45:01,074][[com.automatics.executor.Starter.main()] - frameworkSupportedModels=Rpi-RDKB,Rpi-RDKV, [INFO][2024-12-05 17:45:01,075][[com.automatics.executor.Starter.main()] - rdkvGWModels= [INFO][2024-12-05 17:45:01,075][[com.automatics.executor.Starter.main()] - rdkvCLModels=Rpi-RDKV, [INFO][2024-12-05 17:45:01,075][[com.automatics.executor.Starter.main()] - rdkbModels=Rpi-RDKB, [INFO][2024-12-05 17:45:01,076][[com.automatics.executor.Starter.main()] - rdkcModels= [INFO][2024-12-05 17:45:01,089][[com.automatics.executor.Starter.main()] - Reading implementation from core for deviceProvider [INFO][2024-12-05 17:45:01,104][[com.automatics.executor.Starter.main()] - Is Account based test: false [INFO][2024-12-05 17:45:01,107][[com.automatics.executor.Starter.main()] - INIT-D8:58:D7:00:A5:D6 Get device details [INFO][2024-12-05 17:45:01,410][[com.automatics.executor.Starter.main()] - Fetching device details for D8:58:D7:00:A5:D6 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getDeviceDetails [INFO][2024-12-05 17:45:01,640][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3839","name":"","hardwareRevision":"","hostMacAddress":"D8:58:D7:00:A5:D6","hostIp4Address":"192.168.2.3","hostIp6Address":null,"clientIpAddress":"192.168.2.3","model":"Rpi-RDKB","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"D8:58:D7:00:A5:D6","mtaMacAddress":"","mtaIpAddress":"","ecmMacAddress":"D8:58:D7:00:A5:D6","ecmIpAddress":"192.168.2.3","headend":"HE","gatewayMac":"","extraProperties":{"deviceIp":"192.168.2.3","connectedClients":"192.168.2.57","password":"root1234","ethernetMacAddress":"D8:58:D7:00:A5:D6","wi-ficapability":"Dual band","connectionType":"SSH","nodePort":"22","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123","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-12-05 17:45:01,691][[com.automatics.executor.Starter.main()] - INIT-D8:58:D7:00:A5:D6 Obtained device details [INFO][2024-12-05 17:45:01,693][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model Rpi-RDKB [INFO][2024-12-05 17:45:01,693][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model Rpi-RDKB [INFO][2024-12-05 17:45:01,693][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model Rpi-RDKB to automatics model Rpi-RDKB [INFO][2024-12-05 17:45:01,693][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device [INFO][2024-12-05 17:45:01,698][[pool-2-thread-1] - INIT-{} Check if device already lockedD8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:01,723][[pool-2-thread-1] - Fetching lock status for device D8:58:D7:00:A5:D6 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/allocationStatus [INFO][2024-12-05 17:45:01,731][[pool-2-thread-1] - Response: {"allocationStatus":"AVAILABLE"} [INFO][2024-12-05 17:45:01,753][[pool-2-thread-1] - DeviceConfig allocation status [INFO][2024-12-05 17:45:01,754][[pool-2-thread-1] - [INIT LOG] : Locking device D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:01,755][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Locking device [INFO][2024-12-05 17:45:01,772][[pool-2-thread-1] - Locking device D8:58:D7:00:A5:D6 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/lock [INFO][2024-12-05 17:45:01,832][[pool-2-thread-1] - Response: {"status":"SUCCESS","mac":"D8:58:D7:00:A5:D6"} [INFO][2024-12-05 17:45:01,846][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Lock success [INFO][2024-12-05 17:45:01,847][[pool-2-thread-1] - Setting access mechanism for device D8:58:D7:00:A5:D6 SSH [INFO][2024-12-05 17:45:01,847][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Setting access method SSH [INFO][2024-12-05 17:45:01,847][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Checking if accessibility check required [INFO][2024-12-05 17:45:01,848][[pool-2-thread-1] - Setting accessibility check required to false for device D8:58:D7:00:A5:D6 from device config [INFO][2024-12-05 17:45:01,850][[pool-2-thread-1] - Accessibility check required for device D8:58:D7:00:A5:D6 false [INFO][2024-12-05 17:45:01,850][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Checking if accessibility check required is false [INFO][2024-12-05 17:45:01,851][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Assuming device is accessible [INFO][2024-12-05 17:45:01,851][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Is device accessible true [INFO][2024-12-05 17:45:01,851][[pool-2-thread-1] - [INIT LOG] : Wiring device.D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:01,852][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Performing provider wiring [INFO][2024-12-05 17:45:01,852][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 PowerProvider wiring [INFO][2024-12-05 17:45:01,852][[pool-2-thread-1] - Reading implementation from core for powerProvider [INFO][2024-12-05 17:45:01,857][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 DeviceConfig Connection Based TraceProvider wiring [INFO][2024-12-05 17:45:01,859][[pool-2-thread-1] - Reading implementation from partner for deviceConnectionProvider [INFO][2024-12-05 17:45:01,865][[pool-2-thread-1] - Additional trace support : null [INFO][2024-12-05 17:45:01,865][[pool-2-thread-1] - settopObj.getModel() = Rpi-RDKB [INFO][2024-12-05 17:45:01,866][[pool-2-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue [INFO][2024-12-05 17:45:01,870][[pool-2-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/D858D700A5D6settop_trace.log [INFO][2024-12-05 17:45:01,870][[pool-2-thread-1] - Crash Analysis not enabled during trace monitoring [INFO][2024-12-05 17:45:01,871][[pool-2-thread-1] - Serial based trace to be initialized: false [INFO][2024-12-05 17:45:01,871][[pool-2-thread-1] - [INIT LOG] : Adding dut to locked list [INFO][2024-12-05 17:45:01,872][[pool-2-thread-1] - DeviceConfig Macs D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:01,873][[pool-2-thread-1] - Dut Added to locked list D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:04,695][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads [INFO][2024-12-05 17:45:04,695][[com.automatics.executor.Starter.main()] - Collecting dut info [INFO][2024-12-05 17:45:04,696][[com.automatics.executor.Starter.main()] - Requesting data from http://192.168.2.59:8084/api/rack/service/getmasterconfig?configName=TEST_TYPE_MAP [INFO][2024-12-05 17:45:04,709][[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-12-05 17:45:05,317][[com.automatics.executor.Starter.main()] - AutomaticsTapApi instance not available. Creating new instance [INFO][2024-12-05 17:45:05,318][[com.automatics.executor.Starter.main()] - Creating new instance for AutomaticsTapApi [INFO][2024-12-05 17:45:05,318][[com.automatics.executor.Starter.main()] - Reading implementation from partner for deviceConnectionProvider [INFO][2024-12-05 17:45:05,361][[com.automatics.executor.Starter.main()] - >>>[BEFORE_SUITE]: Perform before suite initialization [INFO][2024-12-05 17:45:05,361][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured. [INFO][2024-12-05 17:45:05,362][[com.automatics.executor.Starter.main()] - Adding locked devices to dut object in AutomaticsTestBase [INFO][2024-12-05 17:45:05,362][[com.automatics.executor.Starter.main()] - Locked Settops: 1 [INFO][2024-12-05 17:45:05,363][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Verifying if build loaded in device as expected [INFO][2024-12-05 17:45:05,363][[pool-6-thread-1] - [BEFORE-SUITE:]Expected build in device is rdkb-generic-broadband-image_rdkb-2023q1-dunfell_20230925052003 [INFO][2024-12-05 17:45:05,363][[pool-6-thread-1] - [BEFORE-SUITE:]Verifying if build changed before test [INFO][2024-12-05 17:45:05,363][[pool-6-thread-1] - Skipping build change verification as partner specific initialization is not configured. [INFO][2024-12-05 17:45:05,363][[pool-6-thread-1] - [BEFORE-SUITE:]Build Change Status: NO_CHANGE [INFO][2024-12-05 17:45:05,364][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Build in device is as expected [INFO][2024-12-05 17:45:05,364][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Setting appropritate build appender based on executionMode [INFO][2024-12-05 17:45:05,368][[pool-6-thread-1] - Skipping setting of execution mode in device as partner specific initialization is not configured. [INFO][2024-12-05 17:45:05,369][[pool-6-thread-1] - INIT-D8:58:D7:00:A5:D6 Perform before suite initialization [INFO][2024-12-05 17:45:05,369][[pool-6-thread-1] - Skipping partner specific before suite initialization as it is not configured. [INFO][2024-12-05 17:45:05,369][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Starting device connection trace [INFO][2024-12-05 17:45:05,369][[pool-6-thread-1] - Starting trace with command : tail -F -n 0 /rdklogs/logs/* [INFO][2024-12-05 17:45:05,377][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Starting reading..D858D700A5D6settop_trace.log, 17:45:05,378 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 17:45:05,378 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-D858D700A5D6settop_trace.log] 17:45:05,379 |-INFO in ch.qos.logback.core.FileAppender[FILE-D858D700A5D6settop_trace.log] - File property is set to [target/settoptrace/D858D700A5D6settop_trace.log] [INFO][2024-12-05 17:45:05,379][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Going to connect device for trace monitoring .....! [INFO][2024-12-05 17:45:05,380][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - XX Host IP Address : 192.168.2.3 [INFO][2024-12-05 17:45:05,380][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - XX Host IP6 Address : null [INFO][2024-12-05 17:45:05,380][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - XX Host MAC Address : D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:05,380][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Settoptrace debugging:Inside Non RDKV client device [INFO][2024-12-05 17:45:05,390][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Reading server-config.xml from /var/lib/jenkins/workspace/testrepo/server-config.xml [INFO][2024-12-05 17:45:05,409][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-05 17:45:05,753][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - About to create SSH connection to DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:05,769][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-05 17:45:11,014][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Closing SSH connection from DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:11,020][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Connectiongateway iscom.automatics.providers.connection.SshConnection@1d08c67b [INFO][2024-12-05 17:45:11,020][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - inside null != connectionGateway condition [INFO][2024-12-05 17:45:11,021][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - gateWayDeviceInputStream iscom.jcraft.jsch.Channel$MyPipedInputStream@634e8188 [INFO][2024-12-05 17:45:11,022][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Settoptrace debugging:Inside else block [INFO][2024-12-05 17:45:11,023][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Entered into readOutputFromChannel method [INFO][2024-12-05 17:45:11,023][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Is RDKV Client trace false [INFO][2024-12-05 17:45:11,039][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-05 17:45:11,267][[PollingThread_26_(D8:58:D7:00:A5:D6)] - sshConnectionPoll established [INFO][2024-12-05 17:45:11,282][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-05 17:45:11,527][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Value of responseString: null [INFO][2024-12-05 17:45:11,527][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Executing command: echo CONNECTION_IS_ALIVE [INFO][2024-12-05 17:45:11,532][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Conn is not null sendRecive: [INFO][2024-12-05 17:45:11,657][[PollingThread_26_(D8:58:D7:00:A5:D6)] - <=========================== RESPONSE =======================> CONNECTION_IS_ALIVE <=============================================================> [INFO][2024-12-05 17:45:11,657][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6074f069 [INFO][2024-12-05 17:45:11,666][[PollingThread_26_(D8:58:D7:00:A5:D6)] - ConnectionPoll disconnected [INFO][2024-12-05 17:45:35,378][[pool-6-thread-1] - Is trace required for connected gateway: false [INFO][2024-12-05 17:45:35,378][[pool-6-thread-1] - Is trace required for connected gateway: false [INFO][2024-12-05 17:45:38,365][[com.automatics.executor.Starter.main()] - Starting testing on class: org.testng.TestRunner [INFO][2024-12-05 17:45:38,394][[com.automatics.executor.Starter.main()] - Doing initialization for D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:38,418][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Perform before method initialization D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:38,419][[TestNG-PoolService-0] - Setting dattime in patter yyyy-MM-dd HH:mm:ss [INFO][2024-12-05 17:45:38,422][[TestNG-PoolService-0] - startTime 2024-12-05 12:15:38 [INFO][2024-12-05 17:45:38,422][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Sending test exection start time to Automatics D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:38,424][[TestNG-PoolService-0] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm [INFO][2024-12-05 17:45:38,425][[TestNG-PoolService-0] - {"jobId":456,"automationId":"TC-RDKB-WIFI-1000","macAddress":"D8:58:D7:00:A5:D6","startDateTimeEST":1733400938423,"endDateTimeEST":0} [INFO][2024-12-05 17:45:38,503][[TestNG-PoolService-0] - Capture execution time - Response : HTTP/1.1 200 [INFO][2024-12-05 17:45:38,504][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - Skipping partner specific before method initialization as it is not configured. 17:45:38,504 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 17:45:38,504 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-Rpi-RDKB-D858D700A5D6] 17:45:38,505 |-INFO in ch.qos.logback.core.FileAppender[FILE-Rpi-RDKB-D858D700A5D6] - File property is set to [target/logs/Rpi-RDKB-D858D700A5D6.log] [INFO][2024-12-05 17:45:38,505][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Extending allocation before method for testType : GROUP_OR_AUTOID D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:38,506][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - Get the locked status: false [INFO][2024-12-05 17:45:38,506][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - Is trace required for connected gateway: false [INFO][2024-12-05 17:45:38,506][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - Is trace required for connected gateway: false [INFO][2024-12-05 17:45:38,516][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - STARTED - testVerifyDefaultWifiMeshAgent - com.automatics.device.Device@6074f069 [INFO][2024-12-05 17:45:38,517][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Getting MDC = D858D700A5D6settop_trace.log [INFO][2024-12-05 17:45:38,517][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Getting MDC = D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 1.Check whether meshagent process is running properly from Gateway device using command ps -ef | grep [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 2.verify meshwifi service is not running through Gateway device [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 3.Verify that wifi mesh service is disabled Using TR181 parameter [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 4.Verify that wifimesh service status using TR181 parameter [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 5.Verify that wifimesh service state using TR181 parameter [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 6.verify the plume back end url using param Device.X_RDKCENTRAL-COM_Mesh.BackhaulURL [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - step 1: Check whether meshagent process is running properly from gateway device using command ps -ef | grep -i mesh [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - EXPECTED: Meshagent process should be listed [INFO][2024-12-05 17:45:38,527][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:38,528][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Entering executeCommandUsingSsh method [INFO][2024-12-05 17:45:38,528][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - About to create SSH connection to DutIP:192.168.2.3 [INFO][2024-12-05 17:45:38,545][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-05 17:45:38,769][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Executing command: ps -ef | grep -i mesh [INFO][2024-12-05 17:45:38,769][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-05 17:45:38,892][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - <=========================== RESPONSE =======================> root 4497 1 0 12:10 ? 00:00:00 /usr/bin/meshAgent -subsys eRT. root 9029 9026 8 12:15 pts/1 00:00:02 tail -F -n 0 /rdklogs/logs/ArmConsolelog.txt.0 /rdklogs/logs/BootTime.log /rdklogs/logs/CRlog.txt.0 /rdklogs/logs/CRlog.txt.1 /rdklogs/logs/CapDebug.txt /rdklogs/logs/CellularManagerLog.txt.0 /rdklogs/logs/CellularManagerLog.txt.1 /rdklogs/logs/Consolelog.txt.0 /rdklogs/logs/DNSInternetCheck.txt.0 /rdklogs/logs/DNSInternetCheck.txt.1 /rdklogs/logs/ETHAGENTLog.txt.0 /rdklogs/logs/ETHAGENTLog.txt.1 /rdklogs/logs/FirewallDebug.txt /rdklogs/logs/InterDeviceManager.txt.0 /rdklogs/logs/InterDeviceManager.txt.1 /rdklogs/logs/LM.txt.0 /rdklogs/logs/LM.txt.1 /rdklogs/logs/MeshAgentLog.txt.0 /rdklogs/logs/MeshAgentLog.txt.1 /rdklogs/logs/MeshServiceLog.txt.0 /rdklogs/logs/MeshServiceLog.txt.1 /rdklogs/logs/MnetDebug.txt /rdklogs/logs/OnBoardingLog.txt.0 /rdklogs/logs/PAMlog.txt.0 /rdklogs/logs/PAMlog.txt.1 /rdklogs/logs/PARODUSlog.txt.0 /rdklogs/logs/PARODUSlog.txt.1 /rdklogs/logs/PSMlog.txt.0 /rdklogs/logs/PSMlog.txt.1 /rdklogs/logs/TDMlog.txt.0 /rdklogs/logs/TDMlog.txt.1 /rdklogs/logs/TR69log.txt.0 /rdklogs/logs/TR69log.txt.1 /rdklogs/logs/WANMANAGERLog.txt.0 /rdklogs/logs/WANMANAGERLog.txt.1 /rdklogs/logs/WEBPAlog.txt.0 /rdklogs/logs/WEBPAlog.txt.1 /rdklogs/logs/WiFilog.txt.0 /rdklogs/logs/WiFilog.txt.1 /rdklogs/logs/dcmProcessing.log /rdklogs/logs/dcmscript.log /rdklogs/logs/eth_telemetry.txt /rdklogs/logs/lighttpderror.log /rdklogs/logs/rdk_shell.log /rdklogs/logs/rtrouted.log /rdklogs/logs/swupdate.log /rdklogs/logs/syseventd_kill.log /rdklogs/logs/telemetry.log /rdklogs/logs/telemetry2_0.txt.0 /rdklogs/logs/webui.log /rdklogs/logs/wifihealth.txt root 9458 9444 0 12:15 pts/2 00:00:00 sh -c ps -ef | grep -i mesh root 9461 9458 0 12:15 pts/2 00:00:00 grep -i mesh <=============================================================> [INFO][2024-12-05 17:45:38,893][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Exiting executeCommandUsingSsh method [INFO][2024-12-05 17:45:38,893][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:38,894][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-WIFI-000] [step Number : s1][ Execution status : PASS] [Error Message : ] [INFO][2024-12-05 17:45:38,896][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-05 17:45:38,897][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Fetching device property: FIRMWARE_VERSION [INFO][2024-12-05 17:45:38,902][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Fetching device props for D8:58:D7:00:A5:D6 for props [FIRMWARE_VERSION] Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getDeviceProps [INFO][2024-12-05 17:45:38,913][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Response: {"mac":"D8:58:D7:00:A5:D6","HEAD_END":null,"FIRMWARE_VERSION":"Rpi-RDKB","ECM_IP_ADDRESS":null,"ESTB_IP_ADDRESS":null} [INFO][2024-12-05 17:45:38,913][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Getting value for property: FIRMWARE_VERSION [INFO][2024-12-05 17:45:38,913][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Value: Rpi-RDKB [INFO][2024-12-05 17:45:38,913][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Device property name: FIRMWARE_VERSION value obtained: Rpi-RDKB [INFO][2024-12-05 17:45:38,923][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-000","stepNumber":"s1","testType":"GROUP_OR_AUTOID ","buildName":"Rpi-RDKB","macAddress":"D8:58:D7:00:A5:D6","remarks":"","executionStatus":"PASS","skipRemaining":true,"partnerName":"","automationId":"TC-RDKB-WIFI-1000"} [ERROR][2024-12-05 17:45:38,935][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 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-12-05 17:45:38,935][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-05 17:45:38,935][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:38,935][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - STEP 2 : DESCRIPTION : Verify that meshwifi service is not running using systemctl command [INFO][2024-12-05 17:45:38,935][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - STEP 2 : ACTION : EXECUTE 'systemctl status meshwifi.service' in atom console [INFO][2024-12-05 17:45:38,935][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - STEP 2 : EXPECTED : MeshWifi service should be inactive [INFO][2024-12-05 17:45:38,935][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:38,940][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - About to create SSH connection to DutIP:192.168.2.3 [INFO][2024-12-05 17:45:38,951][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-05 17:45:39,168][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Entering executeCommandUsingSsh method [INFO][2024-12-05 17:45:39,168][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - About to create SSH connection to DutIP:192.168.2.3 [INFO][2024-12-05 17:45:39,178][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-05 17:45:39,407][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Executing command: systemctl status meshwifi.service [INFO][2024-12-05 17:45:39,407][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-05 17:45:49,430][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - <=========================== RESPONSE =======================> ● meshwifi.service - Mesh Wifi service Loaded: loaded (/lib/systemd/system/meshwifi.service; static; vendor preset : disabled) Active: inactive (dead) ~ ~ ~ ~ ~ ~ ~ ~ ~ ~ ~ ~ ~ ~ ~ ~ ~ ~ standard input <=============================================================> [INFO][2024-12-05 17:45:49,442][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Exiting executeCommandUsingSsh method [INFO][2024-12-05 17:45:49,444][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - systemctl command returned wifi mesh service is inactive [INFO][2024-12-05 17:45:49,445][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Is the mesh service inactive? true [INFO][2024-12-05 17:45:49,445][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:49,445][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-WIFI-000] [step Number : s2][ Execution status : PASS] [Error Message : ] [INFO][2024-12-05 17:45:49,445][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-05 17:45:49,446][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-000","stepNumber":"s2","testType":"GROUP_OR_AUTOID ","buildName":"Rpi-RDKB","macAddress":"D8:58:D7:00:A5:D6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-1000"} [ERROR][2024-12-05 17:45:49,459][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 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-12-05 17:45:49,459][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-05 17:45:49,459][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:49,459][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - step 3: Verify that wifimesh service is disabled using TR181 parameter Device.X_RDKCENTRAL-COM_Mesh.Enable [INFO][2024-12-05 17:45:49,459][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - EXPECTED: parameter should return value false [INFO][2024-12-05 17:45:49,459][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:49,459][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - STARTING METHOD: verifyMeshWifiParamValuesUsingWebPa [INFO][2024-12-05 17:45:49,466][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Starting Method getParameterValuesUsingWebPaOrDmcli() [INFO][2024-12-05 17:45:49,467][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Is WebpaConnectionBroken : false [INFO][2024-12-05 17:45:49,467][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-05 17:45:49,467][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-05 17:45:49,467][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-05 17:45:49,468][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-05 17:45:49,471][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Going to execute commands [INFO][2024-12-05 17:45:49,472][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:49,488][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-05 17:45:49,713][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable [INFO][2024-12-05 17:45:49,713][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-05 17:45:49,837][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable Execution succeed. Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable type: bool, value: false <=============================================================> [INFO][2024-12-05 17:45:49,837][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:49,838][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable Execution succeed. Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable type: bool, value: false [INFO][2024-12-05 17:45:49,839][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Dmcli param value : false [INFO][2024-12-05 17:45:49,839][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable, protocolSpecificParamName=Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable, value=false, datatype=null, statusCode=0] [INFO][2024-12-05 17:45:49,839][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Parameter Value is : false [INFO][2024-12-05 17:45:49,840][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Ending Method getParameterValuesUsingWebPaOrDmcli() [INFO][2024-12-05 17:45:49,840][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ENDING METHOD: verifyMeshWifiParamValuesUsingWebPa [INFO][2024-12-05 17:45:49,840][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Did Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable return false? true [INFO][2024-12-05 17:45:49,840][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:49,840][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-WIFI-000] [step Number : s3][ Execution status : PASS] [Error Message : ] [INFO][2024-12-05 17:45:49,840][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-05 17:45:49,842][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-000","stepNumber":"s3","testType":"GROUP_OR_AUTOID ","buildName":"Rpi-RDKB","macAddress":"D8:58:D7:00:A5:D6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-1000"} [ERROR][2024-12-05 17:45:49,853][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 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-12-05 17:45:49,853][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-05 17:45:49,853][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:49,853][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - step 4: Verify that wifimesh service status using TR181 Device.X_RDKCENTRAL-COM_Mesh.Status [INFO][2024-12-05 17:45:49,854][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - EXPECTED : status should be Off in default state [INFO][2024-12-05 17:45:49,854][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:49,854][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - STARTING METHOD: verifyMeshWifiParamValuesUsingWebPa [INFO][2024-12-05 17:45:49,854][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Starting Method getParameterValuesUsingWebPaOrDmcli() [INFO][2024-12-05 17:45:49,854][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Is WebpaConnectionBroken : false [INFO][2024-12-05 17:45:49,854][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-05 17:45:49,854][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-05 17:45:49,854][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-05 17:45:49,854][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-05 17:45:49,855][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Going to execute commands [INFO][2024-12-05 17:45:49,855][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:49,870][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-05 17:45:50,150][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Status [INFO][2024-12-05 17:45:50,150][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-05 17:45:50,273][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Status Execution succeed. Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Status type: string, value: Off <=============================================================> [INFO][2024-12-05 17:45:50,273][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:50,273][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Status Execution succeed. Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Status type: string, value: Off [INFO][2024-12-05 17:45:50,274][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Dmcli param value : Off [INFO][2024-12-05 17:45:50,274][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Status, protocolSpecificParamName=Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Status, value=Off, datatype=null, statusCode=0] [INFO][2024-12-05 17:45:50,274][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Parameter Value is : Off [INFO][2024-12-05 17:45:50,274][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Ending Method getParameterValuesUsingWebPaOrDmcli() [INFO][2024-12-05 17:45:50,274][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ENDING METHOD: verifyMeshWifiParamValuesUsingWebPa [INFO][2024-12-05 17:45:50,275][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Did Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Status return OFF? true [INFO][2024-12-05 17:45:50,275][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:50,275][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-WIFI-000] [step Number : s4][ Execution status : PASS] [Error Message : ] [INFO][2024-12-05 17:45:50,275][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-05 17:45:50,276][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-000","stepNumber":"s4","testType":"GROUP_OR_AUTOID ","buildName":"Rpi-RDKB","macAddress":"D8:58:D7:00:A5:D6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-1000"} [ERROR][2024-12-05 17:45:50,288][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 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-12-05 17:45:50,288][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-05 17:45:50,288][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:50,288][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - step 5: Verify that wifimesh service state using TR181 Device.X_RDKCENTRAL-COM_Mesh.State [INFO][2024-12-05 17:45:50,288][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - EXPECTED : state should be Full in default state [INFO][2024-12-05 17:45:50,288][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:50,288][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - STARTING METHOD: verifyMeshWifiParamValuesUsingWebPa [INFO][2024-12-05 17:45:50,288][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Starting Method getParameterValuesUsingWebPaOrDmcli() [INFO][2024-12-05 17:45:50,288][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Is WebpaConnectionBroken : false [INFO][2024-12-05 17:45:50,289][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-05 17:45:50,289][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-05 17:45:50,289][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-05 17:45:50,289][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-05 17:45:50,289][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Going to execute commands [INFO][2024-12-05 17:45:50,289][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:50,303][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-05 17:45:50,519][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.State [INFO][2024-12-05 17:45:50,519][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-05 17:45:50,642][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.State Execution succeed. Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.State type: string, value: Full <=============================================================> [INFO][2024-12-05 17:45:50,642][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:50,642][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.State Execution succeed. Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.State type: string, value: Full [INFO][2024-12-05 17:45:50,643][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Dmcli param value : Full [INFO][2024-12-05 17:45:50,643][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.State, protocolSpecificParamName=Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.State, value=Full, datatype=null, statusCode=0] [INFO][2024-12-05 17:45:50,643][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Parameter Value is : Full [INFO][2024-12-05 17:45:50,643][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Ending Method getParameterValuesUsingWebPaOrDmcli() [INFO][2024-12-05 17:45:50,643][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ENDING METHOD: verifyMeshWifiParamValuesUsingWebPa [INFO][2024-12-05 17:45:50,644][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Did Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.State return Full? true [INFO][2024-12-05 17:45:50,644][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:50,644][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-WIFI-000] [step Number : s5][ Execution status : PASS] [Error Message : ] [INFO][2024-12-05 17:45:50,644][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-05 17:45:50,645][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-000","stepNumber":"s5","testType":"GROUP_OR_AUTOID ","buildName":"Rpi-RDKB","macAddress":"D8:58:D7:00:A5:D6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-1000"} [ERROR][2024-12-05 17:45:50,656][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 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-12-05 17:45:50,656][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-05 17:45:50,656][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:50,656][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - step 6 : verify the plume back end url using param Device.X_RDKCENTRAL-COM_Mesh.BackhaulURL [INFO][2024-12-05 17:45:50,656][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - EXPECTED: Parameter should rerurn a value ssl:yellowstone.plume.comcast.net:443 [INFO][2024-12-05 17:45:50,656][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:50,662][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - STARTING METHOD: verifyMeshWifiParamValuesUsingWebPa [INFO][2024-12-05 17:45:50,662][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Starting Method getParameterValuesUsingWebPaOrDmcli() [INFO][2024-12-05 17:45:50,662][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Is WebpaConnectionBroken : false [INFO][2024-12-05 17:45:50,662][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-05 17:45:50,662][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-05 17:45:50,662][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-05 17:45:50,662][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-05 17:45:50,662][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Going to execute commands [INFO][2024-12-05 17:45:50,663][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:50,675][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-05 17:45:50,882][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.URL [INFO][2024-12-05 17:45:50,883][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-05 17:45:51,005][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.URL Execution succeed. Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.URL type: string, value: ssl:yellowstone.plume.comcast.net:443 <=============================================================> [INFO][2024-12-05 17:45:51,006][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.3 [INFO][2024-12-05 17:45:51,006][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.URL Execution succeed. Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.URL type: string, value: ssl:yellowstone.plume.comcast.net:443 [INFO][2024-12-05 17:45:51,006][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Dmcli param value : ssl:yellowstone.plume.comcast.net:443 [INFO][2024-12-05 17:45:51,007][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.URL, protocolSpecificParamName=Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.URL, value=ssl:yellowstone.plume.comcast.net:443, datatype=null, statusCode=0] [INFO][2024-12-05 17:45:51,007][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Parameter Value is : ssl:yellowstone.plume.comcast.net:443 [INFO][2024-12-05 17:45:51,007][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Ending Method getParameterValuesUsingWebPaOrDmcli() [INFO][2024-12-05 17:45:51,007][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ENDING METHOD: verifyMeshWifiParamValuesUsingWebPa [INFO][2024-12-05 17:45:51,007][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Is the plume backhaul url verified? - true [INFO][2024-12-05 17:45:51,008][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ************************************************************************** [INFO][2024-12-05 17:45:51,008][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-WIFI-000] [step Number : s6][ Execution status : PASS] [Error Message : ] [INFO][2024-12-05 17:45:51,008][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-05 17:45:51,009][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-000","stepNumber":"s6","testType":"GROUP_OR_AUTOID ","buildName":"Rpi-RDKB","macAddress":"D8:58:D7:00:A5:D6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-1000"} [ERROR][2024-12-05 17:45:51,020][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 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-12-05 17:45:51,020][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-05 17:45:51,021][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ENDING TEST CASE:TC-RDKB-WIFI-1000 [INFO][2024-12-05 17:45:51,021][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - ####################################################################################### [INFO][2024-12-05 17:45:51,024][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - >>>[AFTER_METHOD]: Clear device trace buffer for D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:51,037][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-WIFI-1000/D858D700A5D6/settoptrace/ [INFO][2024-12-05 17:45:51,038][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - created a new directory true [INFO][2024-12-05 17:45:51,038][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - New trace location from the strings /var/lib/jenkins/workspace/JOB1/target/settoptrace/D858D700A5D6settop_trace.log [INFO][2024-12-05 17:45:51,039][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Writing stated from line [INFO][2024-12-05 17:45:51,095][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - [ HTML LOG PARSER ] : End of log parser Thu Dec 05 17:45:51 IST 2024 [INFO][2024-12-05 17:45:51,096][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-WIFI-1000/D858D700A5D6/logs/ [INFO][2024-12-05 17:45:51,096][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - created a new directory true [INFO][2024-12-05 17:45:51,096][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Destination Summary file doesn't exist. Creating one! [INFO][2024-12-05 17:45:51,097][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Writing stated from line [INFO][2024-12-05 17:45:51,159][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - [ HTML LOG PARSER ] : End of log parser Thu Dec 05 17:45:51 IST 2024 [INFO][2024-12-05 17:45:51,159][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - >>>[AFTER_METHOD]: Sending test execution completion time for D8:58:D7:00:A5:D6 [INFO][2024-12-05 17:45:51,159][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm [INFO][2024-12-05 17:45:51,159][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - {"jobId":456,"automationId":"TC-RDKB-WIFI-1000","macAddress":"D8:58:D7:00:A5:D6","startDateTimeEST":0,"endDateTimeEST":1733400951159} [INFO][2024-12-05 17:45:51,168][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Capture execution time - Response : HTTP/1.1 200 [INFO][2024-12-05 17:45:51,168][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Restarting Trace in after method [INFO][2024-12-05 17:45:51,168][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Is trace required for connected gateway: false [INFO][2024-12-05 17:45:51,168][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Is trace required for connected gateway: false [INFO][2024-12-05 17:45:51,168][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Going for if build changed : Test Type : GROUP_OR_AUTOID [INFO][2024-12-05 17:45:51,169][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - Skipping build change verification as partner specific initialization is not configured. [INFO][2024-12-05 17:45:51,169][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - >>>[AFTER_METHOD]: Build Change Status: NO_CHANGE [INFO][2024-12-05 17:45:51,169][D8:58:D7:00:A5:D6[TC-RDKB-WIFI-1000 : D858D700A5D6] - 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.BroadBandMeshWifiTest@5de91429]org.testng.TestRunner@61edecb4 1575556137 AutomaticsTestBase.performBeforeMethodInit([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@5de91429][Ljava.lang.Object;@106d580 [TestResult name={null} status=CREATED method=BroadBandMeshWifiTest.testVerifyDefaultWifiMeshAgent(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@5de91429] output={null}] 1575556137 BroadBandMeshWifiTest.testVerifyDefaultWifiMeshAgent(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@5de91429]com.automatics.device.Device@6074f069 1575556137 AutomaticsTestBase.performAfterMethodCleanup([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@5de91429][Ljava.lang.Object;@106d580 [TestResult name=testVerifyDefaultWifiMeshAgent status=SUCCESS method=BroadBandMeshWifiTest.testVerifyDefaultWifiMeshAgent(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@5de91429] output={null}] 1575556137 ===== PASSED: testVerifyDefaultWifiMeshAgent(com.automatics.device.Device@6074f069) =============================================== MyTest Tests run: 1, Failures: 0, Skips: 0 =============================================== [INFO][2024-12-05 17:45:51,235][[com.automatics.executor.Starter.main()] - Finished testing class: org.testng.TestRunner [INFO][2024-12-05 17:45:51,236][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Performing after suite cleanup [INFO][2024-12-05 17:45:51,236][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured. [INFO][2024-12-05 17:45:51,236][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Locked devices after suite execution 1 [INFO][2024-12-05 17:45:51,236][[com.automatics.executor.Starter.main()] - Value of intial testType : QUICK [INFO][2024-12-05 17:45:51,236][[com.automatics.executor.Starter.main()] - Value of TestType provided by job : GROUP_OR_AUTOID [INFO][2024-12-05 17:45:51,237][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Verifying if build changed after test [INFO][2024-12-05 17:45:51,237][[com.automatics.executor.Starter.main()] - Skipping build change verification as partner specific initialization is not configured. [INFO][2024-12-05 17:45:51,237][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Build Change Status: NO_CHANGE [INFO][2024-12-05 17:45:51,237][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false [ERROR][2024-12-05 17:45:51,237][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Sleep interrupted sleep interrupted [ERROR][2024-12-05 17:45:51,240][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - 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-12-05 17:45:51,241][[com.automatics.executor.Starter.main()] - Connection instance is Null [INFO][2024-12-05 17:45:51,241][[com.automatics.executor.Starter.main()] - Connection instance is Null [INFO][2024-12-05 17:45:51,241][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Connection instance is Null [INFO][2024-12-05 17:45:51,241][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Connection instance is Null [INFO][2024-12-05 17:45:51,241][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Exited from readOutputFromChannel method [INFO][2024-12-05 17:45:51,241][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false [INFO][2024-12-05 17:45:51,241][[com.automatics.executor.Starter.main()] - Skipping parter specific after suite clean up as it is not configured. [INFO][2024-12-05 17:45:51,247][[com.automatics.executor.Starter.main()] - Releasing device D8:58:D7:00:A5:D6 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/release [INFO][2024-12-05 17:45:51,306][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:58:D7:00:A5:D6"} [INFO][2024-12-05 17:45:51,307][[com.automatics.executor.Starter.main()] - Successfully unlocked RDKB (D8:58:D7:00:A5:D6) mapped components - [INFO][2024-12-05 17:45:51,307][[com.automatics.executor.Starter.main()] - SETTOP - D8:58:D7:00:A5:D6 RELEASED. [INFO][2024-12-05 17:45:51,309][[com.automatics.executor.Starter.main()] - [AFTER-SUITE:]JSON message to Automatics: {"status":"COMPLETED","service":"","buildImageName":"Rpi-RDKB","settopList":["D8:58:D7:00:A5:D6"],"startTime":1733400905362,"completionTime":1733400951308,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"Rpi-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}} [INFO][2024-12-05 17:45:51,321][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [INFO][2024-12-05 17:45:38,516][456][D8:58:D7:00:A5:D6|TestNG-PoolService-0|com.automatics.executor.AutomaticsTestListener:onTestStart:110] STARTED - testVerifyDefaultWifiMeshAgent - com.automatics.device.Device@6074f069 [INFO][2024-12-05 17:45:51,321][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [DEBUG][2024-12-05 17:45:38,517][456][D8:58:D7:00:A5:D6|TC-RDKB-WIFI-1000 : D858D700A5D6|com.automatics.executor.AutomaticsTestListener:onTestStart:150] Started appending log =============================================== MySuite Total tests run: 1, Passes: 1, Failures: 0, Skips: 0 =============================================== [INFO][2024-12-05 17:45:51,390][[com.automatics.executor.Starter.main()] - Updating final execution status to Automatics [INFO][2024-12-05 17:45:51,391][[Thread-33] - ============= MESSAGE SEND TO AUTOMATICS ============== [INFO][2024-12-05 17:45:51,392][[Thread-33] - Tested build name : Rpi-RDKB [INFO][2024-12-05 17:45:51,392][[Thread-33] - Final execution status : COMPLETED [INFO][2024-12-05 17:45:51,392][[Thread-33] - Final successful device list : ["D8:58:D7:00:A5:D6"] [INFO][2024-12-05 17:45:51,392][[Thread-33] - Job Id : 456 [INFO][2024-12-05 17:45:51,392][[Thread-33] - ============================================================== [INFO][2024-12-05 17:45:51,392][[Thread-33] - >>>[INIT]: Automatics JSON -> {"status":"COMPLETED","service":"","buildImageName":"Rpi-RDKB","settopList":["D8:58:D7:00:A5:D6"],"startTime":1733400905362,"completionTime":1733400951308,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"Rpi-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}} [INFO][2024-12-05 17:45:51,393][[Thread-33] - http://192.168.2.59:8080/Automatics/executionResponse.htm [INFO][2024-12-05 17:47:51,536][[Thread-33] - HTTP STATUS LINE : HTTP/1.1 200 [INFO][2024-12-05 17:47:51,536][[Thread-33] - HTTP STATUS CODE : 200 [INFO][2024-12-05 17:47:51,537][[com.automatics.executor.Starter.main()] - ******** EXECUTION COMPLETED ********* [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 03:07 min [INFO] Finished at: 2024-12-05T17:47:51+05:30 [INFO] ------------------------------------------------------------------------ Inside Add Shutdown Hook [INFO][2024-12-05 17:47:51,543][[Thread-1] - Bean testInitializer is not configured. [INFO][2024-12-05 17:47:51,544][[Thread-1] - Closing partner application context [INFO][2024-12-05 17:47:51,546][[Thread-1] - Closing core application context [JOB1] $ /bin/sh -xe /tmp/jenkins18240942187456762017.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