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] 
[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 ---
17:51:20,530 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback-test.xml] at [jar:file:/root/.m2/repository/com/automatics/apps/automatics-core/2.27.0/automatics-core-2.27.0.jar!/logback-test.xml]
17:51:20,539 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@8adc8d - URL [jar:file:/root/.m2/repository/com/automatics/apps/automatics-core/2.27.0/automatics-core-2.27.0.jar!/logback-test.xml] is not of type file
17:51:20,643 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
17:51:20,646 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdOutAppender]
17:51:20,652 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@7:13 - no applicable action for [onMatch], current ElementPath  is [[configuration][appender][filter][onMatch]]
17:51:20,652 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@8:16 - no applicable action for [onMismatch], current ElementPath  is [[configuration][appender][filter][onMismatch]]
17:51:20,653 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:51:20,668 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
17:51:20,670 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [deviceSiftingAppender]
17:51:20,672 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@18:13 - no applicable action for [onMatch], current ElementPath  is [[configuration][appender][filter][onMatch]]
17:51:20,672 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@19:16 - no applicable action for [onMismatch], current ElementPath  is [[configuration][appender][filter][onMismatch]]
17:51:20,674 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
17:51:20,677 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
17:51:20,677 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [TraceLogger]
17:51:20,678 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
17:51:20,678 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
17:51:20,678 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SerialTrace]
17:51:20,678 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
17:51:20,679 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
17:51:20,679 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [crash-analysis]
17:51:20,679 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
17:51:20,680 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.apache.http] to false
17:51:20,680 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache.http level set to INFO
17:51:20,680 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[org.apache.http]
17:51:20,681 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ConnectionTrace] to TRACE
17:51:20,681 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ConnectionTrace] to false
17:51:20,681 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [TraceLogger] to Logger[ConnectionTrace]
17:51:20,681 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [SerialTrace] to TRACE
17:51:20,681 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [SerialTrace] to false
17:51:20,681 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SerialTrace] to Logger[SerialTrace]
17:51:20,681 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [crash-analysis] to DEBUG
17:51:20,681 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [crash-analysis] to false
17:51:20,681 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [crash-analysis] to Logger[crash-analysis]
17:51:20,681 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdOutAppender] to Logger[ROOT]
17:51:20,681 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[ROOT]
17:51:20,681 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
17:51:20,682 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@384919c1 - Registering current configuration as safe fallback point
[INFO][2024-12-20 17:51:20,683][[com.automatics.executor.Starter.main()] - Starting the execution process for the given test cases.
17:51:20,685 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:51:20,688 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-test-suite]
17:51:20,690 |-INFO in ch.qos.logback.core.FileAppender[FILE-test-suite] - File property is set to [target/logs/test-suite.log]
[INFO][2024-12-20 17:51:20,704][[com.automatics.executor.Starter.main()] - Initializing application context partner-applicationContext.xml
[INFO][2024-12-20 17:51:21,035][[com.automatics.executor.Starter.main()] - Initializing application context applicationContext.xml
[INFO][2024-12-20 17:51:21,077][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.12:8080/AutomaticsProps/automatics.properties
[INFO][2024-12-20 17:51:21,110][[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-20 17:51:21,113][[com.automatics.executor.Starter.main()] - 

***************** Automatics BUILD PARAMETERS *****************
[INFO][2024-12-20 17:51:21,121][[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-20 17:51:21,121][[com.automatics.executor.Starter.main()] - Shut Down Hook Attached.
[INFO][2024-12-20 17:51:21,125][[com.automatics.executor.Starter.main()] - Start Execution Test Suite
[INFO][2024-12-20 17:51:21,126][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-12-20 17:51:21,127][[com.automatics.executor.Starter.main()] - Cleaning the trace log directory
[INFO][2024-12-20 17:51:21,149][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.12:8080/AutomaticsProps/automatics.properties
[INFO][2024-12-20 17:51:21,153][[com.automatics.executor.Starter.main()] - >>>[INIT]: Validating config files
[INFO][2024-12-20 17:51:21,154][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.12:8080/AutomaticsProps/deviceProps.json
[INFO][2024-12-20 17:51:21,309][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.12:8080/AutomaticsProps/deviceProps.json
[INFO][2024-12-20 17:51:21,315][[com.automatics.executor.Starter.main()] - frameworkSupportedModels=Rpi-RDKB,Rpi-RDKV,
[INFO][2024-12-20 17:51:21,316][[com.automatics.executor.Starter.main()] - rdkvGWModels=
[INFO][2024-12-20 17:51:21,316][[com.automatics.executor.Starter.main()] - rdkvCLModels=Rpi-RDKV,
[INFO][2024-12-20 17:51:21,317][[com.automatics.executor.Starter.main()] - rdkbModels=Rpi-RDKB,
[INFO][2024-12-20 17:51:21,317][[com.automatics.executor.Starter.main()] - rdkcModels=
[INFO][2024-12-20 17:51:21,331][[com.automatics.executor.Starter.main()] - Reading implementation from core for deviceProvider
[INFO][2024-12-20 17:51:21,347][[com.automatics.executor.Starter.main()] - Is Account based test: false
[INFO][2024-12-20 17:51:21,349][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-12-20 17:51:21,726][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8C:13  Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-12-20 17:51:21,867][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.47","hostIp6Address":null,"clientIpAddress":"192.168.2.47","model":"Rpi-RDKB","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"D8:3A:DD:13:8C:13","mtaMacAddress":"D8:3A:DD:13:8C:13","mtaIpAddress":"","ecmMacAddress":"D8:3A:DD:13:8C:13","ecmIpAddress":"192.168.2.47","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.47","password":"root123","connectedClients":"192.168.2.57","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","nodePort":"22","connectionType":"SSH","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.fasterxml.jackson.databind.util.ClassUtil (file:/root/.m2/repository/com/fasterxml/jackson/core/jackson-databind/2.4.1/jackson-databind-2.4.1.jar) to field java.time.format.DateTimeFormatter.locale
WARNING: Please consider reporting this to the maintainers of com.fasterxml.jackson.databind.util.ClassUtil
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
[INFO][2024-12-20 17:51:21,926][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-12-20 17:51:21,927][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model Rpi-RDKB
[INFO][2024-12-20 17:51:21,927][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model Rpi-RDKB
[INFO][2024-12-20 17:51:21,928][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model Rpi-RDKB to  automatics model Rpi-RDKB
[INFO][2024-12-20 17:51:21,928][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device
[INFO][2024-12-20 17:51:21,946][[pool-2-thread-1] - INIT-{} Check if device already lockedD8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:21,973][[pool-2-thread-1] - Fetching lock status for device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/allocationStatus
[INFO][2024-12-20 17:51:21,982][[pool-2-thread-1] - Response: {"allocationStatus":"AVAILABLE"}
[INFO][2024-12-20 17:51:21,990][[pool-2-thread-1] - DeviceConfig allocation status
[INFO][2024-12-20 17:51:21,991][[pool-2-thread-1] - [INIT LOG] : Locking device D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:21,991][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Locking device
[INFO][2024-12-20 17:51:22,002][[pool-2-thread-1] - Locking device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/lock
[INFO][2024-12-20 17:51:22,059][[pool-2-thread-1] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-12-20 17:51:22,069][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Lock success
[INFO][2024-12-20 17:51:22,070][[pool-2-thread-1] - Setting access mechanism for device D8:3A:DD:13:8C:13 SSH
[INFO][2024-12-20 17:51:22,070][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Setting access method SSH
[INFO][2024-12-20 17:51:22,070][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required
[INFO][2024-12-20 17:51:22,070][[pool-2-thread-1] - Setting accessibility check required to false for device D8:3A:DD:13:8C:13 from device config
[INFO][2024-12-20 17:51:22,070][[pool-2-thread-1] - Accessibility check required for device D8:3A:DD:13:8C:13 false 
[INFO][2024-12-20 17:51:22,070][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required is false
[INFO][2024-12-20 17:51:22,071][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Assuming device is accessible
[INFO][2024-12-20 17:51:22,071][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Is device accessible true
[INFO][2024-12-20 17:51:22,071][[pool-2-thread-1] - [INIT LOG] : Wiring device.D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:22,071][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Performing provider wiring
[INFO][2024-12-20 17:51:22,072][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 PowerProvider wiring
[INFO][2024-12-20 17:51:22,072][[pool-2-thread-1] - Reading implementation from core for powerProvider
[INFO][2024-12-20 17:51:22,074][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 DeviceConfig Connection Based TraceProvider wiring
[INFO][2024-12-20 17:51:22,076][[pool-2-thread-1] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-12-20 17:51:22,080][[pool-2-thread-1] - Additional trace support : null
[INFO][2024-12-20 17:51:22,080][[pool-2-thread-1] - settopObj.getModel() = Rpi-RDKB
[INFO][2024-12-20 17:51:22,080][[pool-2-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue
[INFO][2024-12-20 17:51:22,084][[pool-2-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-12-20 17:51:22,084][[pool-2-thread-1] - Crash Analysis not enabled during trace monitoring
[INFO][2024-12-20 17:51:22,086][[pool-2-thread-1] - Serial based trace to be initialized: false
[INFO][2024-12-20 17:51:22,086][[pool-2-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-12-20 17:51:22,089][[pool-2-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:22,089][[pool-2-thread-1] - Dut Added to locked list D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:24,929][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads
[INFO][2024-12-20 17:51:24,930][[com.automatics.executor.Starter.main()] - Collecting dut info
[INFO][2024-12-20 17:51:24,931][[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-20 17:51:24,945][[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-20 17:51:25,555][[com.automatics.executor.Starter.main()] - AutomaticsTapApi instance not available. Creating new instance
[INFO][2024-12-20 17:51:25,556][[com.automatics.executor.Starter.main()] - Creating new instance for AutomaticsTapApi
[INFO][2024-12-20 17:51:25,556][[com.automatics.executor.Starter.main()] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-12-20 17:51:25,593][[com.automatics.executor.Starter.main()] - >>>[BEFORE_SUITE]: Perform before suite initialization
[INFO][2024-12-20 17:51:25,593][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-12-20 17:51:25,593][[com.automatics.executor.Starter.main()] - Adding locked devices to dut object in AutomaticsTestBase
[INFO][2024-12-20 17:51:25,593][[com.automatics.executor.Starter.main()] - Locked Settops: 1
[INFO][2024-12-20 17:51:25,594][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Verifying if build loaded in device as expected
[INFO][2024-12-20 17:51:25,594][[pool-6-thread-1] - [BEFORE-SUITE:]Expected build in device is rdkb-generic-broadband-image_rdkb-2023q1-dunfell_20230925052003
[INFO][2024-12-20 17:51:25,594][[pool-6-thread-1] - [BEFORE-SUITE:]Verifying if build changed before test
[INFO][2024-12-20 17:51:25,595][[pool-6-thread-1] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-12-20 17:51:25,595][[pool-6-thread-1] - [BEFORE-SUITE:]Build Change Status: NO_CHANGE
[INFO][2024-12-20 17:51:25,595][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Build in device is as expected
[INFO][2024-12-20 17:51:25,595][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Setting appropritate build appender based on executionMode
[INFO][2024-12-20 17:51:25,599][[pool-6-thread-1] - Skipping setting of execution mode in device as partner specific initialization is not configured.
[INFO][2024-12-20 17:51:25,599][[pool-6-thread-1] - INIT-D8:3A:DD:13:8C:13 Perform before suite initialization
[INFO][2024-12-20 17:51:25,600][[pool-6-thread-1] - Skipping partner specific before suite initialization as it is not configured.
[INFO][2024-12-20 17:51:25,600][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Starting device connection trace
[INFO][2024-12-20 17:51:25,600][[pool-6-thread-1] - Starting trace with command : tail -F -n 0  /rdklogs/logs/*
[INFO][2024-12-20 17:51:25,607][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Starting reading..D83ADD138C13settop_trace.log,
17:51:25,608 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:51:25,608 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-D83ADD138C13settop_trace.log]
17:51:25,609 |-INFO in ch.qos.logback.core.FileAppender[FILE-D83ADD138C13settop_trace.log] - File property is set to [target/settoptrace/D83ADD138C13settop_trace.log]
[INFO][2024-12-20 17:51:25,609][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Going to connect device for trace monitoring .....!
[INFO][2024-12-20 17:51:25,610][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP Address : 192.168.2.47
[INFO][2024-12-20 17:51:25,610][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP6 Address : null
[INFO][2024-12-20 17:51:25,610][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host MAC Address : D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:25,610][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside Non RDKV client device
[INFO][2024-12-20 17:51:25,620][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Reading server-config.xml from /var/lib/jenkins/workspace/testrepo/server-config.xml
[INFO][2024-12-20 17:51:25,637][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:51:25,945][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:51:25,957][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:51:31,119][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:51:31,126][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connectiongateway iscom.automatics.providers.connection.SshConnection@deaa7b8
[INFO][2024-12-20 17:51:31,126][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - inside null != connectionGateway condition 
[INFO][2024-12-20 17:51:31,126][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - gateWayDeviceInputStream iscom.jcraft.jsch.Channel$MyPipedInputStream@ad8c3ea
[INFO][2024-12-20 17:51:31,128][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside else block
[INFO][2024-12-20 17:51:31,128][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Entered into readOutputFromChannel method
[INFO][2024-12-20 17:51:31,129][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Is RDKV Client trace false
[INFO][2024-12-20 17:51:31,146][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:51:31,286][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 17:51:31,299][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:51:31,453][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 17:51:31,453][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 17:51:31,456][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:51:31,581][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 17:51:31,581][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 17:51:31,590][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 17:51:55,608][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-12-20 17:51:55,609][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-12-20 17:51:58,596][[com.automatics.executor.Starter.main()] - Starting testing on class: org.testng.TestRunner
[INFO][2024-12-20 17:51:58,625][[com.automatics.executor.Starter.main()] - Doing initialization for D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:58,640][[com.automatics.executor.Starter.main()] - Getting device account no. for D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:58,640][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-12-20 17:51:58,651][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8C:13  Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-12-20 17:51:58,664][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.47","hostIp6Address":null,"clientIpAddress":"192.168.2.47","model":"Rpi-RDKB","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"D8:3A:DD:13:8C:13","mtaMacAddress":"D8:3A:DD:13:8C:13","mtaIpAddress":"","ecmMacAddress":"D8:3A:DD:13:8C:13","ecmIpAddress":"192.168.2.47","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.47","connectedClients":"192.168.2.57","password":"root123","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","connectionType":"SSH","nodePort":"22","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"IN_USE","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
[INFO][2024-12-20 17:51:58,678][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-12-20 17:51:58,678][[com.automatics.executor.Starter.main()] - Device account 123456789 for device D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:58,679][[com.automatics.executor.Starter.main()] - Getting device account no. for D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:58,679][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-12-20 17:51:58,684][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8C:13  Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-12-20 17:51:58,695][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.47","hostIp6Address":null,"clientIpAddress":"192.168.2.47","model":"Rpi-RDKB","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"D8:3A:DD:13:8C:13","mtaMacAddress":"D8:3A:DD:13:8C:13","mtaIpAddress":"","ecmMacAddress":"D8:3A:DD:13:8C:13","ecmIpAddress":"192.168.2.47","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.47","password":"root123","connectedClients":"192.168.2.57","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","connectionType":"SSH","nodePort":"22","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"IN_USE","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
[INFO][2024-12-20 17:51:58,708][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-12-20 17:51:58,709][[com.automatics.executor.Starter.main()] - Device account 123456789 for device D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:58,709][[com.automatics.executor.Starter.main()] - Locking connected devices
[INFO][2024-12-20 17:51:58,709][[com.automatics.executor.Starter.main()] - Getting devices for home account 123456789
[INFO][2024-12-20 17:51:58,716][[com.automatics.executor.Starter.main()] - Fetching account details for 123456789  Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getAccountDetails
[INFO][2024-12-20 17:51:58,728][[com.automatics.executor.Starter.main()] - Response: {"id":"","name":"","accountNumber":"123456789","phoneNumber":"","address":"","homeAccountGroup":"","devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.47","hostIp6Address":null,"clientIpAddress":"192.168.2.47","model":"Rpi-RDKB","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"D8:3A:DD:13:8C:13","mtaMacAddress":"D8:3A:DD:13:8C:13","mtaIpAddress":"","ecmMacAddress":"D8:3A:DD:13:8C:13","ecmIpAddress":"192.168.2.47","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.47","password":"root123","connectedClients":"192.168.2.57","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","nodePort":"22","connectionType":"SSH","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"IN_USE","rackGroups":null,"features":null,"components":null,"wanMacAddress":""},{"id":"3853","name":"","hardwareRevision":"","hostMacAddress":"2C:44:FD:18:F9:FE","hostIp4Address":"192.168.2.57","hostIp6Address":null,"clientIpAddress":"192.168.2.57","model":"WINDOWSVM","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"2C:44:FD:18:F9:FE","mtaMacAddress":"","mtaIpAddress":"","ecmMacAddress":"2C:44:FD:18:F9:FE","ecmIpAddress":"192.168.2.57","headend":"HE","gatewayMac":"","extraProperties":{"deviceIp":"192.168.2.57","password":"asdf@1234","ethernetMacAddress":"2C:44:FD:18:F9:FE","osType":"WINDOWS","devicePort":"22","wifiCapability":"Dual Band","connectionType":"Wi-Fi","nodePort":"4444","username":"User","wifiMacAddress":"18:D6:C7:08:C6:C1"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}]}
[INFO][2024-12-20 17:51:58,741][[com.automatics.executor.Starter.main()] - Devices for home account 123456789 are [D8:3A:DD:13:8C:13, 2C:44:FD:18:F9:FE]
[INFO][2024-12-20 17:51:58,741][[com.automatics.executor.Starter.main()] - Obtained all connected device macs for the account
[INFO][2024-12-20 17:51:58,742][[com.automatics.executor.Starter.main()] - INIT-2C:44:FD:18:F9:FE Get device details
[INFO][2024-12-20 17:51:58,747][[com.automatics.executor.Starter.main()] - Fetching device details for 2C:44:FD:18:F9:FE  Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-12-20 17:51:58,756][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3853","name":"","hardwareRevision":"","hostMacAddress":"2C:44:FD:18:F9:FE","hostIp4Address":"192.168.2.57","hostIp6Address":null,"clientIpAddress":"192.168.2.57","model":"WINDOWSVM","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"2C:44:FD:18:F9:FE","mtaMacAddress":"","mtaIpAddress":"","ecmMacAddress":"2C:44:FD:18:F9:FE","ecmIpAddress":"192.168.2.57","headend":"HE","gatewayMac":"","extraProperties":{"deviceIp":"192.168.2.57","password":"asdf@1234","ethernetMacAddress":"2C:44:FD:18:F9:FE","osType":"WINDOWS","devicePort":"22","wifiCapability":"Dual Band","nodePort":"4444","connectionType":"Wi-Fi","wifiMacAddress":"18:D6:C7:08:C6:C1","username":"User"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
[INFO][2024-12-20 17:51:58,767][[com.automatics.executor.Starter.main()] - INIT-2C:44:FD:18:F9:FE Obtained device details
[INFO][2024-12-20 17:51:58,768][[com.automatics.executor.Starter.main()] - >>>[INIT]: No device config mapped for rack model WINDOWSVM. Proceeding with rack model
[INFO][2024-12-20 17:51:58,768][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device
[INFO][2024-12-20 17:51:58,768][[pool-15-thread-1] - INIT-{} Check if device already locked2C:44:FD:18:F9:FE
[INFO][2024-12-20 17:51:58,773][[pool-15-thread-1] - Fetching lock status for device 2C:44:FD:18:F9:FE Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/allocationStatus
[INFO][2024-12-20 17:51:58,780][[pool-15-thread-1] - Response: {"allocationStatus":"AVAILABLE"}
[INFO][2024-12-20 17:51:58,781][[pool-15-thread-1] - DeviceConfig allocation status
[INFO][2024-12-20 17:51:58,781][[pool-15-thread-1] - [INIT LOG] : Locking device 2C:44:FD:18:F9:FE
[INFO][2024-12-20 17:51:58,781][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Locking device
[INFO][2024-12-20 17:51:58,786][[pool-15-thread-1] - Locking device 2C:44:FD:18:F9:FE Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/lock
[INFO][2024-12-20 17:51:58,824][[pool-15-thread-1] - Response: {"status":"SUCCESS","mac":"2C:44:FD:18:F9:FE"}
[INFO][2024-12-20 17:51:58,825][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Lock success
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - Setting default SSH access mechanism for device null
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Setting access method SSH
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Checking if accessibility check required
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - Accessibility check required for device 2C:44:FD:18:F9:FE false 
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Checking if accessibility check required is false
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Assuming device is accessible
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Is device accessible true
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - [INIT LOG] : Wiring device.2C:44:FD:18:F9:FE
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Performing provider wiring
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE PowerProvider wiring
[INFO][2024-12-20 17:51:58,826][[pool-15-thread-1] - Reading implementation from core for powerProvider
[INFO][2024-12-20 17:51:58,827][[pool-15-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-12-20 17:51:58,827][[pool-15-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:51:58,827][[pool-15-thread-1] - Dut Added to locked list 2C:44:FD:18:F9:FE
[INFO][2024-12-20 17:52:01,768][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads
[INFO][2024-12-20 17:52:01,769][[com.automatics.executor.Starter.main()] - Starting trace from provider for connected clients if not already started
[INFO][2024-12-20 17:52:01,769][[com.automatics.executor.Starter.main()] - =========================================================================
[INFO][2024-12-20 17:52:01,769][[com.automatics.executor.Starter.main()] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:52:01,770][[com.automatics.executor.Starter.main()] - Trace not applicable for Non RDK Devices - 2C:44:FD:18:F9:FE
[INFO][2024-12-20 17:52:01,777][[com.automatics.executor.Starter.main()] - NUMBER OF CONNECTED CLIENTS ASSOCIATED WITH DEVICE D8:3A:DD:13:8C:13 IS 1
[INFO][2024-12-20 17:52:01,777][[com.automatics.executor.Starter.main()] - CONNECTED CLIENTS ASSOCIATED WITH DEVICE D8:3A:DD:13:8C:13 IS 2C:44:FD:18:F9:FE 
[INFO][2024-12-20 17:52:01,792][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Perform before method initialization D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:52:01,793][[TestNG-PoolService-0] - Setting dattime in patter yyyy-MM-dd HH:mm:ss
[INFO][2024-12-20 17:52:01,797][[TestNG-PoolService-0] - startTime 2024-12-20 12:22:01
[INFO][2024-12-20 17:52:01,798][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Sending test exection start time to Automatics D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:52:01,800][[TestNG-PoolService-0] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-12-20 17:52:01,801][[TestNG-PoolService-0] - {"jobId":456,"automationId":"TC-RDKB-WIFI-BAND-STEERING-1007","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":1734697321799,"endDateTimeEST":0}
[INFO][2024-12-20 17:52:01,894][[TestNG-PoolService-0] - Capture execution time - Response : HTTP/1.1 200 
[INFO][2024-12-20 17:52:01,894][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Skipping partner specific before method initialization as it is not configured.
17:52:01,895 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
17:52:01,895 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-Rpi-RDKB-D83ADD138C13]
17:52:01,896 |-INFO in ch.qos.logback.core.FileAppender[FILE-Rpi-RDKB-D83ADD138C13] - File property is set to [target/logs/Rpi-RDKB-D83ADD138C13.log]
[INFO][2024-12-20 17:52:01,896][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Extending allocation before method for testType : GROUP_OR_AUTOID  D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:52:01,896][D8:3A:DD:13:8C:13[TestNG-PoolService-0] -  Get the locked status: false
[INFO][2024-12-20 17:52:01,897][D8:3A:DD:13:8C:13[TestNG-PoolService-0] -  Get the locked status: false
[INFO][2024-12-20 17:52:01,897][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-12-20 17:52:01,897][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-12-20 17:52:01,907][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - <a name="com.automatics.rdkb.tests.wifi.BroadBandSteeringTests.testVerifyBandSteeringProcessGetsKilledWhen2GhzDisabled">STARTED - testVerifyBandSteeringProcessGetsKilledWhen2GhzDisabled - com.automatics.device.Device@6c70ac0d</a>
[INFO][2024-12-20 17:52:01,908][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Getting MDC = D83ADD138C13settop_trace.log
[INFO][2024-12-20 17:52:01,908][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Getting MDC = D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:52:01,917][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - #######################################################################################
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STARTING TEST CASE: TC-RDKB-WIFI-BAND-STEERING-107
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TEST DESCRIPTION: verify that Band steering process gets killed when 5GHz Wireless network is disabled
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 1:Enable band steering on AP either via SNMP  
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 2:Make the SSID name for  band 2.4 and 5Ghz radios same  Ex: RDKB for 2.4GHz and RDKBfor 5GHz  
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 3:Connect the wireless client to 2.4GHz band
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 4: run ping to check connectivity between clients  
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 5: Disable  2.4ghz radio using webpa  
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 6: Check the lbd process is not running on the RPI device  
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 7: Enable  2.4ghz radio using webpa  
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 8: Check the lbd process is running on the RPI device  
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - POSTCONDITION : Going to set the old SSID back to the device
[INFO][2024-12-20 17:52:01,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:52:01,919][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-12-20 17:52:01,919][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-12-20 17:52:01,919][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-12-20 17:52:01,920][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-12-20 17:52:01,922][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to execute commands
[INFO][2024-12-20 17:52:01,923][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:01,935][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:52:02,074][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.SSID.1.SSID
[INFO][2024-12-20 17:52:02,074][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:02,197][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.1.SSID
               type:     string,    value: yolo 

<=============================================================>
[INFO][2024-12-20 17:52:02,198][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:02,198][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.1.SSID
               type:     string,    value: yolo
[INFO][2024-12-20 17:52:02,199][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param value : yolo
[INFO][2024-12-20 17:52:02,199][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:02,215][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:52:02,399][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.SSID.2.SSID
[INFO][2024-12-20 17:52:02,400][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:02,522][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.2.SSID
               type:     string,    value: yolo5Ghz 

<=============================================================>
[INFO][2024-12-20 17:52:02,522][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:02,522][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.2.SSID
               type:     string,    value: yolo5Ghz
[INFO][2024-12-20 17:52:02,523][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param value : yolo5Ghz
[INFO][2024-12-20 17:52:02,524][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.WiFi.SSID.10001.SSID, protocolSpecificParamName=Device.WiFi.SSID.1.SSID, value=yolo, datatype=null, statusCode=0, tableName=null, index=null, name=Device.WiFi.SSID.10101.SSID, protocolSpecificParamName=Device.WiFi.SSID.2.SSID, value=yolo5Ghz, datatype=null, statusCode=0]
[INFO][2024-12-20 17:52:02,524][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:52:02,525][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 1: DESCRIPTION : Enable  band steering using SNMP from gateway device  
[INFO][2024-12-20 17:52:02,525][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 1: ACTION : Enabling band steering using SNMP object -rdkbRgDot11BandSteeringEnable [OID- .1.3.6.1.4.1.17270.50.2.2.8.2.0 ] to 1 for true 
[INFO][2024-12-20 17:52:02,525][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 1: EXPECTED : Band steering should  be enabled via SNMP successfully. 
[INFO][2024-12-20 17:52:02,525][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:52:02,538][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Current SNMP protocol: v2
[INFO][2024-12-20 17:52:02,539][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Reading implementation from core for snmpProviderFactory
[INFO][2024-12-20 17:52:02,540][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Snmp protocol version: v2
[INFO][2024-12-20 17:52:02,541][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Is SNMPv3 enabled using appender - false
[INFO][2024-12-20 17:52:02,543][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-12-20 17:52:02,544][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Performing snmpset on device D8:3A:DD:13:8C:13
[INFO][2024-12-20 17:52:02,544][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Target IP is 192.168.2.47
[INFO][2024-12-20 17:52:02,545][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  SNMP PROVIDER versionv2
[INFO][2024-12-20 17:52:02,546][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  SNMP PROVIDER port 161
[INFO][2024-12-20 17:52:02,546][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  SNMP PROVIDER protocol udp
[INFO][2024-12-20 17:52:02,546][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  SNMP PROVIDER V2
[INFO][2024-12-20 17:52:02,547][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  SNMP PROVIDER community private
[INFO][2024-12-20 17:52:02,554][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:02,572][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:52:02,726][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: snmpset -v 2c  -c private -t 10 -OQ udp:192.168.2.47:161 .1.3.6.1.4.1.17270.50.2.2.8.2.0 i 1
[INFO][2024-12-20 17:52:02,726][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:02,849][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
SNMPv2-SMI::enterprises.17270.50.2.2.8.2.0 = 1

<=============================================================>
[INFO][2024-12-20 17:52:02,849][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:02,851][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 1 : ACTUAL :Successfully enabled band steering using snmp 
[INFO][2024-12-20 17:52:02,851][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:52:02,852][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-BAND-STEERING-107] [step Number : s1][ Execution status : PASS] [Error Message : ]
[INFO][2024-12-20 17:52:02,855][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-12-20 17:52:02,856][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Fetching device property: FIRMWARE_VERSION
[INFO][2024-12-20 17:52:02,864][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Fetching device props for D8:3A:DD:13:8C:13 for props [FIRMWARE_VERSION] Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getDeviceProps
[INFO][2024-12-20 17:52:02,876][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Response: {"mac":"D8:3A:DD:13:8C:13","HEAD_END":null,"FIRMWARE_VERSION":"RPI-RDKB","ECM_IP_ADDRESS":null,"ESTB_IP_ADDRESS":null}
[INFO][2024-12-20 17:52:02,876][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Getting value for property: FIRMWARE_VERSION
[INFO][2024-12-20 17:52:02,876][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Value: RPI-RDKB
[INFO][2024-12-20 17:52:02,877][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Device property name: FIRMWARE_VERSION value obtained: RPI-RDKB
[INFO][2024-12-20 17:52:02,880][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-BAND-STEERING-107","stepNumber":"s1","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":true,"partnerName":"","automationId":"TC-RDKB-WIFI-BAND-STEERING-1007"}
[ERROR][2024-12-20 17:52:02,894][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id
[INFO][2024-12-20 17:52:02,894][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-12-20 17:52:02,894][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:52:02,894][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 2: DESCRIPTION : Make the SSID name for  band 2.4 and 5Ghz radios same    
[INFO][2024-12-20 17:52:02,894][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 2: ACTION      : Execute set on webpa Device.WiFi.SSID.10001.SSID  and Device.WiFi.SSID.10101.SSID . Ex: RDKB for 2.4GHz and RDKB for 5GHz 
[INFO][2024-12-20 17:52:02,894][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 2: EXPECTED    : Webpa should return success. 
[INFO][2024-12-20 17:52:02,894][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:52:02,904][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-12-20 17:52:02,905][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 parameters status code  added  0
[INFO][2024-12-20 17:52:02,905][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-12-20 17:52:02,905][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 parameters status code  added  0
[INFO][2024-12-20 17:52:02,905][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-12-20 17:52:02,905][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-12-20 17:52:02,906][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.SSID.1.SSID string RDKB
[INFO][2024-12-20 17:52:02,906][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:02,920][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:52:03,048][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.SSID.1.SSID string RDKB
[INFO][2024-12-20 17:52:03,048][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:03,171][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:52:03,171][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:03,171][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
[INFO][2024-12-20 17:52:03,171][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed
[INFO][2024-12-20 17:52:03,171][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:03,182][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:52:03,290][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: ps | grep -i "onewifi"
[INFO][2024-12-20 17:52:03,291][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:03,413][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
32642 root      0:23 /usr/bin/OneWifi -subsys eRT.
176669 root      0:00 sh -c ps | grep -i "onewifi"
176671 root      0:00 grep -i onewifi

<=============================================================>
[INFO][2024-12-20 17:52:03,413][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:03,413][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - The device has One Wifi : 32642 root      0:23 /usr/bin/OneWifi -subsys eRT.
176669 root      0:00 sh -c ps | grep -i "onewifi"
176671 root      0:00 grep -i onewifi
[INFO][2024-12-20 17:52:03,414][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed for One WIFI
[INFO][2024-12-20 17:52:03,414][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param name: Device.WiFi.SSID.1.SSID 
[INFO][2024-12-20 17:52:03,414][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:52:03,415][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:52:03,429][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:52:03,564][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:52:03,564][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:03,687][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:52:03,687][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

[INFO][2024-12-20 17:52:03,687][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.SSID.2.SSID string RDKB
[INFO][2024-12-20 17:52:03,688][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:03,699][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:52:03,828][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.SSID.2.SSID string RDKB
[INFO][2024-12-20 17:52:03,828][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:03,952][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:52:03,952][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:03,952][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
[INFO][2024-12-20 17:52:03,952][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed
[INFO][2024-12-20 17:52:03,952][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:03,967][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:52:04,095][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: ps | grep -i "onewifi"
[INFO][2024-12-20 17:52:04,096][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:04,218][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
32642 root      0:23 /usr/bin/OneWifi -subsys eRT.
176704 root      0:00 sh -c ps | grep -i "onewifi"
176706 root      0:00 grep -i onewifi

<=============================================================>
[INFO][2024-12-20 17:52:04,219][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:52:04,219][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - The device has One Wifi : 32642 root      0:23 /usr/bin/OneWifi -subsys eRT.
176704 root      0:00 sh -c ps | grep -i "onewifi"
176706 root      0:00 grep -i onewifi
[INFO][2024-12-20 17:52:04,220][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed for One WIFI
[INFO][2024-12-20 17:52:04,220][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param name: Device.WiFi.SSID.2.SSID 
[INFO][2024-12-20 17:52:04,220][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:52:04,220][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:52:04,235][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:52:04,390][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:52:04,391][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:04,513][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:52:04,514][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

[INFO][2024-12-20 17:52:04,514][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Response : {Device.WiFi.SSID.10001.SSID=Success, Device.WiFi.SSID.10101.SSID=Success}
[INFO][2024-12-20 17:52:31,605][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:52:31,750][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 17:52:31,764][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:52:31,888][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 17:52:31,889][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 17:52:31,889][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:52:32,012][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 17:52:32,012][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 17:52:32,013][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 17:53:32,028][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:53:32,188][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 17:53:32,202][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:53:32,313][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 17:53:32,313][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 17:53:32,314][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:53:32,336][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 17:53:32,336][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 17:53:32,336][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 17:54:04,515][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 2 : ACTUAL :Failed to configure same SSID for both radios
[INFO][2024-12-20 17:54:04,516][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:54:04,516][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-BAND-STEERING-107] [step Number : s2][ Execution status : PASS] [Error Message : ]
[INFO][2024-12-20 17:54:04,516][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-12-20 17:54:04,517][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-BAND-STEERING-107","stepNumber":"s2","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":true,"partnerName":"","automationId":"TC-RDKB-WIFI-BAND-STEERING-1007"}
[ERROR][2024-12-20 17:54:04,530][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id
[INFO][2024-12-20 17:54:04,530][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-12-20 17:54:04,530][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:54:04,531][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 3: DESCRIPTION : Connect the wireless clients to WIFI_BAND_2_GHZ
[INFO][2024-12-20 17:54:04,531][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 3: ACTION : Execute Linux :nmcli dev wifi connect <ssid> password <passwd> indows : netsh wlan connect ssid=<ssid> name=<ssid name> 
[INFO][2024-12-20 17:54:04,531][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 3: EXPECTED    : Devices should be connected with WIFI_BAND_2_GHZ wifi network. 
[INFO][2024-12-20 17:54:04,531][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:54:04,531][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STARTING METHOD: getConnectedClientBasedOnCapability()
[INFO][2024-12-20 17:54:04,532][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - client settop: 192.168.2.57
[INFO][2024-12-20 17:54:04,532][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Number of Connected client with Capability Dual band found is 1
[INFO][2024-12-20 17:54:04,549][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - connectedClient.getModel()====WINDOWSVM
[INFO][2024-12-20 17:54:04,549][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Starting Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-12-20 17:54:04,550][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Is WebpaConnectionBroken : false
[INFO][2024-12-20 17:54:04,550][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-12-20 17:54:04,550][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-12-20 17:54:04,550][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-12-20 17:54:04,550][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-12-20 17:54:04,550][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to execute commands
[INFO][2024-12-20 17:54:04,551][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:54:04,564][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:54:04,720][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.SSID.1.SSID
[INFO][2024-12-20 17:54:04,720][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:54:04,843][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.1.SSID
               type:     string,    value: RDKB 

<=============================================================>
[INFO][2024-12-20 17:54:04,843][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:54:04,844][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.1.SSID
               type:     string,    value: RDKB
[INFO][2024-12-20 17:54:04,844][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param value : RDKB
[INFO][2024-12-20 17:54:04,845][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.WiFi.SSID.10001.SSID, protocolSpecificParamName=Device.WiFi.SSID.1.SSID, value=RDKB, datatype=null, statusCode=0]
[INFO][2024-12-20 17:54:04,845][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Parameter Value is : RDKB
[INFO][2024-12-20 17:54:04,845][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Ending Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-12-20 17:54:04,846][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Starting Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-12-20 17:54:04,846][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Is WebpaConnectionBroken : false
[INFO][2024-12-20 17:54:04,846][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-12-20 17:54:04,846][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-12-20 17:54:04,846][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-12-20 17:54:04,847][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-12-20 17:54:04,847][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to execute commands
[INFO][2024-12-20 17:54:04,847][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:54:04,861][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:54:04,998][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.AccessPoint.1.Security.X_COMCAST-COM_KeyPassphrase
[INFO][2024-12-20 17:54:04,998][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:54:05,121][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.AccessPoint.1.Security.X_COMCAST-COM_KeyPassphrase
               type:     string,    value: yolo@123 

<=============================================================>
[INFO][2024-12-20 17:54:05,121][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:54:05,121][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.AccessPoint.1.Security.X_COMCAST-COM_KeyPassphrase
               type:     string,    value: yolo@123
[INFO][2024-12-20 17:54:05,122][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param value : yolo@123
[INFO][2024-12-20 17:54:05,122][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.WiFi.AccessPoint.10001.Security.X_COMCAST-COM_KeyPassphrase, protocolSpecificParamName=Device.WiFi.AccessPoint.1.Security.X_COMCAST-COM_KeyPassphrase, value=yolo@123, datatype=null, statusCode=0]
[INFO][2024-12-20 17:54:05,122][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Parameter Value is : yolo@123
[INFO][2024-12-20 17:54:05,123][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Ending Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-12-20 17:54:05,123][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Client device connection type : Wi-Fi
[INFO][2024-12-20 17:54:05,124][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Client device wifi capability : Dual Band
[INFO][2024-12-20 17:54:05,130][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : isWindows 
[INFO][2024-12-20 17:54:05,132][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:54:05,132][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh wlan show profiles
[INFO][2024-12-20 17:54:05,132][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:54:05,329][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command netsh wlan show profiles  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:54:05,330][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: netsh wlan show profiles

[INFO][2024-12-20 17:54:05,330][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:54:07,276][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 

Profiles on interface Wi-Fi 3:

Group policy profiles (read only)

---------------------------------

    <None>

User profiles

-------------

    All User Profile     : RDKB
<=============================================================>
[INFO][2024-12-20 17:54:07,276][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:54:07,278][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Driver name from client: Wi-Fi 3
[INFO][2024-12-20 17:54:07,279][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:54:07,279][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh interface set interface "Wi-Fi 3" disable
[INFO][2024-12-20 17:54:07,279][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:54:07,451][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command netsh interface set interface "Wi-Fi 3" disable  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:54:07,451][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: netsh interface set interface "Wi-Fi 3" disable

[INFO][2024-12-20 17:54:07,451][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:54:11,198][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-12-20 17:54:11,198][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:54:11,199][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Waiting for 30 seconds
[INFO][2024-12-20 17:54:32,357][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:54:32,491][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 17:54:32,504][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:54:32,622][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 17:54:32,623][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 17:54:32,623][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:54:32,745][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 17:54:32,745][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 17:54:32,746][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 17:54:41,200][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:54:41,200][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh interface set interface "Wi-Fi 3" enable
[INFO][2024-12-20 17:54:41,200][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:54:41,389][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command netsh interface set interface "Wi-Fi 3" enable  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:54:41,390][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: netsh interface set interface "Wi-Fi 3" enable

[INFO][2024-12-20 17:54:41,390][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:54:44,537][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-12-20 17:54:44,537][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:54:44,538][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Waiting for 60 seconds after enabling the WiFi driver
[INFO][2024-12-20 17:55:32,761][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:55:32,907][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 17:55:32,920][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:55:33,068][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 17:55:33,068][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 17:55:33,068][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:55:33,190][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 17:55:33,191][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 17:55:33,191][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 17:55:44,539][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:55:44,539][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh wlan show networks |grep -i "RDKB"
[INFO][2024-12-20 17:55:44,539][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:44,683][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command grep -i "RDKB"< <(netsh wlan show networks )  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:55:44,683][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: grep -i "RDKB"< <(netsh wlan show networks )

[INFO][2024-12-20 17:55:44,683][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:55:46,529][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
SSID 9 : RDKB

SSID 14 : RPI_RDKB-AP0
<=============================================================>
[INFO][2024-12-20 17:55:46,529][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:46,536][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Found = RDKB
[INFO][2024-12-20 17:55:46,536][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - RDKB
[INFO][2024-12-20 17:55:46,537][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for RDKB in target string = true
[INFO][2024-12-20 17:55:46,537][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:55:46,537][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh wlan delete profile name=*
[INFO][2024-12-20 17:55:46,537][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:46,657][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command netsh wlan delete profile name=*  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:55:46,657][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: netsh wlan delete profile name=*

[INFO][2024-12-20 17:55:46,657][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:55:48,502][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
Profile "RDKB" is deleted from interface "Wi-Fi 3".
<=============================================================>
[INFO][2024-12-20 17:55:48,502][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:48,504][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Successfully removed all existing profiles on NUC 2C:44:FD:18:F9:FE
[INFO][2024-12-20 17:55:48,504][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Creating profile with ssid & password....
[INFO][2024-12-20 17:55:48,504][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : hostMacAddress : 2C44FD18F9FE
[INFO][2024-12-20 17:55:48,505][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] 2: hostMacAddress : F9FE
[INFO][2024-12-20 17:55:48,509][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : profileNameToAdd : .F9FE_RDKB.xml
[INFO][2024-12-20 17:55:48,510][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : path : /var/lib/jenkins/workspace/JOB1/.F9FE_RDKB.xml
[INFO][2024-12-20 17:55:48,511][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : securityModeToVerify : WPA2
[INFO][2024-12-20 17:55:48,511][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : isSsidHidden : false
[INFO][2024-12-20 17:55:48,511][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Creating new profile in server : /var/lib/jenkins/workspace/JOB1/.F9FE_RDKB.xml
[INFO][2024-12-20 17:55:48,515][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Document : [#document: null]
[INFO][2024-12-20 17:55:48,517][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) name
[INFO][2024-12-20 17:55:48,517][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) name
[INFO][2024-12-20 17:55:48,517][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : bIntegerSSID : 1380207426
[INFO][2024-12-20 17:55:48,518][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : hex : 52444b42
[INFO][2024-12-20 17:55:48,518][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) hex
[INFO][2024-12-20 17:55:48,518][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) nonBroadcast
[INFO][2024-12-20 17:55:48,518][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) keyMaterial
[INFO][2024-12-20 17:55:48,564][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Successfully created profile
[INFO][2024-12-20 17:55:48,564][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : resp: true
[INFO][2024-12-20 17:55:48,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Document : [#document: null]
[INFO][2024-12-20 17:55:48,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) name
[INFO][2024-12-20 17:55:48,568][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) name
[INFO][2024-12-20 17:55:48,568][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : bIntegerSSID : 1380207426
[INFO][2024-12-20 17:55:48,568][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : hex : 52444b42
[INFO][2024-12-20 17:55:48,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) hex
[INFO][2024-12-20 17:55:48,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) nonBroadcast
[INFO][2024-12-20 17:55:48,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : subnList.item(i).setTextContent(content) keyMaterial
[INFO][2024-12-20 17:55:48,571][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Successfully created profile
[INFO][2024-12-20 17:55:48,572][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Copying new profile to device : /var/lib/jenkins/workspace/JOB1/.F9FE_RDKB.xml
[INFO][2024-12-20 17:55:48,572][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Copying File From JumpServer To Client
[INFO][2024-12-20 17:55:48,573][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Path for Copying File From :/var/lib/jenkins/workspace/JOB1/.F9FE_RDKB.xml
[INFO][2024-12-20 17:55:48,573][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Path for Copying File To  :/home/User/.F9FE_RDKB.xml
[INFO][2024-12-20 17:55:48,970][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:55:48,970][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE ls -la | grep .F9FE_RDKB.xml
[INFO][2024-12-20 17:55:48,970][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:49,129][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command  grep .F9FE_RDKB.xml< <(ls -la )  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:55:49,129][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command:  grep .F9FE_RDKB.xml< <(ls -la )

[INFO][2024-12-20 17:55:49,129][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:55:49,373][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
-rw-r--r-- 1 User None            885 Dec 20 17:55 .F9FE_RDKB.xml

<=============================================================>
[INFO][2024-12-20 17:55:49,373][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:49,374][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Found = .F9FE_RDKB.xml
[INFO][2024-12-20 17:55:49,374][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - .F9FE_RDKB.xml
[INFO][2024-12-20 17:55:49,374][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for .F9FE_RDKB.xml in target string = true
[INFO][2024-12-20 17:55:49,374][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Is wifi profile xml moved to client : true
[INFO][2024-12-20 17:55:49,375][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Adding wifi profile for ssid RDKB
[INFO][2024-12-20 17:55:49,375][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:55:49,375][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh wlan add profile filename=.F9FE_RDKB.xml
[INFO][2024-12-20 17:55:49,375][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:49,503][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command netsh wlan add profile filename=.F9FE_RDKB.xml  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:55:49,503][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: netsh wlan add profile filename=.F9FE_RDKB.xml

[INFO][2024-12-20 17:55:49,503][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:55:51,448][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
Profile RDKB is added on interface Wi-Fi 3.
<=============================================================>
[INFO][2024-12-20 17:55:51,449][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:51,450][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : Wifi profile  added successfully for ssid RDKB
[INFO][2024-12-20 17:55:51,450][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Removed profile created locally in server
[INFO][2024-12-20 17:55:51,450][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : command : netsh wlan show networks | grep -i RDKB
[INFO][2024-12-20 17:55:51,451][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : startTime : 1734697551450
[INFO][2024-12-20 17:55:51,451][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:55:51,451][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh wlan show networks | grep -i RDKB
[INFO][2024-12-20 17:55:51,451][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:51,610][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command  grep -i RDKB< <(netsh wlan show networks )  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:55:51,610][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command:  grep -i RDKB< <(netsh wlan show networks )

[INFO][2024-12-20 17:55:51,610][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:55:53,456][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
SSID 7 : RDKB

SSID 11 : RPI_RDKB-AP0
<=============================================================>
[INFO][2024-12-20 17:55:53,456][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:53,457][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [TEST LOG] : commandResponse : SSID 7 : RDKB

SSID 11 : RPI_RDKB-AP0

[INFO][2024-12-20 17:55:53,457][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Found = RDKB
[INFO][2024-12-20 17:55:53,457][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - RDKB
[INFO][2024-12-20 17:55:53,457][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for RDKB in target string = true
[INFO][2024-12-20 17:55:53,458][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to validate Wifi Security Mode for the SSID : RDKB on the windows client
[INFO][2024-12-20 17:55:53,458][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:55:53,458][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh wlan show networks | awk '/RDKB/,/Encryption/' | grep Authentication
[INFO][2024-12-20 17:55:53,458][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:53,592][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command  grep Authentication< <(( awk '/RDKB/,/Encryption/' )< <(netsh wlan show networks ))  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:55:53,592][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command:  grep Authentication< <(( awk '/RDKB/,/Encryption/' )< <(netsh wlan show networks ))

[INFO][2024-12-20 17:55:53,592][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:55:55,437][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
    Authentication          : WPA2-Personal

    Authentication          : WPA2-Personal
<=============================================================>
[INFO][2024-12-20 17:55:55,437][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:55,438][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Response for wifi security mode in Windows client :     Authentication          : WPA2-Personal

    Authentication          : WPA2-Personal

[INFO][2024-12-20 17:55:55,439][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:55:55,439][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh wlan connect name="RDKB"
[INFO][2024-12-20 17:55:55,439][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:55,557][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command netsh wlan connect name="RDKB"  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:55:55,557][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: netsh wlan connect name="RDKB"

[INFO][2024-12-20 17:55:55,557][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:55:57,402][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
Connection request was completed successfully.
<=============================================================>
[INFO][2024-12-20 17:55:57,402][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:57,403][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:55:57,404][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE netsh wlan show interface | grep -i "state"
[INFO][2024-12-20 17:55:57,404][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:57,520][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command  grep -i "state"< <(netsh wlan show interface )  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:55:57,520][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command:  grep -i "state"< <(netsh wlan show interface )

[INFO][2024-12-20 17:55:57,521][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:55:59,380][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
    State                  : connected
<=============================================================>
[INFO][2024-12-20 17:55:59,380][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:59,381][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  Is the device connected to Gateway Wi-Fi true
[INFO][2024-12-20 17:55:59,382][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Device successfully connected to WIFI_BAND_2_GHZ wifi. Connected device 2C:44:FD:18:F9:FE to WIFI_BAND_2_GHZ wifi band 
[INFO][2024-12-20 17:55:59,382][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 3 : ACTUAL : Wireless client connected toWIFI_BAND_2_GHZ
[INFO][2024-12-20 17:55:59,383][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:55:59,383][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-BAND-STEERING-107] [step Number : s3][ Execution status : PASS] [Error Message : ]
[INFO][2024-12-20 17:55:59,383][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-12-20 17:55:59,384][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-BAND-STEERING-107","stepNumber":"s3","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":true,"partnerName":"","automationId":"TC-RDKB-WIFI-BAND-STEERING-1007"}
[ERROR][2024-12-20 17:55:59,395][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id
[INFO][2024-12-20 17:55:59,395][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-12-20 17:55:59,396][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:55:59,396][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 4: DESCRIPTION :run ping to check connectivity between clients   
[INFO][2024-12-20 17:55:59,396][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 4: ACTION : Execute  From Client 1 : ping addressOfClient1 
[INFO][2024-12-20 17:55:59,396][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 4: EXPECTED: Ping should be succesful and clients should be able to communicate between them
[INFO][2024-12-20 17:55:59,396][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:55:59,396][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Windows device is connected to Gateway device to Wi-Fi
[INFO][2024-12-20 17:55:59,397][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - searchTrace in getIpOrMacFromWindowsConnectedClient "Wireless LAN adapter Wi"
[INFO][2024-12-20 17:55:59,397][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Command to be executed is ipconfig /all |grep -A 40 "Wireless LAN adapter Wi"|grep -i "IPv4 Address"
[INFO][2024-12-20 17:55:59,397][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:55:59,397][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE ipconfig /all |grep -A 40 "Wireless LAN adapter Wi"|grep -i "IPv4 Address"
[INFO][2024-12-20 17:55:59,397][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:55:59,549][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command grep -i "IPv4 Address"< <((grep -A 40 "Wireless LAN adapter Wi")< <(ipconfig /all ))  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:55:59,549][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: grep -i "IPv4 Address"< <((grep -A 40 "Wireless LAN adapter Wi")< <(ipconfig /all ))

[INFO][2024-12-20 17:55:59,550][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:56:01,396][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
   IPv4 Address. . . . . . . . . . . : 10.0.0.184(Preferred) 

   IPv4 Address. . . . . . . . . . . : 192.168.2.57(Preferred) 
<=============================================================>
[INFO][2024-12-20 17:56:01,396][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:56:01,397][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - response of ipconfig is    IPv4 Address. . . . . . . . . . . : 10.0.0.184(Preferred) 

   IPv4 Address. . . . . . . . . . . : 192.168.2.57(Preferred) 

[INFO][2024-12-20 17:56:01,398][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Value of ip is - 10.0.0.184
[INFO][2024-12-20 17:56:01,398][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - response .lenght - 2
[INFO][2024-12-20 17:56:01,398][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - response of ip or mac is -   IPv4 Address. . . . . . . . . . . : 10.0.0.184(Preferred) 
[INFO][2024-12-20 17:56:01,399][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Value of ip or mac is -10.0.0.184.
device Model :Rpi-RDKB
[INFO][2024-12-20 17:56:01,401][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-12-20 17:56:01,401][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:56:01,435][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:56:01,580][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: arp -a | grep -i 10.0.0.184
[INFO][2024-12-20 17:56:01,581][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:56:01,703][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
sh: arp: command not found

<=============================================================>
[INFO][2024-12-20 17:56:01,704][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-12-20 17:56:01,704][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Validate response is -sh: arp: command not found.
[INFO][2024-12-20 17:56:01,705][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Value of Windows connected client is 10.0.0.184
[INFO][2024-12-20 17:56:01,705][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to ping 10.0.0.184 from device 
[INFO][2024-12-20 17:56:01,705][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STARTING METHOD : verifyPingConnection()
[INFO][2024-12-20 17:56:01,705][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - OS TYPE OF THE WIFI CLIENT: WINDOWS
[INFO][2024-12-20 17:56:01,706][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Ping Command: ping -n 5 
[INFO][2024-12-20 17:56:01,706][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - BroadBandCommonUtils.concatStringUsingStringBuffer(pingCommand, ipAddress) is: ping -n 5 10.0.0.184
[INFO][2024-12-20 17:56:01,706][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-12-20 17:56:01,706][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE ping -n 5 10.0.0.184
[INFO][2024-12-20 17:56:01,707][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.57
[INFO][2024-12-20 17:56:01,902][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command ping -n 5 10.0.0.184  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.57] and Port Number [22]
[INFO][2024-12-20 17:56:01,902][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: ping -n 5 10.0.0.184

[INFO][2024-12-20 17:56:01,902][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-12-20 17:56:07,741][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 

Pinging 10.0.0.184 with 32 bytes of data:

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Ping statistics for 10.0.0.184:

    Packets: Sent = 5, Received = 5, Lost = 0 (0% loss),

Approximate round trip times in milli-seconds:

    Minimum = 0ms, Maximum = 0ms, Average = 0ms
<=============================================================>
[INFO][2024-12-20 17:56:07,741][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.57
[INFO][2024-12-20 17:56:07,742][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - response is: 
Pinging 10.0.0.184 with 32 bytes of data:

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Reply from 10.0.0.184: bytes=32 time<1ms TTL=128

Ping statistics for 10.0.0.184:

    Packets: Sent = 5, Received = 5, Lost = 0 (0% loss),

Approximate round trip times in milli-seconds:

    Minimum = 0ms, Maximum = 0ms, Average = 0ms

[ERROR][2024-12-20 17:56:07,743][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Not Found = TTL Expired in Transit
[INFO][2024-12-20 17:56:07,743][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for TTL Expired in Transit in target string = false
[ERROR][2024-12-20 17:56:07,743][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Not Found = Destination host unreachable
[INFO][2024-12-20 17:56:07,743][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for Destination host unreachable in target string = false
[ERROR][2024-12-20 17:56:07,743][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Not Found = Request timed out
[INFO][2024-12-20 17:56:07,743][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for Request timed out in target string = false
[ERROR][2024-12-20 17:56:07,744][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Not Found = Unknown host
[INFO][2024-12-20 17:56:07,744][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for Unknown host in target string = false
[ERROR][2024-12-20 17:56:07,744][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Not Found = Ping request could not find host
[INFO][2024-12-20 17:56:07,744][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for Ping request could not find host in target string = false
[ERROR][2024-12-20 17:56:07,744][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Not Found = Request timeout for icmp_seq
[INFO][2024-12-20 17:56:07,744][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for Request timeout for icmp_seq in target string = false
[INFO][2024-12-20 17:56:07,745][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - validate Ping Response is :false
[INFO][2024-12-20 17:56:07,745][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - PATTERN MATCHED STRING:0
[INFO][2024-12-20 17:56:07,746][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 4 : ACTUAL :WIFI_BAND_2_GHZ has connecetivity in ipv4 interface
[INFO][2024-12-20 17:56:07,746][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:56:07,746][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-BAND-STEERING-107] [step Number : s4][ Execution status : PASS] [Error Message : ]
[INFO][2024-12-20 17:56:07,746][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-12-20 17:56:07,747][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-BAND-STEERING-107","stepNumber":"s4","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-BAND-STEERING-1007"}
[ERROR][2024-12-20 17:56:07,778][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id
[INFO][2024-12-20 17:56:07,778][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-12-20 17:56:07,779][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:56:07,779][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 5: DESCRIPTION : Disable WIFI_BAND_2_GHZ radio using webpa   
[INFO][2024-12-20 17:56:07,780][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 5: ACTION      : Execute set on WIFI_BAND_2_GHZ enable status value false 
[INFO][2024-12-20 17:56:07,780][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 5: EXPECTED    : Webpa should execute successfully 
[INFO][2024-12-20 17:56:07,780][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:56:07,783][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to DisableWIFI_BAND_2_GHZ
[INFO][2024-12-20 17:56:07,784][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - WebPa data type: BOOLEAN
[INFO][2024-12-20 17:56:07,785][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 parameters status code  added  0
[INFO][2024-12-20 17:56:07,785][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-12-20 17:56:07,785][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-12-20 17:56:07,785][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.SSID.1.Enable bool false
[INFO][2024-12-20 17:56:07,785][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:56:07,797][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:56:07,913][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.SSID.1.Enable bool false
[INFO][2024-12-20 17:56:07,913][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:56:08,036][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:56:08,036][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:56:08,037][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
[INFO][2024-12-20 17:56:08,037][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed
[INFO][2024-12-20 17:56:08,037][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:56:08,051][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:56:08,201][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: ps | grep -i "onewifi"
[INFO][2024-12-20 17:56:08,201][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:56:08,323][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
32642 root      0:24 /usr/bin/OneWifi -subsys eRT.
182163 root      0:00 sh -c ps | grep -i "onewifi"
182165 root      0:00 grep -i onewifi

<=============================================================>
[INFO][2024-12-20 17:56:08,323][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:56:08,324][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - The device has One Wifi : 32642 root      0:24 /usr/bin/OneWifi -subsys eRT.
182163 root      0:00 sh -c ps | grep -i "onewifi"
182165 root      0:00 grep -i onewifi
[INFO][2024-12-20 17:56:08,324][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed for One WIFI
[INFO][2024-12-20 17:56:08,324][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param name: Device.WiFi.SSID.1.Enable 
[INFO][2024-12-20 17:56:08,324][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:56:08,324][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:56:08,338][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:56:08,488][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:56:08,488][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:56:08,611][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:56:08,611][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

[INFO][2024-12-20 17:56:08,611][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Response : {Device.WiFi.SSID.10001.Enable=Success}
[INFO][2024-12-20 17:56:08,612][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-12-20 17:56:08,612][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-12-20 17:56:08,612][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-12-20 17:56:08,622][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - WEBPA PARAM - Device.WiFi.SSID.10001.Enable SET WITH VALUE - false IS SUCCESSFUL: true
[INFO][2024-12-20 17:56:08,623][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Status of setting WebPaParameter values: true
[INFO][2024-12-20 17:56:08,623][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-12-20 17:56:08,623][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:56:08,634][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:56:08,754][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:56:08,754][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:56:08,878][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:56:08,879][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-12-20 17:56:08,879][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-12-20 17:56:08,879][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:56:08,894][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:56:09,031][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyRadioSettings bool 1
[INFO][2024-12-20 17:56:09,031][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:56:09,154][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:56:09,154][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-12-20 17:56:09,154][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Waiting for 90 seconds to reflect the WiFi changes before getting or setting any WiFi parameters.
[INFO][2024-12-20 17:56:33,206][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:56:33,355][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 17:56:33,369][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:56:33,516][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 17:56:33,517][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 17:56:33,517][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:56:33,639][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 17:56:33,639][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 17:56:33,640][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 17:57:33,652][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:57:33,774][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 17:57:33,785][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:57:33,888][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 17:57:33,889][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 17:57:33,889][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:57:34,011][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 17:57:34,011][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 17:57:34,012][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 17:57:39,155][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-12-20 17:57:39,155][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-12-20 17:57:39,155][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-12-20 17:57:39,155][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-12-20 17:57:39,155][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to execute commands
[INFO][2024-12-20 17:57:39,155][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:57:39,170][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:57:39,300][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.SSID.1.Enable
[INFO][2024-12-20 17:57:39,300][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:57:39,423][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.1.Enable
               type:       bool,    value: false 

<=============================================================>
[INFO][2024-12-20 17:57:39,423][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:57:39,423][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.1.Enable
               type:       bool,    value: false
[INFO][2024-12-20 17:57:39,424][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param value : false
[INFO][2024-12-20 17:57:39,424][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.WiFi.SSID.10001.Enable, protocolSpecificParamName=Device.WiFi.SSID.1.Enable, value=false, datatype=null, statusCode=0]
[INFO][2024-12-20 17:57:39,425][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - valueToBePassed: false
[INFO][2024-12-20 17:57:39,425][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - response: false
[INFO][2024-12-20 17:57:39,425][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Found = false
[INFO][2024-12-20 17:57:39,425][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - false
[INFO][2024-12-20 17:57:39,425][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for false in target string = true
[INFO][2024-12-20 17:57:39,426][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Status of getting WebPaParameter values: true
[INFO][2024-12-20 17:57:39,426][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Disable WIFI_BAND_2_GHZ success
[INFO][2024-12-20 17:57:39,427][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 5 : ACTUAL :Disable WIFI_BAND_2_GHZ radio using webpa is success
[INFO][2024-12-20 17:57:39,427][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:57:39,427][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-BAND-STEERING-107] [step Number : s5][ Execution status : PASS] [Error Message : ]
[INFO][2024-12-20 17:57:39,427][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-12-20 17:57:39,428][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-BAND-STEERING-107","stepNumber":"s5","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-BAND-STEERING-1007"}
[ERROR][2024-12-20 17:57:39,460][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id
[INFO][2024-12-20 17:57:39,460][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-12-20 17:57:39,460][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:57:39,461][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 6: DESCRIPTION : Check the lbd process is not running on the RPI device    
[INFO][2024-12-20 17:57:39,461][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 6: ACTION : Exceute command  :  ps  | grep lbd  and check for String /sbin/lbd -C /tmp/lbd.conf in the command response
[INFO][2024-12-20 17:57:39,461][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 6: EXPECTED : logs confirms that band steering daemon is stopped. lbd.conf  must be killed, since there is no target band. The String "/sbin/lbd -C /tmp/lbd.conf"  should not be present in the command output  
[INFO][2024-12-20 17:57:39,461][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:57:39,461][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Waiting for 2 minutes before checking the lbd process
[INFO][2024-12-20 17:58:34,026][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:58:34,149][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 17:58:34,162][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:58:34,296][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 17:58:34,297][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 17:58:34,297][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:58:34,419][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 17:58:34,419][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 17:58:34,419][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 17:59:34,434][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:59:34,566][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 17:59:34,579][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 17:59:34,723][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 17:59:34,723][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 17:59:34,723][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:59:34,845][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 17:59:34,845][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 17:59:34,846][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 17:59:39,461][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-12-20 17:59:39,462][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:59:39,476][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:59:39,627][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: ps  | grep lbd
[INFO][2024-12-20 17:59:39,628][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:59:39,750][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
186282 root      0:00 sh -c ps  | grep lbd
186284 root      0:00 grep lbd

<=============================================================>
[INFO][2024-12-20 17:59:39,750][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-12-20 17:59:39,751][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Response of command  :  186282 root      0:00 sh -c ps  | grep lbd
186284 root      0:00 grep lbd
[INFO][2024-12-20 17:59:39,751][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 6 : ACTUAL :Band Steering process is not running when target WIFI_BAND_2_GHZ band is disabled 
[INFO][2024-12-20 17:59:39,752][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:59:39,752][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-BAND-STEERING-107] [step Number : s6][ Execution status : PASS] [Error Message : ]
[INFO][2024-12-20 17:59:39,752][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-12-20 17:59:39,753][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-BAND-STEERING-107","stepNumber":"s6","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-BAND-STEERING-1007"}
[ERROR][2024-12-20 17:59:39,785][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id
[INFO][2024-12-20 17:59:39,785][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-12-20 17:59:39,785][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:59:39,786][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 7: DESCRIPTION : Enable  WIFI_BAND_2_GHZ radio using webpa   
[INFO][2024-12-20 17:59:39,786][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 7: ACTION      : Execute set on WIFI_BAND_2_GHZ Enable value  is true 
[INFO][2024-12-20 17:59:39,786][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 7: EXPECTED    : Webpa should execute successfully 
[INFO][2024-12-20 17:59:39,786][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 17:59:39,786][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to EnableWIFI_BAND_2_GHZ
[INFO][2024-12-20 17:59:39,786][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - WebPa data type: BOOLEAN
[INFO][2024-12-20 17:59:39,787][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 parameters status code  added  0
[INFO][2024-12-20 17:59:39,787][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-12-20 17:59:39,787][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-12-20 17:59:39,787][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.SSID.1.Enable bool true
[INFO][2024-12-20 17:59:39,787][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:59:39,798][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:59:39,926][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.SSID.1.Enable bool true
[INFO][2024-12-20 17:59:39,926][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:59:40,049][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:59:40,049][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:59:40,049][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
[INFO][2024-12-20 17:59:40,049][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed
[INFO][2024-12-20 17:59:40,050][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 17:59:40,063][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:59:40,205][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: ps | grep -i "onewifi"
[INFO][2024-12-20 17:59:40,205][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:59:40,328][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
32642 root      0:25 /usr/bin/OneWifi -subsys eRT.
186290 root      0:00 sh -c ps | grep -i "onewifi"
186292 root      0:00 grep -i onewifi

<=============================================================>
[INFO][2024-12-20 17:59:40,328][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 17:59:40,328][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - The device has One Wifi : 32642 root      0:25 /usr/bin/OneWifi -subsys eRT.
186290 root      0:00 sh -c ps | grep -i "onewifi"
186292 root      0:00 grep -i onewifi
[INFO][2024-12-20 17:59:40,328][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed for One WIFI
[INFO][2024-12-20 17:59:40,328][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param name: Device.WiFi.SSID.1.Enable 
[INFO][2024-12-20 17:59:40,328][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:59:40,329][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:59:40,342][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:59:40,476][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:59:40,476][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:59:40,599][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:59:40,599][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

[INFO][2024-12-20 17:59:40,599][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Response : {Device.WiFi.SSID.10001.Enable=Success}
[INFO][2024-12-20 17:59:40,600][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-12-20 17:59:40,600][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-12-20 17:59:40,600][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-12-20 17:59:40,600][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - WEBPA PARAM - Device.WiFi.SSID.10001.Enable SET WITH VALUE - true IS SUCCESSFUL: true
[INFO][2024-12-20 17:59:40,600][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Status of setting WebPaParameter values: true
[INFO][2024-12-20 17:59:40,600][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-12-20 17:59:40,600][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:59:40,613][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:59:40,759][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 17:59:40,760][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:59:40,882][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:59:40,882][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-12-20 17:59:40,882][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-12-20 17:59:40,883][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 17:59:40,896][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 17:59:41,019][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyRadioSettings bool 1
[INFO][2024-12-20 17:59:41,019][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 17:59:41,142][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 17:59:41,143][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-12-20 17:59:41,143][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Waiting for 90 seconds to reflect the WiFi changes before getting or setting any WiFi parameters.
[INFO][2024-12-20 18:00:34,861][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 18:00:34,984][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 18:00:34,996][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 18:00:35,134][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 18:00:35,135][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 18:00:35,135][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:00:35,257][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 18:00:35,257][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 18:00:35,258][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 18:01:11,143][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-12-20 18:01:11,144][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-12-20 18:01:11,144][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-12-20 18:01:11,144][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-12-20 18:01:11,144][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going to execute commands
[INFO][2024-12-20 18:01:11,144][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 18:01:11,158][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 18:01:11,294][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.SSID.1.Enable
[INFO][2024-12-20 18:01:11,294][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:01:11,417][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.1.Enable
               type:       bool,    value: true 

<=============================================================>
[INFO][2024-12-20 18:01:11,417][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 18:01:11,417][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.SSID.1.Enable
               type:       bool,    value: true
[INFO][2024-12-20 18:01:11,417][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param value : true
[INFO][2024-12-20 18:01:11,418][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.WiFi.SSID.10001.Enable, protocolSpecificParamName=Device.WiFi.SSID.1.Enable, value=true, datatype=null, statusCode=0]
[INFO][2024-12-20 18:01:11,418][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - valueToBePassed: true
[INFO][2024-12-20 18:01:11,418][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - response: true
[INFO][2024-12-20 18:01:11,419][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Pattern Found = true
[INFO][2024-12-20 18:01:11,419][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - true
[INFO][2024-12-20 18:01:11,419][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - is patter found for true in target string = true
[INFO][2024-12-20 18:01:11,419][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Status of getting WebPaParameter values: true
[INFO][2024-12-20 18:01:11,419][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Enable WIFI_BAND_2_GHZ success
[INFO][2024-12-20 18:01:11,420][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 7 : ACTUAL :Enable WIFI_BAND_2_GHZ radio using webpa is success
[INFO][2024-12-20 18:01:11,420][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 18:01:11,420][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-BAND-STEERING-107] [step Number : s7][ Execution status : PASS] [Error Message : ]
[INFO][2024-12-20 18:01:11,420][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-12-20 18:01:11,421][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-BAND-STEERING-107","stepNumber":"s7","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-BAND-STEERING-1007"}
[ERROR][2024-12-20 18:01:11,453][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id
[INFO][2024-12-20 18:01:11,453][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-12-20 18:01:11,454][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 18:01:11,454][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 8: DESCRIPTION : Check the lbd process is  running on the RPI device    
[INFO][2024-12-20 18:01:11,454][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 8: ACTION      : Exceute command  :  ps  | grep lbd  and compare with not null response
[INFO][2024-12-20 18:01:11,454][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 8: EXPECTED    : logs confirms that band steering daemon is running.  lbd.conf  must be reinitiated and band steering must work as expected.
[INFO][2024-12-20 18:01:11,454][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 18:01:11,454][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Waiting for 2 minutes before checking the lbd process
[INFO][2024-12-20 18:01:35,271][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 18:01:35,395][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 18:01:35,408][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 18:01:35,552][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 18:01:35,553][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 18:01:35,553][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:01:35,675][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 18:01:35,675][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 18:01:35,676][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 18:02:35,691][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 18:02:35,810][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-12-20 18:02:35,828][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-12-20 18:02:35,956][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-12-20 18:02:35,956][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-12-20 18:02:35,956][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:02:36,079][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-12-20 18:02:36,079][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@6c70ac0d
[INFO][2024-12-20 18:02:36,079][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-12-20 18:03:11,454][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-12-20 18:03:11,455][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 18:03:11,468][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 18:03:11,593][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: ps  | grep lbd
[INFO][2024-12-20 18:03:11,593][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:03:11,715][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
195599 root      0:00 sh -c ps  | grep lbd
195601 root      0:00 grep lbd

<=============================================================>
[INFO][2024-12-20 18:03:11,715][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-12-20 18:03:11,716][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Response of command  :  195599 root      0:00 sh -c ps  | grep lbd
195601 root      0:00 grep lbd
[INFO][2024-12-20 18:03:11,717][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - STEP 8 : ACTUAL :Band Steering process is  running when target WIFI_BAND_2_GHZ band is enabled 
[INFO][2024-12-20 18:03:11,717][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - *************************************************************************
[INFO][2024-12-20 18:03:11,717][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-BAND-STEERING-107] [step Number : s8][ Execution status : PASS] [Error Message : ]
[INFO][2024-12-20 18:03:11,717][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-12-20 18:03:11,718][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-WIFI-BAND-STEERING-107","stepNumber":"s8","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-BAND-STEERING-1007"}
[ERROR][2024-12-20 18:03:11,750][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id
[INFO][2024-12-20 18:03:11,750][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-12-20 18:03:11,750][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - POSTCONDITION : Going to set the old SSID back to the device
[INFO][2024-12-20 18:03:11,750][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-12-20 18:03:11,750][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 parameters status code  added  0
[INFO][2024-12-20 18:03:11,750][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-12-20 18:03:11,751][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 parameters status code  added  0
[INFO][2024-12-20 18:03:11,751][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-12-20 18:03:11,751][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-12-20 18:03:11,751][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.SSID.1.SSID string yolo
[INFO][2024-12-20 18:03:11,751][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 18:03:11,763][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 18:03:11,918][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.SSID.1.SSID string yolo
[INFO][2024-12-20 18:03:11,919][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:03:12,041][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 18:03:12,041][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 18:03:12,041][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
[INFO][2024-12-20 18:03:12,041][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed
[INFO][2024-12-20 18:03:12,041][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 18:03:12,051][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 18:03:12,164][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: ps | grep -i "onewifi"
[INFO][2024-12-20 18:03:12,164][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:03:12,286][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
32642 root      0:26 /usr/bin/OneWifi -subsys eRT.
195607 root      0:00 sh -c ps | grep -i "onewifi"
195609 root      0:00 grep -i onewifi

<=============================================================>
[INFO][2024-12-20 18:03:12,286][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 18:03:12,286][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - The device has One Wifi : 32642 root      0:26 /usr/bin/OneWifi -subsys eRT.
195607 root      0:00 sh -c ps | grep -i "onewifi"
195609 root      0:00 grep -i onewifi
[INFO][2024-12-20 18:03:12,286][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed for One WIFI
[INFO][2024-12-20 18:03:12,286][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param name: Device.WiFi.SSID.1.SSID 
[INFO][2024-12-20 18:03:12,287][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 18:03:12,287][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 18:03:12,299][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 18:03:12,419][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 18:03:12,419][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:03:12,541][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 18:03:12,542][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

[INFO][2024-12-20 18:03:12,542][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.SSID.2.SSID string yolo5Ghz
[INFO][2024-12-20 18:03:12,542][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 18:03:12,555][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 18:03:12,687][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.SSID.2.SSID string yolo5Ghz
[INFO][2024-12-20 18:03:12,687][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:03:12,810][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 18:03:12,811][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 18:03:12,811][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
[INFO][2024-12-20 18:03:12,811][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed
[INFO][2024-12-20 18:03:12,811][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.47
[INFO][2024-12-20 18:03:12,823][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 18:03:12,974][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: ps | grep -i "onewifi"
[INFO][2024-12-20 18:03:12,974][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:03:13,097][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
32642 root      0:26 /usr/bin/OneWifi -subsys eRT.
195626 root      0:00 sh -c ps | grep -i "onewifi"
195628 root      0:00 grep -i onewifi

<=============================================================>
[INFO][2024-12-20 18:03:13,097][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.47
[INFO][2024-12-20 18:03:13,097][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - The device has One Wifi : 32642 root      0:26 /usr/bin/OneWifi -subsys eRT.
195626 root      0:00 sh -c ps | grep -i "onewifi"
195628 root      0:00 grep -i onewifi
[INFO][2024-12-20 18:03:13,097][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Apply settings dmcli command to be executed for One WIFI
[INFO][2024-12-20 18:03:13,098][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli param name: Device.WiFi.SSID.2.SSID 
[INFO][2024-12-20 18:03:13,098][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 18:03:13,098][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.47
[INFO][2024-12-20 18:03:13,110][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-12-20 18:03:13,237][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-12-20 18:03:13,237][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-12-20 18:03:13,359][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

<=============================================================>
[INFO][2024-12-20 18:03:13,360][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.

[INFO][2024-12-20 18:03:13,360][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - TR181 Response : {Device.WiFi.SSID.10001.SSID=Success, Device.WiFi.SSID.10101.SSID=Success}
[INFO][2024-12-20 18:03:13,361][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Successfully set the parameter : Device.WiFi.SSID.10001.SSID
[INFO][2024-12-20 18:03:13,361][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Successfully set the parameter : Device.WiFi.SSID.10101.SSID
[INFO][2024-12-20 18:03:13,365][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - >>>[AFTER_METHOD]: Clear device trace buffer for D8:3A:DD:13:8C:13
[INFO][2024-12-20 18:03:13,374][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - NewLocation for log saving logs  /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-WIFI-BAND-STEERING-1007/D83ADD138C13/settoptrace/
[INFO][2024-12-20 18:03:13,376][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - created a new directory true
[INFO][2024-12-20 18:03:13,376][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - New trace location from the strings /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-12-20 18:03:13,379][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Writing stated from line
[INFO][2024-12-20 18:03:13,868][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Fri Dec 20 18:03:13 IST 2024
[INFO][2024-12-20 18:03:13,869][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - NewLocation for log saving logs  /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-WIFI-BAND-STEERING-1007/D83ADD138C13/logs/
[INFO][2024-12-20 18:03:13,869][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - created a new directory true
[INFO][2024-12-20 18:03:13,869][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Destination Summary file doesn't exist. Creating one!
[INFO][2024-12-20 18:03:13,869][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Writing stated from line
[INFO][2024-12-20 18:03:13,928][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Fri Dec 20 18:03:13 IST 2024
[INFO][2024-12-20 18:03:13,928][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - >>>[AFTER_METHOD]: Sending test execution completion time for D8:3A:DD:13:8C:13
[INFO][2024-12-20 18:03:13,928][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-12-20 18:03:13,929][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - {"jobId":456,"automationId":"TC-RDKB-WIFI-BAND-STEERING-1007","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":0,"endDateTimeEST":1734697993928}
[INFO][2024-12-20 18:03:13,938][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Capture execution time - Response : HTTP/1.1 200 
[INFO][2024-12-20 18:03:13,939][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Restarting Trace in after method
[INFO][2024-12-20 18:03:13,941][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-12-20 18:03:13,941][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-12-20 18:03:13,941][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Going for if build changed : Test Type : GROUP_OR_AUTOID 
[INFO][2024-12-20 18:03:13,941][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-12-20 18:03:13,941][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - >>>[AFTER_METHOD]: Build Change Status: NO_CHANGE
[INFO][2024-12-20 18:03:13,941][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13] - Skipping parter specific after method clean up as it is not configured.
===== Invoked methods
  AutomaticsTestBase.performBeforeSuiteInit(org.testng.ITestContext)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandSteeringTests@40ff441d]org.testng.TestRunner@1cf0ba2a  1090470941
  AutomaticsTestBase.performBeforeMethodInit([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandSteeringTests@40ff441d][Ljava.lang.Object;@6a8a69d8 [TestResult name={null} status=CREATED method=BroadBandSteeringTests.testVerifyBandSteeringProcessGetsKilledWhen2GhzDisabled(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandSteeringTests@40ff441d] output={null}]  1090470941
    BroadBandSteeringTests.testVerifyBandSteeringProcessGetsKilledWhen2GhzDisabled(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandSteeringTests@40ff441d]com.automatics.device.Device@6c70ac0d  1090470941
  AutomaticsTestBase.performAfterMethodCleanup([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandSteeringTests@40ff441d][Ljava.lang.Object;@6a8a69d8 [TestResult name=testVerifyBandSteeringProcessGetsKilledWhen2GhzDisabled status=SUCCESS method=BroadBandSteeringTests.testVerifyBandSteeringProcessGetsKilledWhen2GhzDisabled(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.BroadBandSteeringTests@40ff441d] output={null}]  1090470941
=====
PASSED: testVerifyBandSteeringProcessGetsKilledWhen2GhzDisabled(com.automatics.device.Device@6c70ac0d)

===============================================
    MyTest
    Tests run: 1, Failures: 0, Skips: 0
===============================================

[INFO][2024-12-20 18:03:13,985][[com.automatics.executor.Starter.main()] - Finished testing class: org.testng.TestRunner
[INFO][2024-12-20 18:03:13,986][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Performing after suite cleanup
[INFO][2024-12-20 18:03:13,986][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-12-20 18:03:13,986][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Locked devices after suite execution 1
[INFO][2024-12-20 18:03:13,987][[com.automatics.executor.Starter.main()] - Value of intial testType : QUICK
[INFO][2024-12-20 18:03:13,987][[com.automatics.executor.Starter.main()] - Value of TestType provided by job : GROUP_OR_AUTOID 
[INFO][2024-12-20 18:03:13,987][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Verifying if build changed after test
[INFO][2024-12-20 18:03:13,987][[com.automatics.executor.Starter.main()] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-12-20 18:03:13,987][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Build Change Status: NO_CHANGE
[INFO][2024-12-20 18:03:13,987][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[ERROR][2024-12-20 18:03:13,987][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Sleep interrupted sleep interrupted
[ERROR][2024-12-20 18:03:13,990][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - readOutputFromChannel - Exception Details ------ 
java.io.InterruptedIOException: null
	at java.base/java.io.PipedInputStream.read(PipedInputStream.java:328)
	at java.base/java.io.PipedInputStream.read(PipedInputStream.java:377)
	at java.base/sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:284)
	at java.base/sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:326)
	at java.base/sun.nio.cs.StreamDecoder.read(StreamDecoder.java:178)
	at java.base/java.io.InputStreamReader.read(InputStreamReader.java:181)
	at java.base/java.io.BufferedReader.fill(BufferedReader.java:161)
	at java.base/java.io.BufferedReader.readLine(BufferedReader.java:326)
	at java.base/java.io.BufferedReader.readLine(BufferedReader.java:392)
	at com.automatics.providers.trace.AbstractTraceProviderImpl.readOutputFromChannel(AbstractTraceProviderImpl.java:820)
	at com.automatics.providers.trace.AbstractTraceProviderImpl.connectAndRead(AbstractTraceProviderImpl.java:734)
	at com.automatics.providers.trace.AbstractTraceProviderImpl$ConnectionThread.run(AbstractTraceProviderImpl.java:1135)
[INFO][2024-12-20 18:03:13,991][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-12-20 18:03:13,991][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-12-20 18:03:13,991][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Exited from readOutputFromChannel method
[INFO][2024-12-20 18:03:13,991][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-12-20 18:03:13,991][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-12-20 18:03:13,991][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[INFO][2024-12-20 18:03:13,991][[com.automatics.executor.Starter.main()] - Skipping parter specific after suite clean up as it is not configured.
[INFO][2024-12-20 18:03:13,995][[com.automatics.executor.Starter.main()] - Releasing device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/release
[INFO][2024-12-20 18:03:14,045][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-12-20 18:03:14,050][[com.automatics.executor.Starter.main()] - Releasing device 2C:44:FD:18:F9:FE Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/release
[INFO][2024-12-20 18:03:14,104][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"2C:44:FD:18:F9:FE"}
[INFO][2024-12-20 18:03:14,105][[com.automatics.executor.Starter.main()] - Successfully unlocked RDKB (D8:3A:DD:13:8C:13) mapped components - (WINDOWSVM) - 2C:44:FD:18:F9:FE
[INFO][2024-12-20 18:03:14,106][[com.automatics.executor.Starter.main()] - SETTOP - D8:3A:DD:13:8C:13 RELEASED.
[INFO][2024-12-20 18:03:14,107][[com.automatics.executor.Starter.main()] - [AFTER-SUITE:]JSON message to Automatics: {"status":"COMPLETED","service":"","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1734697285593,"completionTime":1734697994106,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}}
[INFO][2024-12-20 18:03:14,117][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [INFO][2024-12-20 17:52:01,907][456][D8:3A:DD:13:8C:13|TestNG-PoolService-0|com.automatics.executor.AutomaticsTestListener:onTestStart:110] <a name="com.automatics.rdkb.tests.wifi.BroadBandSteeringTests.testVerifyBandSteeringProcessGetsKilledWhen2GhzDisabled">STARTED - testVerifyBandSteeringProcessGetsKilledWhen2GhzDisabled - com.automatics.device.Device@6c70ac0d</a>
[INFO][2024-12-20 18:03:14,117][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [DEBUG][2024-12-20 17:52:01,908][456][D8:3A:DD:13:8C:13|TC-RDKB-WIFI-BAND-STEERING-1007 : D83ADD138C13|com.automatics.executor.AutomaticsTestListener:onTestStart:150] Started appending log

===============================================
MySuite
Total tests run: 1, Passes: 1, Failures: 0, Skips: 0
===============================================

[INFO][2024-12-20 18:03:14,194][[com.automatics.executor.Starter.main()] - Updating final execution status to Automatics
[INFO][2024-12-20 18:03:14,195][[Thread-205] - ============= MESSAGE SEND TO AUTOMATICS ==============
[INFO][2024-12-20 18:03:14,195][[Thread-205] - Tested build name : RPI-RDKB
[INFO][2024-12-20 18:03:14,195][[Thread-205] - Final execution status : COMPLETED
[INFO][2024-12-20 18:03:14,196][[Thread-205] - Final successful device list : ["D8:3A:DD:13:8C:13"]
[INFO][2024-12-20 18:03:14,196][[Thread-205] - Job Id : 456
[INFO][2024-12-20 18:03:14,196][[Thread-205] - ==============================================================

[INFO][2024-12-20 18:03:14,196][[Thread-205] - >>>[INIT]: Automatics JSON ->
{"status":"COMPLETED","service":"","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1734697285593,"completionTime":1734697994106,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}}
[INFO][2024-12-20 18:03:14,196][[Thread-205] - http://192.168.2.59:8080/Automatics/executionResponse.htm
[INFO][2024-12-20 18:05:14,367][[Thread-205] - HTTP STATUS LINE : HTTP/1.1 200 
[INFO][2024-12-20 18:05:14,367][[Thread-205] - HTTP STATUS CODE : 200
[INFO][2024-12-20 18:05:14,368][[com.automatics.executor.Starter.main()] - ********  EXECUTION COMPLETED *********
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  14:06 min
[INFO] Finished at: 2024-12-20T18:05:14+05:30
[INFO] ------------------------------------------------------------------------
Inside Add Shutdown Hook
[INFO][2024-12-20 18:05:14,374][[Thread-1] - Bean testInitializer is not configured.
[INFO][2024-12-20 18:05:14,375][[Thread-1] - Closing partner application context
[INFO][2024-12-20 18:05:14,376][[Thread-1] - Closing core application context
[JOB1] $ /bin/sh -xe /tmp/jenkins6691733514631359209.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 <groupId>com.automatics.providers</groupId><artifactId>rpi-provider-impl</artifactId><version>0.0.1-SNAPSHOT</version>
Finished updating pom.xml
Archiving artifacts
Finished: SUCCESS