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] '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] [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 120 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 --- 11:57:32,507 |-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] 11:57:32,517 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@6dcb1ef6 - 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 11:57:32,621 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 11:57:32,623 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdOutAppender] 11:57:32,629 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@7:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]] 11:57:32,630 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@8:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]] 11:57:32,630 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 11:57:32,646 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 11:57:32,649 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [deviceSiftingAppender] 11:57:32,650 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@18:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]] 11:57:32,651 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@19:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]] 11:57:32,652 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 11:57:32,655 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 11:57:32,655 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [TraceLogger] 11:57:32,656 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 11:57:32,656 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 11:57:32,656 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SerialTrace] 11:57:32,656 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 11:57:32,657 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender] 11:57:32,657 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [crash-analysis] 11:57:32,657 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property 11:57:32,658 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.apache.http] to false 11:57:32,658 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache.http level set to INFO 11:57:32,658 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[org.apache.http] 11:57:32,658 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ConnectionTrace] to TRACE 11:57:32,659 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ConnectionTrace] to false 11:57:32,659 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [TraceLogger] to Logger[ConnectionTrace] 11:57:32,659 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [SerialTrace] to TRACE 11:57:32,659 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [SerialTrace] to false 11:57:32,659 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SerialTrace] to Logger[SerialTrace] 11:57:32,659 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [crash-analysis] to DEBUG 11:57:32,659 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [crash-analysis] to false 11:57:32,659 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [crash-analysis] to Logger[crash-analysis] 11:57:32,659 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdOutAppender] to Logger[ROOT] 11:57:32,659 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[ROOT] 11:57:32,659 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 11:57:32,660 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@2375cb09 - Registering current configuration as safe fallback point [INFO][2024-12-23 11:57:32,661][[com.automatics.executor.Starter.main()] - Starting the execution process for the given test cases. 11:57:32,663 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 11:57:32,664 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-test-suite] 11:57:32,666 |-INFO in ch.qos.logback.core.FileAppender[FILE-test-suite] - File property is set to [target/logs/test-suite.log] [INFO][2024-12-23 11:57:32,675][[com.automatics.executor.Starter.main()] - Initializing application context partner-applicationContext.xml [INFO][2024-12-23 11:57:32,960][[com.automatics.executor.Starter.main()] - Initializing application context applicationContext.xml [INFO][2024-12-23 11:57:33,001][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.12:8080/AutomaticsProps/automatics.properties [INFO][2024-12-23 11:57:33,033][[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-23 11:57:33,036][[com.automatics.executor.Starter.main()] - ***************** Automatics BUILD PARAMETERS ***************** [INFO][2024-12-23 11:57:33,043][[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-23 11:57:33,043][[com.automatics.executor.Starter.main()] - Shut Down Hook Attached. [INFO][2024-12-23 11:57:33,048][[com.automatics.executor.Starter.main()] - Start Execution Test Suite [INFO][2024-12-23 11:57:33,050][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured. [INFO][2024-12-23 11:57:33,050][[com.automatics.executor.Starter.main()] - Cleaning the trace log directory [INFO][2024-12-23 11:57:33,072][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.12:8080/AutomaticsProps/automatics.properties [INFO][2024-12-23 11:57:33,077][[com.automatics.executor.Starter.main()] - >>>[INIT]: Validating config files [INFO][2024-12-23 11:57:33,077][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.12:8080/AutomaticsProps/deviceProps.json [INFO][2024-12-23 11:57:33,234][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.12:8080/AutomaticsProps/deviceProps.json [INFO][2024-12-23 11:57:33,240][[com.automatics.executor.Starter.main()] - frameworkSupportedModels=Rpi-RDKB,Rpi-RDKV, [INFO][2024-12-23 11:57:33,240][[com.automatics.executor.Starter.main()] - rdkvGWModels= [INFO][2024-12-23 11:57:33,240][[com.automatics.executor.Starter.main()] - rdkvCLModels=Rpi-RDKV, [INFO][2024-12-23 11:57:33,241][[com.automatics.executor.Starter.main()] - rdkbModels=Rpi-RDKB, [INFO][2024-12-23 11:57:33,241][[com.automatics.executor.Starter.main()] - rdkcModels= [INFO][2024-12-23 11:57:33,254][[com.automatics.executor.Starter.main()] - Reading implementation from core for deviceProvider [INFO][2024-12-23 11:57:33,269][[com.automatics.executor.Starter.main()] - Is Account based test: false [INFO][2024-12-23 11:57:33,271][[com.automatics.executor.Starter.main()] - INIT-D8:58:D7:00:A5:D6 Get device details [INFO][2024-12-23 11:57:33,612][[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-23 11:57:33,792][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3839","name":"","hardwareRevision":"","hostMacAddress":"D8:58:D7:00:A5:D6","hostIp4Address":"192.168.2.54","hostIp6Address":null,"clientIpAddress":"192.168.2.54","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.54","headend":"HE","gatewayMac":"","extraProperties":{"deviceIp":"192.168.2.54","password":"root1234","connectedClients":"192.168.2.57","ethernetMacAddress":"D8:58:D7:00:A5:D6","wi-ficapability":"Dual band","nodePort":"22","connectionType":"SSH","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-23 11:57:33,868][[com.automatics.executor.Starter.main()] - INIT-D8:58:D7:00:A5:D6 Obtained device details [INFO][2024-12-23 11:57:33,869][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model Rpi-RDKB [INFO][2024-12-23 11:57:33,869][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model Rpi-RDKB [INFO][2024-12-23 11:57:33,869][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model Rpi-RDKB to automatics model Rpi-RDKB [INFO][2024-12-23 11:57:33,869][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device [INFO][2024-12-23 11:57:33,882][[pool-2-thread-1] - INIT-{} Check if device already lockedD8:58:D7:00:A5:D6 [INFO][2024-12-23 11:57:33,914][[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-23 11:57:33,924][[pool-2-thread-1] - Response: {"allocationStatus":"AVAILABLE"} [INFO][2024-12-23 11:57:33,926][[pool-2-thread-1] - DeviceConfig allocation status [INFO][2024-12-23 11:57:33,926][[pool-2-thread-1] - [INIT LOG] : Locking device D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:57:33,926][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Locking device [INFO][2024-12-23 11:57:33,942][[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-23 11:57:33,994][[pool-2-thread-1] - Response: {"status":"SUCCESS","mac":"D8:58:D7:00:A5:D6"} [INFO][2024-12-23 11:57:33,997][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Lock success [INFO][2024-12-23 11:57:33,997][[pool-2-thread-1] - Setting access mechanism for device D8:58:D7:00:A5:D6 SSH [INFO][2024-12-23 11:57:33,997][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Setting access method SSH [INFO][2024-12-23 11:57:33,997][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Checking if accessibility check required [INFO][2024-12-23 11:57:33,998][[pool-2-thread-1] - Setting accessibility check required to false for device D8:58:D7:00:A5:D6 from device config [INFO][2024-12-23 11:57:33,998][[pool-2-thread-1] - Accessibility check required for device D8:58:D7:00:A5:D6 false [INFO][2024-12-23 11:57:33,998][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Checking if accessibility check required is false [INFO][2024-12-23 11:57:33,998][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Assuming device is accessible [INFO][2024-12-23 11:57:33,998][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Is device accessible true [INFO][2024-12-23 11:57:33,998][[pool-2-thread-1] - [INIT LOG] : Wiring device.D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:57:33,998][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 Performing provider wiring [INFO][2024-12-23 11:57:33,999][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 PowerProvider wiring [INFO][2024-12-23 11:57:33,999][[pool-2-thread-1] - Reading implementation from core for powerProvider [INFO][2024-12-23 11:57:34,000][[pool-2-thread-1] - INIT-D8:58:D7:00:A5:D6 DeviceConfig Connection Based TraceProvider wiring [INFO][2024-12-23 11:57:34,002][[pool-2-thread-1] - Reading implementation from partner for deviceConnectionProvider [INFO][2024-12-23 11:57:34,007][[pool-2-thread-1] - Additional trace support : null [INFO][2024-12-23 11:57:34,008][[pool-2-thread-1] - settopObj.getModel() = Rpi-RDKB [INFO][2024-12-23 11:57:34,008][[pool-2-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue [INFO][2024-12-23 11:57:34,011][[pool-2-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/D858D700A5D6settop_trace.log [INFO][2024-12-23 11:57:34,011][[pool-2-thread-1] - Crash Analysis not enabled during trace monitoring [INFO][2024-12-23 11:57:34,012][[pool-2-thread-1] - Serial based trace to be initialized: false [INFO][2024-12-23 11:57:34,012][[pool-2-thread-1] - [INIT LOG] : Adding dut to locked list [INFO][2024-12-23 11:57:34,012][[pool-2-thread-1] - DeviceConfig Macs D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:57:34,013][[pool-2-thread-1] - Dut Added to locked list D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:57:36,871][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads [INFO][2024-12-23 11:57:36,871][[com.automatics.executor.Starter.main()] - Collecting dut info [INFO][2024-12-23 11:57:36,872][[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-23 11:57:36,888][[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-23 11:57:37,506][[com.automatics.executor.Starter.main()] - AutomaticsTapApi instance not available. Creating new instance [INFO][2024-12-23 11:57:37,507][[com.automatics.executor.Starter.main()] - Creating new instance for AutomaticsTapApi [INFO][2024-12-23 11:57:37,507][[com.automatics.executor.Starter.main()] - Reading implementation from partner for deviceConnectionProvider [INFO][2024-12-23 11:57:37,548][[com.automatics.executor.Starter.main()] - >>>[BEFORE_SUITE]: Perform before suite initialization [INFO][2024-12-23 11:57:37,548][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured. [INFO][2024-12-23 11:57:37,548][[com.automatics.executor.Starter.main()] - Adding locked devices to dut object in AutomaticsTestBase [INFO][2024-12-23 11:57:37,548][[com.automatics.executor.Starter.main()] - Locked Settops: 1 [INFO][2024-12-23 11:57:37,549][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Verifying if build loaded in device as expected [INFO][2024-12-23 11:57:37,550][[pool-6-thread-1] - [BEFORE-SUITE:]Expected build in device is rdkb-generic-broadband-image_rdkb-2023q1-dunfell_20230925052003 [INFO][2024-12-23 11:57:37,550][[pool-6-thread-1] - [BEFORE-SUITE:]Verifying if build changed before test [INFO][2024-12-23 11:57:37,550][[pool-6-thread-1] - Skipping build change verification as partner specific initialization is not configured. [INFO][2024-12-23 11:57:37,550][[pool-6-thread-1] - [BEFORE-SUITE:]Build Change Status: NO_CHANGE [INFO][2024-12-23 11:57:37,550][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Build in device is as expected [INFO][2024-12-23 11:57:37,550][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Setting appropritate build appender based on executionMode [INFO][2024-12-23 11:57:37,555][[pool-6-thread-1] - Skipping setting of execution mode in device as partner specific initialization is not configured. [INFO][2024-12-23 11:57:37,555][[pool-6-thread-1] - INIT-D8:58:D7:00:A5:D6 Perform before suite initialization [INFO][2024-12-23 11:57:37,555][[pool-6-thread-1] - Skipping partner specific before suite initialization as it is not configured. [INFO][2024-12-23 11:57:37,555][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Starting device connection trace [INFO][2024-12-23 11:57:37,556][[pool-6-thread-1] - Starting trace with command : tail -F -n 0 /rdklogs/logs/* [INFO][2024-12-23 11:57:37,563][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Starting reading..D858D700A5D6settop_trace.log, 11:57:37,564 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 11:57:37,564 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-D858D700A5D6settop_trace.log] 11:57:37,565 |-INFO in ch.qos.logback.core.FileAppender[FILE-D858D700A5D6settop_trace.log] - File property is set to [target/settoptrace/D858D700A5D6settop_trace.log] [INFO][2024-12-23 11:57:37,565][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Going to connect device for trace monitoring .....! [INFO][2024-12-23 11:57:37,565][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - XX Host IP Address : 192.168.2.54 [INFO][2024-12-23 11:57:37,566][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - XX Host IP6 Address : null [INFO][2024-12-23 11:57:37,566][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - XX Host MAC Address : D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:57:37,566][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Settoptrace debugging:Inside Non RDKV client device [INFO][2024-12-23 11:57:37,575][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Reading server-config.xml from /var/lib/jenkins/workspace/testrepo/server-config.xml [INFO][2024-12-23 11:57:37,593][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-23 11:57:37,928][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:57:37,946][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-23 11:57:43,215][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:57:43,221][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Connectiongateway iscom.automatics.providers.connection.SshConnection@39866506 [INFO][2024-12-23 11:57:43,222][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - inside null != connectionGateway condition [INFO][2024-12-23 11:57:43,222][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - gateWayDeviceInputStream iscom.jcraft.jsch.Channel$MyPipedInputStream@68183894 [INFO][2024-12-23 11:57:43,224][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Settoptrace debugging:Inside else block [INFO][2024-12-23 11:57:43,225][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Entered into readOutputFromChannel method [INFO][2024-12-23 11:57:43,226][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Is RDKV Client trace false [INFO][2024-12-23 11:57:43,244][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-23 11:57:43,493][[PollingThread_26_(D8:58:D7:00:A5:D6)] - sshConnectionPoll established [INFO][2024-12-23 11:57:43,512][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-23 11:57:43,745][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Value of responseString: null [INFO][2024-12-23 11:57:43,746][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Executing command: echo CONNECTION_IS_ALIVE [INFO][2024-12-23 11:57:43,748][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Conn is not null sendRecive: [INFO][2024-12-23 11:57:43,873][[PollingThread_26_(D8:58:D7:00:A5:D6)] - <=========================== RESPONSE =======================> CONNECTION_IS_ALIVE <=============================================================> [INFO][2024-12-23 11:57:43,873][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6401e33e [INFO][2024-12-23 11:57:43,883][[PollingThread_26_(D8:58:D7:00:A5:D6)] - ConnectionPoll disconnected [INFO][2024-12-23 11:58:07,563][[pool-6-thread-1] - Is trace required for connected gateway: false [INFO][2024-12-23 11:58:07,564][[pool-6-thread-1] - Is trace required for connected gateway: false [INFO][2024-12-23 11:58:10,552][[com.automatics.executor.Starter.main()] - Starting testing on class: org.testng.TestRunner [INFO][2024-12-23 11:58:10,587][[com.automatics.executor.Starter.main()] - Doing initialization for D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:58:10,614][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Perform before method initialization D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:58:10,615][[TestNG-PoolService-0] - Setting dattime in patter yyyy-MM-dd HH:mm:ss [INFO][2024-12-23 11:58:10,618][[TestNG-PoolService-0] - startTime 2024-12-23 06:28:10 [INFO][2024-12-23 11:58:10,619][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Sending test exection start time to Automatics D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:58:10,620][[TestNG-PoolService-0] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm [INFO][2024-12-23 11:58:10,622][[TestNG-PoolService-0] - {"jobId":456,"automationId":"TC-RDKB-MESH-WIFI-1003","macAddress":"D8:58:D7:00:A5:D6","startDateTimeEST":1734935290620,"endDateTimeEST":0} [INFO][2024-12-23 11:58:10,702][[TestNG-PoolService-0] - Capture execution time - Response : HTTP/1.1 200 [INFO][2024-12-23 11:58:10,703][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - Skipping partner specific before method initialization as it is not configured. 11:58:10,703 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 11:58:10,703 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-Rpi-RDKB-D858D700A5D6] 11:58:10,704 |-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-23 11:58:10,704][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-23 11:58:10,705][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - Get the locked status: false [INFO][2024-12-23 11:58:10,705][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - Is trace required for connected gateway: false [INFO][2024-12-23 11:58:10,705][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - Is trace required for connected gateway: false [INFO][2024-12-23 11:58:10,715][D8:58:D7:00:A5:D6[TestNG-PoolService-0] - STARTED - testVerifyBridgeModeEnabledUsingTr69WhenMeshEnabled - com.automatics.device.Device@6401e33e [INFO][2024-12-23 11:58:10,716][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Getting MDC = D858D700A5D6settop_trace.log [INFO][2024-12-23 11:58:10,716][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Getting MDC = D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:58:10,724][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ####################################################################################### [INFO][2024-12-23 11:58:10,724][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STARTING TEST CASE: TC-RDKB-MESH-WIFI-1003 [INFO][2024-12-23 11:58:10,724][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TEST DESCRIPTION: Verify enabling Bridge Mode using TR181 when the MESH is enabled [INFO][2024-12-23 11:58:10,724][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TEST STEPS : [INFO][2024-12-23 11:58:10,724][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Pre condition : Disable bridge mode and enable mesh wifi using webpa [INFO][2024-12-23 11:58:10,724][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - 1. Enable Bridge Mode using TR181 command [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - 2. Check for LanMode value set to bridge mode log message [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - 3. Check for Setting Mesh to disabled when bridge mode enabled log message [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - 4. Poll for Mesh wifi has been disabled log message [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - 5. Verify Mesh Enable parameter has been set to false [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Post condition: Disable bridge mode and mesh wifi using webpa [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ####################################################################################### [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ################### STARTING PRE-CONFIGURATIONS ################### [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - PRE-CONDITION STEPS [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - PRE-CONDITION : DESCRIPTION : Disable bridge mode and enable mesh wifi using webpa [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - PRE-CONDITION : ACTION : Execute webpa commands to set LanManagement parameter to router and MeshEnable parameter to true [INFO][2024-12-23 11:58:10,725][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - PRE-CONDITION : EXPECTED : Pre condition executed successfully [INFO][2024-12-23 11:58:10,731][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:58:10,731][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:58:10,731][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-23 11:58:10,732][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-23 11:58:10,734][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to execute commands [INFO][2024-12-23 11:58:10,734][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:10,750][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:58:10,976][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT getv Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode [INFO][2024-12-23 11:58:10,977][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:58:11,100][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. Parameter 1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode type: string, value: router <=============================================================> [INFO][2024-12-23 11:58:11,101][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:11,101][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. Parameter 1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode type: string, value: router [INFO][2024-12-23 11:58:11,102][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli param value : router [INFO][2024-12-23 11:58:11,103][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Response : [tableName=null, index=null, name=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode, protocolSpecificParamName=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode, value=router, datatype=null, statusCode=0] [INFO][2024-12-23 11:58:11,103][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPA get successful [INFO][2024-12-23 11:58:11,104][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Successfully set bridge mode to false [INFO][2024-12-23 11:58:11,104][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:58:11,104][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:58:11,104][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-23 11:58:11,105][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-23 11:58:11,105][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to execute commands [INFO][2024-12-23 11:58:11,105][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:11,122][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:58:11,369][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable [INFO][2024-12-23 11:58:11,369][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:58:11,491][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:58:11,491][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:11,492][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:58:11,492][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli param value : false [INFO][2024-12-23 11:58:11,492][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:58:11,492][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPA get successful [INFO][2024-12-23 11:58:11,505][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPa data type: BOOLEAN [INFO][2024-12-23 11:58:11,505][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 parameters status code added 0 [INFO][2024-12-23 11:58:11,505][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:58:11,505][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:58:11,506][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli Command to be executed: dmcli eRT setv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable bool true [INFO][2024-12-23 11:58:11,506][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:11,518][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:58:11,744][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT setv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable bool true [INFO][2024-12-23 11:58:11,745][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:58:11,868][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. setv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable Execution succeed. <=============================================================> [INFO][2024-12-23 11:58:11,868][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:11,868][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. setv from/to component(eRT.com.cisco.spvtg.ccsp.meshagent): Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable Execution succeed. [INFO][2024-12-23 11:58:11,869][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Response : {Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable=Success} [INFO][2024-12-23 11:58:11,870][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ENDING METHOD: setWebPaParameterValues : success [INFO][2024-12-23 11:58:11,871][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - RESPONSE CODE: 0 [INFO][2024-12-23 11:58:11,871][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - RESPONSE MESSAGE: success [INFO][2024-12-23 11:58:11,885][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WEBPA PARAM - Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable SET WITH VALUE - true IS SUCCESSFUL: true [INFO][2024-12-23 11:58:11,885][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPA set successful [INFO][2024-12-23 11:58:11,885][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:58:11,886][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:58:11,886][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-23 11:58:11,886][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-23 11:58:11,886][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to execute commands [INFO][2024-12-23 11:58:11,886][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:11,909][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:58:12,148][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable [INFO][2024-12-23 11:58:12,149][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:58:12,272][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:58:12,272][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:12,272][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:58:12,273][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli param value : false [INFO][2024-12-23 11:58:12,273][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:58:12,273][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPA get after set failed [INFO][2024-12-23 11:58:12,276][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Waiting for 30 seconds [INFO][2024-12-23 11:58:42,277][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:58:42,277][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:58:42,278][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-23 11:58:42,278][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-23 11:58:42,278][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to execute commands [INFO][2024-12-23 11:58:42,278][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:42,296][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:58:42,518][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable [INFO][2024-12-23 11:58:42,518][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:58:42,641][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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: true <=============================================================> [INFO][2024-12-23 11:58:42,642][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:42,642][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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: true [INFO][2024-12-23 11:58:42,643][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli param value : true [INFO][2024-12-23 11:58:42,643][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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=true, datatype=null, statusCode=0] [INFO][2024-12-23 11:58:42,643][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPA get after set successful [INFO][2024-12-23 11:58:42,644][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Successfully set mesh wifi to true [INFO][2024-12-23 11:58:42,644][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - PRE-CONDITION : ACTUAL : Pre condition executed successfully [INFO][2024-12-23 11:58:42,644][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ################### COMPLETED PRE-CONFIGURATIONS ################### [INFO][2024-12-23 11:58:42,644][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:58:42,644][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:58:42,645][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 1: DESCRIPTION : Enable Bridge Mode using TR181 command [INFO][2024-12-23 11:58:42,645][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 1: ACTION : Execute TR181 command to set LanManagement parameter to bridge-static [INFO][2024-12-23 11:58:42,645][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 1: EXPECTED : TR181 command to enable bridge mode successful [INFO][2024-12-23 11:58:42,645][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:58:42,645][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPa data type: STRING [INFO][2024-12-23 11:58:42,645][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 parameters status code added 0 [INFO][2024-12-23 11:58:42,646][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:58:42,646][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:58:42,646][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli Command to be executed: dmcli eRT setv Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode string bridge-static [INFO][2024-12-23 11:58:42,646][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:42,663][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:58:42,879][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT setv Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode string bridge-static [INFO][2024-12-23 11:58:42,879][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:58:43,899][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-23 11:58:44,697][[PollingThread_26_(D8:58:D7:00:A5:D6)] - sshConnectionPoll established [INFO][2024-12-23 11:58:44,713][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Default SSH custom port is set [INFO][2024-12-23 11:58:45,213][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Value of responseString: null [INFO][2024-12-23 11:58:45,214][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Executing command: echo CONNECTION_IS_ALIVE [INFO][2024-12-23 11:58:45,214][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Conn is not null sendRecive: [INFO][2024-12-23 11:58:45,337][[PollingThread_26_(D8:58:D7:00:A5:D6)] - <=========================== RESPONSE =======================> CONNECTION_IS_ALIVE <=============================================================> [INFO][2024-12-23 11:58:45,337][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6401e33e [INFO][2024-12-23 11:58:45,338][[PollingThread_26_(D8:58:D7:00:A5:D6)] - ConnectionPoll disconnected [INFO][2024-12-23 11:58:46,706][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. <=============================================================> [INFO][2024-12-23 11:58:46,706][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:46,706][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. [INFO][2024-12-23 11:58:46,707][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Response : {Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode=Success} [INFO][2024-12-23 11:58:46,707][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ENDING METHOD: setWebPaParameterValues : success [INFO][2024-12-23 11:58:46,707][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - RESPONSE CODE: 0 [INFO][2024-12-23 11:58:46,707][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - RESPONSE MESSAGE: success [INFO][2024-12-23 11:58:46,707][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WEBPA PARAM - Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode SET WITH VALUE - bridge-static IS SUCCESSFUL: true [INFO][2024-12-23 11:58:46,709][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Status of setting WebPaParameter values: true [INFO][2024-12-23 11:58:46,709][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:58:46,709][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:58:46,710][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-23 11:58:46,710][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-23 11:58:46,710][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to execute commands [INFO][2024-12-23 11:58:46,710][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:46,729][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:58:47,336][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT getv Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode [INFO][2024-12-23 11:58:47,337][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:58:47,460][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================>  Broadcast message from systemd-journald@TurrisOmnia-GW (Mon 2023-09-25 05:56:04 UTC): pl2rld[11669]: [OpenSync] TRACEBACK: MAIN: [fd 12] Received LOG, sev 7, module 1, len 45, "inet_eth: brlan0: Service IPv4 ready -> stop."  Broadcast message from systemd-journald@TurrisOmnia-GW (Mon 2023-09-25 05:56:04 UTC): pl2rld[11669]: [OpenSync] TRACEBACK: MAIN: [fd 12] Received LOG, sev 7, module 1, len 45, "inet_base: brlan0: Service IPv4 ready -> stop"  Broadcast message from systemd-journald@TurrisOmnia-GW (Mon 2023-09-25 05:56:04 UTC): pl2rld[11669]: [OpenSync] TRACEBACK: MAIN: [fd 10] Received LOG, sev 7, module 5, len 77, "MON upd: MOD table: Wifi_Inet_State row: 1c56a735-fd82-429d-bd30-a55f4234aa5f" CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. Parameter 1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode type: string, value: bridge-static <=============================================================> [INFO][2024-12-23 11:58:47,460][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:58:47,460][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli response : Broadcast message from systemd-journald@TurrisOmnia-GW (Mon 2023-09-25 05:56:04 UTC): pl2rld[11669]: [OpenSync] TRACEBACK: MAIN: [fd 12] Received LOG, sev 7, module 1, len 45, "inet_eth: brlan0: Service IPv4 ready -> stop."  Broadcast message from systemd-journald@TurrisOmnia-GW (Mon 2023-09-25 05:56:04 UTC): pl2rld[11669]: [OpenSync] TRACEBACK: MAIN: [fd 12] Received LOG, sev 7, module 1, len 45, "inet_base: brlan0: Service IPv4 ready -> stop"  Broadcast message from systemd-journald@TurrisOmnia-GW (Mon 2023-09-25 05:56:04 UTC): pl2rld[11669]: [OpenSync] TRACEBACK: MAIN: [fd 10] Received LOG, sev 7, module 5, len 77, "MON upd: MOD table: Wifi_Inet_State row: 1c56a735-fd82-429d-bd30-a55f4234aa5f" CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. Parameter 1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode type: string, value: bridge-static [INFO][2024-12-23 11:58:47,461][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli param value : bridge-static [INFO][2024-12-23 11:58:47,461][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Response : [tableName=null, index=null, name=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode, protocolSpecificParamName=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode, value=bridge-static, datatype=null, statusCode=0] [INFO][2024-12-23 11:58:47,461][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - valueToBePassed: bridge-static [INFO][2024-12-23 11:58:47,462][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - response: bridge-static [INFO][2024-12-23 11:58:47,467][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Pattern Found = bridge-static [INFO][2024-12-23 11:58:47,467][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - bridge-static [INFO][2024-12-23 11:58:47,472][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - is patter found for bridge-static in target string = true [INFO][2024-12-23 11:58:47,473][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Status of getting WebPaParameter values: true [INFO][2024-12-23 11:58:47,473][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 1: ACTUAL : TR181 command to enable bridge mode successful [INFO][2024-12-23 11:58:47,473][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:58:47,474][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-MESH-WIFI-003] [step Number : s1][ Execution status : PASS] [Error Message : ] [INFO][2024-12-23 11:58:47,476][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-23 11:58:47,477][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Fetching device property: FIRMWARE_VERSION [INFO][2024-12-23 11:58:47,483][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:58:47,495][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:58:47,496][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Getting value for property: FIRMWARE_VERSION [INFO][2024-12-23 11:58:47,496][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Value: Rpi-RDKB [INFO][2024-12-23 11:58:47,496][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Device property name: FIRMWARE_VERSION value obtained: Rpi-RDKB [INFO][2024-12-23 11:58:47,502][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-MESH-WIFI-003","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-MESH-WIFI-1003"} [ERROR][2024-12-23 11:58:47,516][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:58:47,516][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-23 11:58:47,517][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:58:47,518][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 2: DESCRIPTION : Check for LanMode value set to bridge mode log message [INFO][2024-12-23 11:58:47,519][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 2: ACTION : Execute grep command for Lan mode set to (2) log message in PAMlog [INFO][2024-12-23 11:58:47,519][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 2: EXPECTED : Log message for bridge mode set is present [INFO][2024-12-23 11:58:47,519][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:58:47,519][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - COMMAND TO BE EXECUTED: grep -i "RDKB_LAN_CONFIG_CHANGED: Setting new LanMode value (bridge-dhcp(1),bridge-static(2),router(3),full-bridge-static(4)) as (2)..." /rdklogs/logs/PAMlog.txt.0 [INFO][2024-12-23 11:59:07,520][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entering executeCommandUsingSsh method [INFO][2024-12-23 11:59:07,521][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DutIP:192.168.2.54 [INFO][2024-12-23 11:59:07,537][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:07,747][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: grep -i "RDKB_LAN_CONFIG_CHANGED: Setting new LanMode value (bridge-dhcp(1),bridge-static(2),router(3),full-bridge-static(4)) as (2)..." /rdklogs/logs/PAMlog.txt.0 [INFO][2024-12-23 11:59:07,747][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:07,869][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> 230925-05:37:46.910214 [mod=PAM, lvl=WARN] [tid=2188] RDKB_LAN_CONFIG_CHANGED: Setting new LanMode value (bridge-dhcp(1),bridge-static(2),router(3),full-bridge-static(4)) as (2)... 230925-05:55:59.593712 [mod=PAM, lvl=WARN] [tid=2188] RDKB_LAN_CONFIG_CHANGED: Setting new LanMode value (bridge-dhcp(1),bridge-static(2),router(3),full-bridge-static(4)) as (2)... <=============================================================> [INFO][2024-12-23 11:59:07,871][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exiting executeCommandUsingSsh method [INFO][2024-12-23 11:59:07,877][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - SEARCH RESPONSE FOR - RDKB_LAN_CONFIG_CHANGED: Setting new LanMode value (bridge-dhcp(1),bridge-static(2),router(3),full-bridge-static(4)) as (2)... IN THE LOG FILE - /rdklogs/logs/PAMlog.txt.0 IS : 230925-05:37:46.910214 [mod=PAM, lvl=WARN] [tid=2188] RDKB_LAN_CONFIG_CHANGED: Setting new LanMode value (bridge-dhcp(1),bridge-static(2),router(3),full-bridge-static(4)) as (2)... 230925-05:55:59.593712 [mod=PAM, lvl=WARN] [tid=2188] RDKB_LAN_CONFIG_CHANGED: Setting new LanMode value (bridge-dhcp(1),bridge-static(2),router(3),full-bridge-static(4)) as (2)... [INFO][2024-12-23 11:59:07,877][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 2: ACTUAL : Log message for bridge mode set is present [INFO][2024-12-23 11:59:07,877][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:07,878][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-MESH-WIFI-003] [step Number : s2][ Execution status : PASS] [Error Message : ] [INFO][2024-12-23 11:59:07,878][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-23 11:59:07,879][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-MESH-WIFI-003","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-MESH-WIFI-1003"} [ERROR][2024-12-23 11:59:07,892][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:07,892][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-23 11:59:07,892][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:07,892][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 3: DESCRIPTION : Check for Setting Mesh to disabled when bridge mode enabled log message [INFO][2024-12-23 11:59:07,893][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 3: ACTION : Execute grep command for setting mesh to disabled log message in PAMlog [INFO][2024-12-23 11:59:07,893][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 3: EXPECTED : Log message for setting mesh to disabled is present [INFO][2024-12-23 11:59:07,893][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:07,893][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - COMMAND TO BE EXECUTED: grep -i "Setting MESH to disabled as LanMode is changed to Bridge mode" /rdklogs/logs/PAMlog.txt.0 [INFO][2024-12-23 11:59:27,894][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entering executeCommandUsingSsh method [INFO][2024-12-23 11:59:27,894][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DutIP:192.168.2.54 [INFO][2024-12-23 11:59:27,909][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:28,116][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: grep -i "Setting MESH to disabled as LanMode is changed to Bridge mode" /rdklogs/logs/PAMlog.txt.0 [INFO][2024-12-23 11:59:28,116][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:28,239][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> 230925-05:37:49.585690 [mod=PAM, lvl=WARN] [tid=2188] Setting MESH to disabled as LanMode is changed to Bridge mode 230925-05:56:03.359001 [mod=PAM, lvl=WARN] [tid=2188] Setting MESH to disabled as LanMode is changed to Bridge mode <=============================================================> [INFO][2024-12-23 11:59:28,239][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exiting executeCommandUsingSsh method [INFO][2024-12-23 11:59:28,240][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - SEARCH RESPONSE FOR - Setting MESH to disabled as LanMode is changed to Bridge mode IN THE LOG FILE - /rdklogs/logs/PAMlog.txt.0 IS : 230925-05:37:49.585690 [mod=PAM, lvl=WARN] [tid=2188] Setting MESH to disabled as LanMode is changed to Bridge mode 230925-05:56:03.359001 [mod=PAM, lvl=WARN] [tid=2188] Setting MESH to disabled as LanMode is changed to Bridge mode [INFO][2024-12-23 11:59:28,240][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 3: ACTUAL : Log message for setting mesh to disabled is present [INFO][2024-12-23 11:59:28,240][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:28,240][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-MESH-WIFI-003] [step Number : s3][ Execution status : PASS] [Error Message : ] [INFO][2024-12-23 11:59:28,241][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-23 11:59:28,242][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-MESH-WIFI-003","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-MESH-WIFI-1003"} [ERROR][2024-12-23 11:59:28,254][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:28,254][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-23 11:59:28,255][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:28,255][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 4: DESCRIPTION : Poll for Mesh wifi has been disabled log message [INFO][2024-12-23 11:59:28,255][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 4: ACTION : Execute grep command for Meshwifi disabled log message in MeshAgentLog [INFO][2024-12-23 11:59:28,256][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 4: EXPECTED : Log message for Meshwifi disabled is present [INFO][2024-12-23 11:59:28,256][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:28,256][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Command to be executed: grep -i "MESH_STATUS:disabled" /rdklogs/logs/MeshAgentLog.txt.0 [INFO][2024-12-23 11:59:28,257][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - command to be executed to get device properties is cat /etc/device.properties | grep [INFO][2024-12-23 11:59:28,257][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entering executeCommandUsingSsh method [INFO][2024-12-23 11:59:28,257][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DutIP:192.168.2.54 [INFO][2024-12-23 11:59:28,272][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:28,510][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: cat /etc/device.properties | grep ATOM_SYNC [INFO][2024-12-23 11:59:28,510][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:28,633][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> <=============================================================> [INFO][2024-12-23 11:59:28,633][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exiting executeCommandUsingSsh method [INFO][2024-12-23 11:59:28,633][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entering executeCommandUsingSsh method [INFO][2024-12-23 11:59:28,634][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DutIP:192.168.2.54 [INFO][2024-12-23 11:59:28,648][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:28,891][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: cat /etc/device.properties | grep ATOM_SYNC [INFO][2024-12-23 11:59:28,891][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:29,014][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> <=============================================================> [INFO][2024-12-23 11:59:29,014][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exiting executeCommandUsingSsh method [INFO][2024-12-23 11:59:29,014][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entering executeCommandUsingSsh method [INFO][2024-12-23 11:59:29,014][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DutIP:192.168.2.54 [INFO][2024-12-23 11:59:29,025][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:29,234][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: cat /etc/device.properties | grep ATOM_SYNC [INFO][2024-12-23 11:59:29,234][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:29,356][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> <=============================================================> [INFO][2024-12-23 11:59:29,357][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exiting executeCommandUsingSsh method [INFO][2024-12-23 11:59:29,360][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entering executeCommandUsingSsh method [INFO][2024-12-23 11:59:29,360][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DutIP:192.168.2.54 [INFO][2024-12-23 11:59:29,375][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:29,630][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: grep -i "MESH_STATUS:disabled" /rdklogs/logs/MeshAgentLog.txt.0 [INFO][2024-12-23 11:59:29,630][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:29,753][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> grep: /rdklogs/logs/MeshAgentLog.txt.0: Input/output error <=============================================================> [INFO][2024-12-23 11:59:29,753][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exiting executeCommandUsingSsh method [INFO][2024-12-23 11:59:29,754][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Response after searching grep: /rdklogs/logs/MeshAgentLog.txt.0: Input/output error [INFO][2024-12-23 11:59:29,754][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 4: ACTUAL : Log message for Meshwifi disabled is present [INFO][2024-12-23 11:59:29,754][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:29,755][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-MESH-WIFI-003] [step Number : s4][ Execution status : PASS] [Error Message : ] [INFO][2024-12-23 11:59:29,755][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-23 11:59:29,756][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-MESH-WIFI-003","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-MESH-WIFI-1003"} [ERROR][2024-12-23 11:59:29,769][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:29,769][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-23 11:59:29,769][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:29,770][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 5: DESCRIPTION : Verify Mesh Enable parameter has been set to false [INFO][2024-12-23 11:59:29,770][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 5: ACTION : Execute webpa command to get value of MeshEnable parameter [INFO][2024-12-23 11:59:29,770][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 5: EXPECTED : Value of MeshEnable parameter is false [INFO][2024-12-23 11:59:29,770][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:29,771][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:59:29,771][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:59:29,771][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-23 11:59:29,771][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-23 11:59:29,771][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to execute commands [INFO][2024-12-23 11:59:29,771][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:29,785][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:30,024][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable [INFO][2024-12-23 11:59:30,024][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:30,154][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:30,155][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:30,155][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:30,155][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli param value : false [INFO][2024-12-23 11:59:30,155][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:30,156][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPa response for given parameter is: false [INFO][2024-12-23 11:59:30,156][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - STEP 5: ACTUAL : Value of MeshEnable parameter is false [INFO][2024-12-23 11:59:30,157][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ********************************************************************************** [INFO][2024-12-23 11:59:30,157][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - [STB MAC : D8:58:D7:00:A5:D6][ Manual test ID : TC-RDKB-MESH-WIFI-003] [step Number : s5][ Execution status : PASS] [Error Message : ] [INFO][2024-12-23 11:59:30,157][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Entered into updateExecutionStatus method [INFO][2024-12-23 11:59:30,158][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-MESH-WIFI-003","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-MESH-WIFI-1003"} [ERROR][2024-12-23 11:59:30,169][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:30,170][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Exited from updateExecutionStatus method [INFO][2024-12-23 11:59:30,170][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ################### STARTING POST-CONFIGURATIONS ################### [INFO][2024-12-23 11:59:30,170][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - POST-CONDITION STEPS [INFO][2024-12-23 11:59:30,170][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - POST-CONDITION : DESCRIPTION : Disable bridge mode and mesh wifi using webpa [INFO][2024-12-23 11:59:30,170][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - POST-CONDITION : ACTION : Execute webpa commands to set LanManagement parameter to router and MeshEnable parameter to false [INFO][2024-12-23 11:59:30,170][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - POST-CONDITION : EXPECTED : Post condition executed successfully [INFO][2024-12-23 11:59:30,170][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:59:30,171][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:59:30,171][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-23 11:59:30,171][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-23 11:59:30,171][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to execute commands [INFO][2024-12-23 11:59:30,171][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:30,185][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:30,409][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT getv Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode [INFO][2024-12-23 11:59:30,410][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:30,533][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. Parameter 1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode type: string, value: bridge-static <=============================================================> [INFO][2024-12-23 11:59:30,533][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:30,533][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. Parameter 1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode type: string, value: bridge-static [INFO][2024-12-23 11:59:30,534][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli param value : bridge-static [INFO][2024-12-23 11:59:30,534][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Response : [tableName=null, index=null, name=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode, protocolSpecificParamName=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode, value=bridge-static, datatype=null, statusCode=0] [INFO][2024-12-23 11:59:30,534][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPA get successful [INFO][2024-12-23 11:59:30,534][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPa data type: STRING [INFO][2024-12-23 11:59:30,535][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 parameters status code added 0 [INFO][2024-12-23 11:59:30,535][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:59:30,535][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:59:30,535][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli Command to be executed: dmcli eRT setv Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode string router [INFO][2024-12-23 11:59:30,535][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:30,549][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:30,770][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT setv Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode string router [INFO][2024-12-23 11:59:30,770][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:34,897][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. <=============================================================> [INFO][2024-12-23 11:59:34,897][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:34,898][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. [INFO][2024-12-23 11:59:34,898][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Response : {Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode=Success} [INFO][2024-12-23 11:59:34,898][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ENDING METHOD: setWebPaParameterValues : success [INFO][2024-12-23 11:59:34,898][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - RESPONSE CODE: 0 [INFO][2024-12-23 11:59:34,899][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - RESPONSE MESSAGE: success [INFO][2024-12-23 11:59:34,899][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WEBPA PARAM - Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode SET WITH VALUE - router IS SUCCESSFUL: true [INFO][2024-12-23 11:59:34,899][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPA set successful [INFO][2024-12-23 11:59:34,899][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:59:34,899][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:59:34,899][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-23 11:59:34,899][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-23 11:59:34,900][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to execute commands [INFO][2024-12-23 11:59:34,900][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:34,914][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:35,495][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT getv Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode [INFO][2024-12-23 11:59:35,495][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:35,618][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - <=========================== RESPONSE =======================> CR component name is: eRT.com.cisco.spvtg.ccsp.CR subsystem_prefix eRT. getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. Parameter 1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode type: string, value: router <=============================================================> [INFO][2024-12-23 11:59:35,618][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:35,618][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode Execution succeed. Parameter 1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode type: string, value: router [INFO][2024-12-23 11:59:35,619][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli param value : router [INFO][2024-12-23 11:59:35,619][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Response : [tableName=null, index=null, name=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode, protocolSpecificParamName=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanMode, value=router, datatype=null, statusCode=0] [INFO][2024-12-23 11:59:35,619][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPA get after set successful [INFO][2024-12-23 11:59:35,619][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Successfully set bridge mode to false [INFO][2024-12-23 11:59:35,619][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method configured in Automatics Props: null [INFO][2024-12-23 11:59:35,620][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method going to use: DMCLI [INFO][2024-12-23 11:59:35,620][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - TR181 Access Method: DMCLI [INFO][2024-12-23 11:59:35,620][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to find protocol specific names [INFO][2024-12-23 11:59:35,620][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going to execute commands [INFO][2024-12-23 11:59:35,620][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - About to create SSH connection to DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:35,633][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Default SSH custom port is set [INFO][2024-12-23 11:59:36,154][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_xOpsDeviceMgmt.Mesh.Enable [INFO][2024-12-23 11:59:36,154][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Conn is not null sendRecive: [INFO][2024-12-23 11:59:36,277][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:36,277][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Closing SSH connection from DeviceIP:192.168.2.54 [INFO][2024-12-23 11:59:36,278][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:36,278][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Dmcli param value : false [INFO][2024-12-23 11:59:36,278][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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-23 11:59:36,278][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - WebPA get successful [INFO][2024-12-23 11:59:36,279][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Successfully set mesh wifi to false [INFO][2024-12-23 11:59:36,279][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - POST-CONDITION : ACTUAL : Post condition executed successfully [INFO][2024-12-23 11:59:36,279][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - POST-CONFIGURATIONS : FINAL STATUS - true [INFO][2024-12-23 11:59:36,279][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ################### COMPLETED POST-CONFIGURATIONS ################### [INFO][2024-12-23 11:59:36,279][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ENDING TEST CASE: TC-RDKB-MESH-WIFI-1003 [INFO][2024-12-23 11:59:36,279][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - ####################################################################################### [INFO][2024-12-23 11:59:36,284][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - >>>[AFTER_METHOD]: Clear device trace buffer for D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:59:36,292][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-MESH-WIFI-1003/D858D700A5D6/settoptrace/ [INFO][2024-12-23 11:59:36,294][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - created a new directory true [INFO][2024-12-23 11:59:36,294][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - New trace location from the strings /var/lib/jenkins/workspace/JOB1/target/settoptrace/D858D700A5D6settop_trace.log [INFO][2024-12-23 11:59:36,295][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Writing stated from line [INFO][2024-12-23 11:59:36,440][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - [ HTML LOG PARSER ] : End of log parser Mon Dec 23 11:59:36 IST 2024 [INFO][2024-12-23 11:59:36,440][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-MESH-WIFI-1003/D858D700A5D6/logs/ [INFO][2024-12-23 11:59:36,441][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - created a new directory true [INFO][2024-12-23 11:59:36,441][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Destination Summary file doesn't exist. Creating one! [INFO][2024-12-23 11:59:36,441][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Writing stated from line [INFO][2024-12-23 11:59:36,483][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - [ HTML LOG PARSER ] : End of log parser Mon Dec 23 11:59:36 IST 2024 [INFO][2024-12-23 11:59:36,483][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - >>>[AFTER_METHOD]: Sending test execution completion time for D8:58:D7:00:A5:D6 [INFO][2024-12-23 11:59:36,484][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm [INFO][2024-12-23 11:59:36,484][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - {"jobId":456,"automationId":"TC-RDKB-MESH-WIFI-1003","macAddress":"D8:58:D7:00:A5:D6","startDateTimeEST":0,"endDateTimeEST":1734935376483} [INFO][2024-12-23 11:59:36,494][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Capture execution time - Response : HTTP/1.1 200 [INFO][2024-12-23 11:59:36,495][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Restarting Trace in after method [INFO][2024-12-23 11:59:36,496][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Is trace required for connected gateway: false [INFO][2024-12-23 11:59:36,496][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Is trace required for connected gateway: false [INFO][2024-12-23 11:59:36,496][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Going for if build changed : Test Type : GROUP_OR_AUTOID [INFO][2024-12-23 11:59:36,496][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - Skipping build change verification as partner specific initialization is not configured. [INFO][2024-12-23 11:59:36,496][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : D858D700A5D6] - >>>[AFTER_METHOD]: Build Change Status: NO_CHANGE [INFO][2024-12-23 11:59:36,496][D8:58:D7:00:A5:D6[TC-RDKB-MESH-WIFI-1003 : 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@59d3976a]org.testng.TestRunner@4c5c342a 1507039082 AutomaticsTestBase.performBeforeMethodInit([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@59d3976a][Ljava.lang.Object;@44ed14a4 [TestResult name={null} status=CREATED method=BroadBandMeshWifiTest.testVerifyBridgeModeEnabledUsingTr69WhenMeshEnabled(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@59d3976a] output={null}] 1507039082 BroadBandMeshWifiTest.testVerifyBridgeModeEnabledUsingTr69WhenMeshEnabled(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@59d3976a]com.automatics.device.Device@6401e33e 1507039082 AutomaticsTestBase.performAfterMethodCleanup([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@59d3976a][Ljava.lang.Object;@44ed14a4 [TestResult name=testVerifyBridgeModeEnabledUsingTr69WhenMeshEnabled status=SUCCESS method=BroadBandMeshWifiTest.testVerifyBridgeModeEnabledUsingTr69WhenMeshEnabled(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandMeshWifiTest@59d3976a] output={null}] 1507039082 ===== PASSED: testVerifyBridgeModeEnabledUsingTr69WhenMeshEnabled(com.automatics.device.Device@6401e33e) =============================================== MyTest Tests run: 1, Failures: 0, Skips: 0 =============================================== [INFO][2024-12-23 11:59:36,539][[com.automatics.executor.Starter.main()] - Finished testing class: org.testng.TestRunner [INFO][2024-12-23 11:59:36,540][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Performing after suite cleanup [INFO][2024-12-23 11:59:36,540][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured. [INFO][2024-12-23 11:59:36,540][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Locked devices after suite execution 1 [INFO][2024-12-23 11:59:36,540][[com.automatics.executor.Starter.main()] - Value of intial testType : QUICK [INFO][2024-12-23 11:59:36,540][[com.automatics.executor.Starter.main()] - Value of TestType provided by job : GROUP_OR_AUTOID [INFO][2024-12-23 11:59:36,541][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Verifying if build changed after test [INFO][2024-12-23 11:59:36,541][[com.automatics.executor.Starter.main()] - Skipping build change verification as partner specific initialization is not configured. [INFO][2024-12-23 11:59:36,541][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Build Change Status: NO_CHANGE [INFO][2024-12-23 11:59:36,541][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false [ERROR][2024-12-23 11:59:36,541][[PollingThread_26_(D8:58:D7:00:A5:D6)] - Sleep interrupted sleep interrupted [INFO][2024-12-23 11:59:36,543][[com.automatics.executor.Starter.main()] - Connection instance is Null [INFO][2024-12-23 11:59:36,543][[com.automatics.executor.Starter.main()] - Connection instance is Null [INFO][2024-12-23 11:59:36,543][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false [INFO][2024-12-23 11:59:36,543][[com.automatics.executor.Starter.main()] - Skipping parter specific after suite clean up as it is not configured. [ERROR][2024-12-23 11:59:36,543][[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-23 11:59:36,543][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Connection instance is Null [INFO][2024-12-23 11:59:36,544][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Connection instance is Null [INFO][2024-12-23 11:59:36,544][[ConnectionThread_21 (D8:58:D7:00:A5:D6)] - Exited from readOutputFromChannel method [INFO][2024-12-23 11:59:36,547][[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-23 11:59:36,602][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:58:D7:00:A5:D6"} [INFO][2024-12-23 11:59:36,604][[com.automatics.executor.Starter.main()] - Successfully unlocked RDKB (D8:58:D7:00:A5:D6) mapped components - [INFO][2024-12-23 11:59:36,604][[com.automatics.executor.Starter.main()] - SETTOP - D8:58:D7:00:A5:D6 RELEASED. [INFO][2024-12-23 11:59:36,605][[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":1734935257549,"completionTime":1734935376605,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"Rpi-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}} [INFO][2024-12-23 11:59:36,619][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [INFO][2024-12-23 11:58:10,715][456][D8:58:D7:00:A5:D6|TestNG-PoolService-0|com.automatics.executor.AutomaticsTestListener:onTestStart:110] STARTED - testVerifyBridgeModeEnabledUsingTr69WhenMeshEnabled - com.automatics.device.Device@6401e33e [INFO][2024-12-23 11:59:36,620][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [DEBUG][2024-12-23 11:58:10,716][456][D8:58:D7:00:A5:D6|TC-RDKB-MESH-WIFI-1003 : D858D700A5D6|com.automatics.executor.AutomaticsTestListener:onTestStart:150] Started appending log =============================================== MySuite Total tests run: 1, Passes: 1, Failures: 0, Skips: 0 =============================================== [INFO][2024-12-23 11:59:36,799][[com.automatics.executor.Starter.main()] - Updating final execution status to Automatics [INFO][2024-12-23 11:59:36,799][[Thread-72] - ============= MESSAGE SEND TO AUTOMATICS ============== [INFO][2024-12-23 11:59:36,800][[Thread-72] - Tested build name : Rpi-RDKB [INFO][2024-12-23 11:59:36,800][[Thread-72] - Final execution status : COMPLETED [INFO][2024-12-23 11:59:36,800][[Thread-72] - Final successful device list : ["D8:58:D7:00:A5:D6"] [INFO][2024-12-23 11:59:36,800][[Thread-72] - Job Id : 456 [INFO][2024-12-23 11:59:36,800][[Thread-72] - ============================================================== [INFO][2024-12-23 11:59:36,800][[Thread-72] - >>>[INIT]: Automatics JSON -> {"status":"COMPLETED","service":"","buildImageName":"Rpi-RDKB","settopList":["D8:58:D7:00:A5:D6"],"startTime":1734935257549,"completionTime":1734935376605,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"Rpi-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}} [INFO][2024-12-23 11:59:36,801][[Thread-72] - http://192.168.2.59:8080/Automatics/executionResponse.htm [INFO][2024-12-23 12:01:36,964][[Thread-72] - HTTP STATUS LINE : HTTP/1.1 200 [INFO][2024-12-23 12:01:36,964][[Thread-72] - HTTP STATUS CODE : 200 [INFO][2024-12-23 12:01:36,965][[com.automatics.executor.Starter.main()] - ******** EXECUTION COMPLETED ********* [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 04:17 min [INFO] Finished at: 2024-12-23T12:01:36+05:30 [INFO] ------------------------------------------------------------------------ Inside Add Shutdown Hook [INFO][2024-12-23 12:01:36,972][[Thread-1] - Bean testInitializer is not configured. [INFO][2024-12-23 12:01:36,972][[Thread-1] - Closing partner application context [INFO][2024-12-23 12:01:36,974][[Thread-1] - Closing core application context [JOB1] $ /bin/sh -xe /tmp/jenkins7047824273359129391.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