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 -DsettopList=D8:3A:DD:13:8C:13 -DfilterTestType=GROUP_OR_AUTOID -DupdateRdkPortal=true -Dgrb=rdk-next -Dend_point=DESK_DEVICE -DfilterTestIds=TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 -DexecutionMode=RDKB -Dgr=rdkb-tests -DBUILD_NAME=rdkb-generic-broadband-image_rdk-next_20240215073601 -DJMD_ID=4567 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.130:8080/AutomaticsProps/automatics.properties
[INFO] Scanning for projects...
[WARNING] 
[WARNING] Some problems were encountered while building the effective model for RDKMAutomationRDKBTests:rdkb-automation:jar:0.0.1-SNAPSHOT
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 171
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 314
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 457
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 600
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 743
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 886
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1029
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1172
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1315
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1458
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1601
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1744
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1887
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2030
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2173
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2316
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2459
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2602
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2745
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2888
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3031
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3174
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3317
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3460
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3603
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3746
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3889
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4032
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4175
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4318
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4461
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4604
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4747
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4890
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5033
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5176
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5319
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5462
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5605
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5748
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5891
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6034
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6177
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6320
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6463
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6606
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6749
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6892
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 7035
[WARNING] '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 7178
[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 7321
[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 7464
[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 7607
[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 7750
[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 7893
[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 8036
[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 8179
[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 8322
[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 8465
[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 8608
[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 8751
[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 8894
[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 9037
[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 9180
[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 9323
[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 9466
[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 9609
[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 9752
[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 9895
[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 10038
[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 10181
[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 10324
[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 10467
[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 10610
[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 10753
[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 10896
[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 11039
[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 11182
[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 11325
[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 11468
[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 11611
[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 11754
[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 11897
[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 12040
[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 12183
[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 12326
[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 12469
[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 12612
[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 12755
[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 12898
[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 13041
[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 13184
[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 13327
[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 13470
[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 13613
[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 13756
[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 13899
[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 14042
[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 14185
[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 14328
[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 14471
[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 14614
[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 14757
[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 14900
[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 15043
[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 15186
[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 15329
[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 15472
[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 15615
[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 15758
[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 15901
[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 16044
[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 16187
[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 16330
[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 16473
[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 16616
[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 16759
[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 16902
[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 17045
[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 118 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 ---
15:47:26,300 |-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.20.0-SNAPSHOT/automatics-core-2.20.0-SNAPSHOT.jar!/logback-test.xml]
15:47:26,313 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@2dc623b4 - URL [jar:file:/root/.m2/repository/com/automatics/apps/automatics-core/2.20.0-SNAPSHOT/automatics-core-2.20.0-SNAPSHOT.jar!/logback-test.xml] is not of type file
15:47:26,522 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
15:47:26,524 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdOutAppender]
15:47:26,531 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@7:13 - no applicable action for [onMatch], current ElementPath  is [[configuration][appender][filter][onMatch]]
15:47:26,531 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@8:16 - no applicable action for [onMismatch], current ElementPath  is [[configuration][appender][filter][onMismatch]]
15:47:26,531 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
15:47:26,547 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
15:47:26,549 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [deviceSiftingAppender]
15:47:26,553 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@18:13 - no applicable action for [onMatch], current ElementPath  is [[configuration][appender][filter][onMatch]]
15:47:26,554 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@19:16 - no applicable action for [onMismatch], current ElementPath  is [[configuration][appender][filter][onMismatch]]
15:47:26,558 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
15:47:26,561 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
15:47:26,561 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [TraceLogger]
15:47:26,561 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
15:47:26,562 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
15:47:26,564 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SerialTrace]
15:47:26,564 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
15:47:26,564 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
15:47:26,565 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [crash-analysis]
15:47:26,565 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
15:47:26,566 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.apache.http] to false
15:47:26,566 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache.http level set to INFO
15:47:26,566 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[org.apache.http]
15:47:26,567 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ConnectionTrace] to TRACE
15:47:26,567 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ConnectionTrace] to false
15:47:26,567 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [TraceLogger] to Logger[ConnectionTrace]
15:47:26,567 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [SerialTrace] to TRACE
15:47:26,567 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [SerialTrace] to false
15:47:26,567 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SerialTrace] to Logger[SerialTrace]
15:47:26,567 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [crash-analysis] to DEBUG
15:47:26,567 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [crash-analysis] to false
15:47:26,567 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [crash-analysis] to Logger[crash-analysis]
15:47:26,568 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdOutAppender] to Logger[ROOT]
15:47:26,568 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[ROOT]
15:47:26,568 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
15:47:26,569 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@c0fae94 - Registering current configuration as safe fallback point
[INFO][2024-04-29 15:47:26,572][[com.automatics.executor.Starter.main()] - Starting the execution process for the given test cases.
15:47:26,574 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
15:47:26,575 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-test-suite]
15:47:26,579 |-INFO in ch.qos.logback.core.FileAppender[FILE-test-suite] - File property is set to [target/logs/test-suite.log]
[INFO][2024-04-29 15:47:26,588][[com.automatics.executor.Starter.main()] - Initializing application context partner-applicationContext.xml
[INFO][2024-04-29 15:47:27,246][[com.automatics.executor.Starter.main()] - Initializing application context applicationContext.xml
[INFO][2024-04-29 15:47:27,310][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.130:8080/AutomaticsProps/automatics.properties
[INFO][2024-04-29 15:47:27,440][[com.automatics.executor.Starter.main()] - Automatics Response - {}
[INFO][2024-04-29 15:47:27,451][[com.automatics.executor.Starter.main()] - 

***************** Automatics BUILD PARAMETERS *****************
[INFO][2024-04-29 15:47:27,452][[com.automatics.executor.Starter.main()] - Shut Down Hook Attached.
[INFO][2024-04-29 15:47:27,455][[com.automatics.executor.Starter.main()] - Start Execution Test Suite
[INFO][2024-04-29 15:47:27,456][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-04-29 15:47:27,456][[com.automatics.executor.Starter.main()] - Cleaning the trace log directory
[INFO][2024-04-29 15:47:27,507][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.130:8080/AutomaticsProps/automatics.properties
[INFO][2024-04-29 15:47:27,512][[com.automatics.executor.Starter.main()] - >>>[INIT]: Validating config files
[INFO][2024-04-29 15:47:27,513][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.130:8080/AutomaticsProps/deviceProps.json
[INFO][2024-04-29 15:47:27,740][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.130:8080/AutomaticsProps/deviceProps.json
[INFO][2024-04-29 15:47:27,747][[com.automatics.executor.Starter.main()] - frameworkSupportedModels=Rpi-RDKB,Rpi-RDKV,
[INFO][2024-04-29 15:47:27,747][[com.automatics.executor.Starter.main()] - rdkvGWModels=
[INFO][2024-04-29 15:47:27,747][[com.automatics.executor.Starter.main()] - rdkvCLModels=Rpi-RDKV,
[INFO][2024-04-29 15:47:27,748][[com.automatics.executor.Starter.main()] - rdkbModels=Rpi-RDKB,
[INFO][2024-04-29 15:47:27,748][[com.automatics.executor.Starter.main()] - rdkcModels=
[INFO][2024-04-29 15:47:27,806][[com.automatics.executor.Starter.main()] - Reading implementation from core for deviceProvider
[INFO][2024-04-29 15:47:27,863][[com.automatics.executor.Starter.main()] - Is Account based test: false
[INFO][2024-04-29 15:47:27,866][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-04-29 15:47:29,145][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8C:13  Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-04-29 15:47:29,362][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.173","hostIp6Address":null,"clientIpAddress":"192.168.2.173","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.173","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.173","password":"root123","connectedClients":"192.168.2.81","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","connectionType":"SSH","nodePort":"22","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.fasterxml.jackson.databind.util.ClassUtil (file:/root/.m2/repository/com/fasterxml/jackson/core/jackson-databind/2.4.1/jackson-databind-2.4.1.jar) to field java.time.format.DateTimeFormatter.locale
WARNING: Please consider reporting this to the maintainers of com.fasterxml.jackson.databind.util.ClassUtil
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
[INFO][2024-04-29 15:47:29,437][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-04-29 15:47:29,438][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model Rpi-RDKB
[INFO][2024-04-29 15:47:29,439][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model Rpi-RDKB
[INFO][2024-04-29 15:47:29,439][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model Rpi-RDKB to  automatics model Rpi-RDKB
[INFO][2024-04-29 15:47:29,439][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device
[INFO][2024-04-29 15:47:29,450][[pool-2-thread-1] - Desk Box Testing enabled
[INFO][2024-04-29 15:47:29,451][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Skipping locking device as Non-Rack device
[INFO][2024-04-29 15:47:29,452][[pool-2-thread-1] - Setting access mechanism for device D8:3A:DD:13:8C:13 SSH
[INFO][2024-04-29 15:47:29,452][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Setting access method SSH
[INFO][2024-04-29 15:47:29,453][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required
[INFO][2024-04-29 15:47:29,453][[pool-2-thread-1] - Setting accessibility check required to false for device D8:3A:DD:13:8C:13 from device config
[INFO][2024-04-29 15:47:29,453][[pool-2-thread-1] - Accessibility check required for device D8:3A:DD:13:8C:13 false 
[INFO][2024-04-29 15:47:29,454][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required is false
[INFO][2024-04-29 15:47:29,454][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Assuming device is accessible
[INFO][2024-04-29 15:47:29,454][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Is device accessible true
[INFO][2024-04-29 15:47:29,454][[pool-2-thread-1] - Desk Box Testing enabled
[INFO][2024-04-29 15:47:29,454][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 DeviceConfig Connection Based TraceProvider wiring
[INFO][2024-04-29 15:47:29,457][[pool-2-thread-1] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-04-29 15:47:29,472][[pool-2-thread-1] - Additional trace support : null
[INFO][2024-04-29 15:47:29,472][[pool-2-thread-1] - settopObj.getModel() = Rpi-RDKB
[INFO][2024-04-29 15:47:29,472][[pool-2-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue
[INFO][2024-04-29 15:47:29,476][[pool-2-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-04-29 15:47:29,476][[pool-2-thread-1] - Crash Analysis not enabled during trace monitoring
[INFO][2024-04-29 15:47:29,477][[pool-2-thread-1] - Serial based trace to be initialized: false
[INFO][2024-04-29 15:47:29,478][[pool-2-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-04-29 15:47:29,478][[pool-2-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:47:29,479][[pool-2-thread-1] - Dut Added to locked list D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:47:32,448][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads
[INFO][2024-04-29 15:47:32,449][[com.automatics.executor.Starter.main()] - Collecting dut info
[INFO][2024-04-29 15:47:32,449][[com.automatics.executor.Starter.main()] - Requesting data from http://192.168.2.59:8084/api/rack/service/getmasterconfig?configName=TEST_TYPE_MAP
[INFO][2024-04-29 15:47:32,470][[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-04-29 15:47:33,181][[com.automatics.executor.Starter.main()] - AutomaticsTapApi instance not available. Creating new instance
[INFO][2024-04-29 15:47:33,181][[com.automatics.executor.Starter.main()] - Creating new instance for AutomaticsTapApi
[INFO][2024-04-29 15:47:33,182][[com.automatics.executor.Starter.main()] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-04-29 15:47:33,355][[com.automatics.executor.Starter.main()] - >>>[BEFORE_SUITE]: Perform before suite initialization
[INFO][2024-04-29 15:47:33,355][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-04-29 15:47:33,355][[com.automatics.executor.Starter.main()] - Adding locked devices to dut object in AutomaticsTestBase
[INFO][2024-04-29 15:47:33,356][[com.automatics.executor.Starter.main()] - Locked Settops: 1
[INFO][2024-04-29 15:47:33,373][[pool-4-thread-1] - >>>[BEFORE_SUITE]: Verifying if build loaded in device as expected
[INFO][2024-04-29 15:47:33,374][[pool-4-thread-1] - [BEFORE-SUITE:]Expected build in device is rdkb-generic-broadband-image_rdk-next_20240215073601
[INFO][2024-04-29 15:47:33,374][[pool-4-thread-1] - [BEFORE-SUITE:]Verifying if build changed before test
[INFO][2024-04-29 15:47:33,374][[pool-4-thread-1] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-04-29 15:47:33,375][[pool-4-thread-1] - [BEFORE-SUITE:]Build Change Status: NO_CHANGE
[INFO][2024-04-29 15:47:33,375][[pool-4-thread-1] - >>>[BEFORE_SUITE]: Build in device is as expected
[INFO][2024-04-29 15:47:33,375][[pool-4-thread-1] - >>>[BEFORE_SUITE]: Setting appropritate build appender based on executionMode
[INFO][2024-04-29 15:47:33,396][[pool-4-thread-1] - Skipping setting of execution mode in device as partner specific initialization is not configured.
[INFO][2024-04-29 15:47:33,397][[pool-4-thread-1] - INIT-D8:3A:DD:13:8C:13 Perform before suite initialization
[INFO][2024-04-29 15:47:33,397][[pool-4-thread-1] - Skipping partner specific before suite initialization as it is not configured.
[INFO][2024-04-29 15:47:33,397][[pool-4-thread-1] - >>>[BEFORE_SUITE]: Starting device connection trace
[INFO][2024-04-29 15:47:33,398][[pool-4-thread-1] - Starting trace with command : tail -F -n 0  /rdklogs/logs/*
[INFO][2024-04-29 15:47:33,425][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Starting reading..D83ADD138C13settop_trace.log,
15:47:33,425 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
15:47:33,436 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-D83ADD138C13settop_trace.log]
15:47:33,437 |-INFO in ch.qos.logback.core.FileAppender[FILE-D83ADD138C13settop_trace.log] - File property is set to [target/settoptrace/D83ADD138C13settop_trace.log]
[INFO][2024-04-29 15:47:33,438][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Going to connect device for trace monitoring .....!
[INFO][2024-04-29 15:47:33,438][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP Address : 192.168.2.173
[INFO][2024-04-29 15:47:33,438][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP6 Address : null
[INFO][2024-04-29 15:47:33,438][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host MAC Address : D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:47:33,438][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside Non RDKV client device
[INFO][2024-04-29 15:47:33,439][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - getConnection method invoked 
[INFO][2024-04-29 15:47:33,456][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:47:33,457][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-04-29 15:47:33,502][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Reading server-config.xml from /var/lib/jenkins/workspace/testrepo/server-config.xml
[INFO][2024-04-29 15:47:33,552][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:47:33,950][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:47:33,951][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:47:33,951][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-04-29 15:47:33,967][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:47:34,184][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Received response: 
[INFO][2024-04-29 15:47:36,185][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connectiongateway iscom.automatics.providers.connection.SshConnection@1f05e05f
[INFO][2024-04-29 15:47:36,185][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - inside null != connectionGateway condition 
[INFO][2024-04-29 15:47:36,185][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - gateWayDeviceInputStream isnull
[INFO][2024-04-29 15:47:36,187][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside else block
[INFO][2024-04-29 15:47:36,187][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Creating polling connection
[INFO][2024-04-29 15:47:36,187][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Is RDKV Client trace false
[INFO][2024-04-29 15:47:36,187][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Read trace
[INFO][2024-04-29 15:47:36,188][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked 
[INFO][2024-04-29 15:47:36,189][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:47:36,190][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[ERROR][2024-04-29 15:47:36,214][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - readOutputFromChannel - Exception Details ------ 
java.lang.NullPointerException: null
	at com.automatics.providers.trace.AbstractTraceProviderImpl.readOutputFromChannel(AbstractTraceProviderImpl.java:824)
	at com.automatics.providers.trace.AbstractTraceProviderImpl.connectAndRead(AbstractTraceProviderImpl.java:735)
	at com.automatics.providers.trace.AbstractTraceProviderImpl$ConnectionThread.run(AbstractTraceProviderImpl.java:1147)
[INFO][2024-04-29 15:47:36,215][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Out of readOutputFromChannel loop
[INFO][2024-04-29 15:47:36,228][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-04-29 15:47:36,228][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-04-29 15:47:36,235][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:47:36,441][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Checking if trace connection alive
[INFO][2024-04-29 15:47:36,441][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-04-29 15:47:36,441][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked 
[INFO][2024-04-29 15:47:36,441][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:47:36,441][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-04-29 15:47:36,455][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:47:36,647][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-04-29 15:47:36,647][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute: 
[INFO][2024-04-29 15:47:36,648][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-04-29 15:47:36,650][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:47:36,781][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-04-29 15:47:36,782][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@10ca8d22
[INFO][2024-04-29 15:47:36,792][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-04-29 15:48:03,425][[pool-4-thread-1] - Is trace required for connected gateway: false
[INFO][2024-04-29 15:48:03,426][[pool-4-thread-1] - Is trace required for connected gateway: false
[INFO][2024-04-29 15:48:06,368][[com.automatics.executor.Starter.main()] - Starting testing on class: org.testng.TestRunner
[INFO][2024-04-29 15:48:06,479][[com.automatics.executor.Starter.main()] - Doing initialization for D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:06,494][[com.automatics.executor.Starter.main()] - Getting device account no. for D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:06,495][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-04-29 15:48:06,516][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8C:13  Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-04-29 15:48:06,530][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.173","hostIp6Address":null,"clientIpAddress":"192.168.2.173","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.173","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.173","connectedClients":"192.168.2.81","password":"root123","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","nodePort":"22","connectionType":"SSH","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
[INFO][2024-04-29 15:48:06,547][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-04-29 15:48:06,548][[com.automatics.executor.Starter.main()] - Device account 123456789 for device D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:06,548][[com.automatics.executor.Starter.main()] - Getting device account no. for D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:06,548][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-04-29 15:48:06,564][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8C:13  Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-04-29 15:48:06,577][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.173","hostIp6Address":null,"clientIpAddress":"192.168.2.173","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.173","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.173","connectedClients":"192.168.2.81","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":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
[INFO][2024-04-29 15:48:06,597][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-04-29 15:48:06,602][[com.automatics.executor.Starter.main()] - Device account 123456789 for device D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:06,602][[com.automatics.executor.Starter.main()] - Locking connected devices
[INFO][2024-04-29 15:48:06,602][[com.automatics.executor.Starter.main()] - Getting devices for home account 123456789
[INFO][2024-04-29 15:48:06,627][[com.automatics.executor.Starter.main()] - Fetching account details for 123456789  Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/getAccountDetails
[INFO][2024-04-29 15:48:06,648][[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.173","hostIp6Address":null,"clientIpAddress":"192.168.2.173","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.173","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.173","password":"root123","connectedClients":"192.168.2.81","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":""},{"id":"3853","name":"","hardwareRevision":"","hostMacAddress":"2C:44:FD:18:F9:FE","hostIp4Address":"192.168.2.81","hostIp6Address":null,"clientIpAddress":"192.168.2.81","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.81","headend":"HE","gatewayMac":"","extraProperties":{"deviceIp":"192.168.2.81","password":"asdf@1234","ethernetMacAddress":"2C:44:FD:18:F9:FE","osType":"WINDOWS","devicePort":"22","wifiCapability":"Dual Band","connectionType":"Ethernet","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":""},{"id":"3854","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8B:B3","hostIp4Address":"192.168.2.55","hostIp6Address":null,"clientIpAddress":"192.168.2.55","model":"RPI-RDKV","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"D8:3A:DD:13:8B:B3","mtaMacAddress":"D8:3A:DD:13:8B:B3","mtaIpAddress":"192.168.2.55","ecmMacAddress":"D8:3A:DD:13:8B:B3","ecmIpAddress":"192.168.2.55","headend":"HE","gatewayMac":"D8:3A:DD:13:8B:B3","extraProperties":{"deviceIp":"192.168.2.55","password":"root123","ethernetMacAddress":"D8:3A:DD:13:8B:B3","devicePort":"22","connectionType":"SSH","nodePort":"22","username":"root","wifiMacAddress":""},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}]}
[INFO][2024-04-29 15:48:06,673][[com.automatics.executor.Starter.main()] - Devices for home account 123456789 are [D8:3A:DD:13:8C:13, 2C:44:FD:18:F9:FE, D8:3A:DD:13:8B:B3]
[INFO][2024-04-29 15:48:06,677][[com.automatics.executor.Starter.main()] - Obtained all connected device macs for the account
[INFO][2024-04-29 15:48:06,678][[com.automatics.executor.Starter.main()] - INIT-2C:44:FD:18:F9:FE Get device details
[INFO][2024-04-29 15:48:06,691][[com.automatics.executor.Starter.main()] - Fetching device details for 2C:44:FD:18:F9:FE  Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-04-29 15:48:06,705][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3853","name":"","hardwareRevision":"","hostMacAddress":"2C:44:FD:18:F9:FE","hostIp4Address":"192.168.2.81","hostIp6Address":null,"clientIpAddress":"192.168.2.81","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.81","headend":"HE","gatewayMac":"","extraProperties":{"deviceIp":"192.168.2.81","password":"asdf@1234","ethernetMacAddress":"2C:44:FD:18:F9:FE","osType":"WINDOWS","devicePort":"22","wifiCapability":"Dual Band","nodePort":"4444","connectionType":"Ethernet","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-04-29 15:48:06,716][[com.automatics.executor.Starter.main()] - INIT-2C:44:FD:18:F9:FE Obtained device details
[INFO][2024-04-29 15:48:06,716][[com.automatics.executor.Starter.main()] - >>>[INIT]: No device config mapped for rack model WINDOWSVM. Proceeding with rack model
[INFO][2024-04-29 15:48:06,717][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device
[INFO][2024-04-29 15:48:06,717][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8B:B3 Get device details
[INFO][2024-04-29 15:48:06,744][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8B:B3  Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-04-29 15:48:06,758][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3854","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8B:B3","hostIp4Address":"192.168.2.55","hostIp6Address":null,"clientIpAddress":"192.168.2.55","model":"RPI-RDKV","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"D8:3A:DD:13:8B:B3","mtaMacAddress":"D8:3A:DD:13:8B:B3","mtaIpAddress":"192.168.2.55","ecmMacAddress":"D8:3A:DD:13:8B:B3","ecmIpAddress":"192.168.2.55","headend":"HE","gatewayMac":"D8:3A:DD:13:8B:B3","extraProperties":{"deviceIp":"192.168.2.55","password":"root123","ethernetMacAddress":"D8:3A:DD:13:8B:B3","devicePort":"22","nodePort":"22","connectionType":"SSH","username":"root","wifiMacAddress":""},"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-04-29 15:48:06,783][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8B:B3 Obtained device details
[INFO][2024-04-29 15:48:06,783][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model RPI-RDKV
[INFO][2024-04-29 15:48:06,783][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model RPI-RDKV
[INFO][2024-04-29 15:48:06,783][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model RPI-RDKV to  automatics model Rpi-RDKV
[INFO][2024-04-29 15:48:06,784][[com.automatics.executor.Starter.main()] - [INIT LOG] : RDKV Client device
[INFO][2024-04-29 15:48:06,784][[com.automatics.executor.Starter.main()] - Desk Box Testing enabled
[INFO][2024-04-29 15:48:06,784][[pool-14-thread-1] - Desk Box Testing enabled
[INFO][2024-04-29 15:48:06,784][[pool-14-thread-1] - INIT-2C:44:FD:18:F9:FE Skipping locking device as Non-Rack device
[INFO][2024-04-29 15:48:06,785][[pool-14-thread-1] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-04-29 15:48:06,785][[pool-14-thread-1] - Setting default SSH access mechanism for device null
[INFO][2024-04-29 15:48:06,785][[pool-14-thread-1] - INIT-2C:44:FD:18:F9:FE Setting access method SSH
[INFO][2024-04-29 15:48:06,786][[pool-14-thread-1] - INIT-2C:44:FD:18:F9:FE Checking if accessibility check required
[INFO][2024-04-29 15:48:06,786][[pool-14-thread-1] - Accessibility check required for device 2C:44:FD:18:F9:FE false 
[INFO][2024-04-29 15:48:06,786][[pool-14-thread-1] - INIT-2C:44:FD:18:F9:FE Checking if accessibility check required is false
[INFO][2024-04-29 15:48:06,786][[pool-14-thread-1] - INIT-2C:44:FD:18:F9:FE Assuming device is accessible
[INFO][2024-04-29 15:48:06,786][[pool-14-thread-1] - INIT-2C:44:FD:18:F9:FE Is device accessible true
[INFO][2024-04-29 15:48:06,787][[pool-14-thread-1] - Desk Box Testing enabled
[INFO][2024-04-29 15:48:06,787][[pool-14-thread-1] - INIT-2C:44:FD:18:F9:FE DeviceConfig Connection Based TraceProvider wiring
[INFO][2024-04-29 15:48:06,787][[pool-14-thread-1] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-04-29 15:48:06,787][[pool-14-thread-1] - Additional trace support : null
[INFO][2024-04-29 15:48:06,787][[pool-14-thread-1] - settopObj.getModel() = WINDOWSVM
[INFO][2024-04-29 15:48:06,788][[pool-14-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue
[INFO][2024-04-29 15:48:06,788][[pool-14-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/2C44FD18F9FEsettop_trace.log
[INFO][2024-04-29 15:48:06,788][[pool-14-thread-1] - Crash Analysis not enabled during trace monitoring
[INFO][2024-04-29 15:48:06,788][[pool-14-thread-1] - Serial based trace to be initialized: false
[INFO][2024-04-29 15:48:06,788][[pool-14-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-04-29 15:48:06,789][[pool-14-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:06,789][[pool-14-thread-1] - Dut Added to locked list 2C:44:FD:18:F9:FE
[INFO][2024-04-29 15:48:09,784][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads
[INFO][2024-04-29 15:48:09,786][[pool-15-thread-1] - [INIT LOG] : No gateway device available for initialization for client
[INFO][2024-04-29 15:48:12,786][[com.automatics.executor.Starter.main()] - Finished Client Connected Gateway Initialization Threads
[INFO][2024-04-29 15:48:12,787][[pool-16-thread-1] - Desk Box Testing enabled
[INFO][2024-04-29 15:48:12,787][[pool-16-thread-1] - [INIT LOG] : Skipping locking client device as Non-Rack device
[INFO][2024-04-29 15:48:12,788][[pool-16-thread-1] - Setting access mechanism for device D8:3A:DD:13:8B:B3 SSH
[INFO][2024-04-29 15:48:12,788][[pool-16-thread-1] - INIT-D8:3A:DD:13:8B:B3 Setting access method SSH
[INFO][2024-04-29 15:48:12,788][[pool-16-thread-1] - INIT-D8:3A:DD:13:8B:B3 Checking if accessibility check required
[INFO][2024-04-29 15:48:12,788][[pool-16-thread-1] - Setting accessibility check required to false for device D8:3A:DD:13:8B:B3 from device config
[INFO][2024-04-29 15:48:12,788][[pool-16-thread-1] - Accessibility check required for device D8:3A:DD:13:8B:B3 false 
[INFO][2024-04-29 15:48:12,789][[pool-16-thread-1] - INIT-D8:3A:DD:13:8B:B3 Accessibility check required false
[INFO][2024-04-29 15:48:12,789][[pool-16-thread-1] - INIT-D8:3A:DD:13:8B:B3 Assuming device is accessible
[INFO][2024-04-29 15:48:12,789][[pool-16-thread-1] - INIT-D8:3A:DD:13:8B:B3 Is device accessible true
[INFO][2024-04-29 15:48:12,789][[pool-16-thread-1] - Desk Box Testing enabled
[INFO][2024-04-29 15:48:12,789][[pool-16-thread-1] - INIT-D8:3A:DD:13:8B:B3 DeviceConfig Connection Based TraceProvider wiring
[INFO][2024-04-29 15:48:12,789][[pool-16-thread-1] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-04-29 15:48:12,790][[pool-16-thread-1] - Additional trace support : null
[INFO][2024-04-29 15:48:12,790][[pool-16-thread-1] - settopObj.getModel() = Rpi-RDKV
[INFO][2024-04-29 15:48:12,790][[pool-16-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue
[INFO][2024-04-29 15:48:12,791][[pool-16-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138BB3settop_trace.log
[INFO][2024-04-29 15:48:12,791][[pool-16-thread-1] - Crash Analysis not enabled during trace monitoring
[INFO][2024-04-29 15:48:12,791][[pool-16-thread-1] - Serial based trace to be initialized: false
[INFO][2024-04-29 15:48:12,792][[pool-16-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-04-29 15:48:12,792][[pool-16-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:12,792][[pool-16-thread-1] - Dut Added to locked list D8:3A:DD:13:8B:B3
[INFO][2024-04-29 15:48:15,787][[com.automatics.executor.Starter.main()] - Finished Client Devices Initialization Thread
[INFO][2024-04-29 15:48:15,787][[com.automatics.executor.Starter.main()] - Starting trace from provider for connected clients if not already started
[INFO][2024-04-29 15:48:15,787][[com.automatics.executor.Starter.main()] - =========================================================================
[INFO][2024-04-29 15:48:15,788][[com.automatics.executor.Starter.main()] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-04-29 15:48:15,788][[com.automatics.executor.Starter.main()] - Trace not applicable for Non RDK Devices - 2C:44:FD:18:F9:FE
[INFO][2024-04-29 15:48:15,788][[com.automatics.executor.Starter.main()] - Starting trace from provider for connected clients if not already started
[INFO][2024-04-29 15:48:15,788][[com.automatics.executor.Starter.main()] - =========================================================================
[INFO][2024-04-29 15:48:15,788][[com.automatics.executor.Starter.main()] - sshTrace.isMonitoringStarted() for D8:3A:DD:13:8B:B3 = false
[INFO][2024-04-29 15:48:15,789][[com.automatics.executor.Starter.main()] - Starting trace with command : sh /opt/journalctl_logs_v1.sh
[INFO][2024-04-29 15:48:15,789][[com.automatics.executor.Starter.main()] - Clearing all existing journalctl and tail processes.
[INFO][2024-04-29 15:48:15,789][[com.automatics.executor.Starter.main()] - About to create SSH connection to DutIP:192.168.2.55
[INFO][2024-04-29 15:48:15,789][[com.automatics.executor.Starter.main()] - SSH Host IP : 192.168.2.55
[INFO][2024-04-29 15:48:15,789][[com.automatics.executor.Starter.main()] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:15,845][[com.automatics.executor.Starter.main()] - SSh connection attempet : 1 failed due to RESOURCE_NOT_FOUND: Resource file not found.Unable to fetch ssh connection details for 192.168.2.55 in the serverconfig.xml file and device manager. for 192.168.2.55. Trying once more..
[INFO][2024-04-29 15:48:25,845][[com.automatics.executor.Starter.main()] - SSh connection attempet : 2
[INFO][2024-04-29 15:48:25,892][[com.automatics.executor.Starter.main()] - SSh connection attempet : 2 failed due to RESOURCE_NOT_FOUND: Resource file not found.Unable to fetch ssh connection details for 192.168.2.55 in the serverconfig.xml file and device manager. for 192.168.2.55. Trying once more..
[INFO][2024-04-29 15:48:35,892][[com.automatics.executor.Starter.main()] - SSh connection attempet : 3
[INFO][2024-04-29 15:48:35,908][[com.automatics.executor.Starter.main()] - SSh connection attempet : 3 failed due to RESOURCE_NOT_FOUND: Resource file not found.Unable to fetch ssh connection details for 192.168.2.55 in the serverconfig.xml file and device manager. for 192.168.2.55. Trying once more..
[INFO][2024-04-29 15:48:36,793][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Creating polling connection
[INFO][2024-04-29 15:48:36,793][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked 
[INFO][2024-04-29 15:48:36,793][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:36,793][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:36,805][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:48:37,025][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Checking if trace connection alive
[INFO][2024-04-29 15:48:37,025][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-04-29 15:48:37,025][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked 
[INFO][2024-04-29 15:48:37,025][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:37,025][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:37,036][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:48:37,247][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-04-29 15:48:37,247][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute: 
[INFO][2024-04-29 15:48:37,247][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-04-29 15:48:37,247][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:48:37,370][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-04-29 15:48:37,370][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@10ca8d22
[INFO][2024-04-29 15:48:37,370][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-04-29 15:48:45,908][[com.automatics.executor.Starter.main()] - SSh connection attempet : 4
[INFO][2024-04-29 15:48:45,922][[com.automatics.executor.Starter.main()] - SSh connection attempet : 4 failed due to RESOURCE_NOT_FOUND: Resource file not found.Unable to fetch ssh connection details for 192.168.2.55 in the serverconfig.xml file and device manager. for 192.168.2.55. 
[ERROR][2024-04-29 15:48:45,923][[com.automatics.executor.Starter.main()] - Exception while starting trace for CONNECTION: SSH connection failure to the server.RESOURCE_NOT_FOUND: Resource file not found.Unable to fetch ssh connection details for 192.168.2.55 in the serverconfig.xml file and device manager.,{}D8:3A:DD:13:8B:B3
[INFO][2024-04-29 15:48:45,954][[com.automatics.executor.Starter.main()] - NUMBER OF CONNECTED CLIENTS ASSOCIATED WITH DEVICE D8:3A:DD:13:8C:13 IS 2
[INFO][2024-04-29 15:48:45,954][[com.automatics.executor.Starter.main()] - CONNECTED CLIENTS ASSOCIATED WITH DEVICE D8:3A:DD:13:8C:13 IS 2C:44:FD:18:F9:FE D8:3A:DD:13:8B:B3 
[INFO][2024-04-29 15:48:45,971][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Perform before method initialization D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:45,972][[TestNG-PoolService-0] - Setting dattime in patter yyyy-MM-dd HH:mm:ss
[INFO][2024-04-29 15:48:46,038][[TestNG-PoolService-0] - startTime 2024-04-29 10:18:46
[INFO][2024-04-29 15:48:46,038][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Sending test exection start time to Automatics D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:46,040][[TestNG-PoolService-0] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-04-29 15:48:46,049][[TestNG-PoolService-0] - {"jobId":4567,"automationId":"TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":1714385926039,"endDateTimeEST":0}
[INFO][2024-04-29 15:48:46,189][[TestNG-PoolService-0] - Capture execution time - Response : HTTP/1.1 400 Bad Request
[INFO][2024-04-29 15:48:46,190][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Skipping partner specific before method initialization as it is not configured.
15:48:46,190 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
15:48:46,190 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-Rpi-RDKB-D83ADD138C13]
15:48:46,191 |-INFO in ch.qos.logback.core.FileAppender[FILE-Rpi-RDKB-D83ADD138C13] - File property is set to [target/logs/Rpi-RDKB-D83ADD138C13.log]
[INFO][2024-04-29 15:48:46,192][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-04-29 15:48:46,192][D8:3A:DD:13:8C:13[TestNG-PoolService-0] -  Get the locked status: false
[INFO][2024-04-29 15:48:46,192][D8:3A:DD:13:8C:13[TestNG-PoolService-0] -  Get the locked status: false
[INFO][2024-04-29 15:48:46,193][D8:3A:DD:13:8C:13[TestNG-PoolService-0] -  Get the locked status: false
[INFO][2024-04-29 15:48:46,193][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-04-29 15:48:46,193][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-04-29 15:48:46,203][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - <a name="com.automatics.rdkb.tests.wifi.connectedclients.BroadBandWiFiConnectedClientTests.testToVerifyIpv4ValuePostDhcpServerDisableForLanClient">STARTED - testToVerifyIpv4ValuePostDhcpServerDisableForLanClient - com.automatics.device.Device@10ca8d22</a>
[INFO][2024-04-29 15:48:46,204][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Getting MDC = D83ADD138C13settop_trace.log
[INFO][2024-04-29 15:48:46,205][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Getting MDC = D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:48:46,212][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - #######################################################################################
[INFO][2024-04-29 15:48:46,213][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STARTING TEST CASE: TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501
[INFO][2024-04-29 15:48:46,213][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TEST DESCRIPTION: Verify the IP range in Ethernet connected client when DHCP Server Disabled.
[INFO][2024-04-29 15:48:46,213][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TEST STEPS : 
[INFO][2024-04-29 15:48:46,213][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - PRE-CONDITION : Get the DHCP Minimum and Maximum IP Range Configured for Gateway.
[INFO][2024-04-29 15:48:46,213][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 1. Verify the DHCP Server Enable Status value for the Ethernet client Via WEBPA GET Parameter: Device.DHCPv4.Server.Enable
[INFO][2024-04-29 15:48:46,214][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 2. Set and Verify the DHCP Server Disabled for Ethernet client via WEBPA Parameter: Device.DHCPv4.Server.Enable with Set Value as false
[INFO][2024-04-29 15:48:46,214][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 3. Obtain and Connect to the Ethernet Client Associated with the Gateway.
[INFO][2024-04-29 15:48:46,214][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 4. Verify IPv4  assigned on the ethernet client is  not in the DHCP address range.
[INFO][2024-04-29 15:48:46,214][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 5. Verify internet is not accessibble by using intetface IPv4 obtained on the ethernet client.
[INFO][2024-04-29 15:48:46,214][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - POST-CONDITION : Set and Verify the DHCP Server Enabled for Ethernet client via WEBPA Parameter: Device.DHCPv4.Server.Enable with Set Value as true
[INFO][2024-04-29 15:48:46,215][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - #######################################################################################
[INFO][2024-04-29 15:48:46,215][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - ################### STARTING PRE-CONFIGURATIONS ###################
[INFO][2024-04-29 15:48:46,215][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - PRE-CONDITION STEPS
[INFO][2024-04-29 15:48:46,215][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - #######################################################################################
[INFO][2024-04-29 15:48:46,215][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - PRE-CONDITION : DESCRIPTION : Get the DHCP Minimum and Maximum IP Range Configured for Gateway.
[INFO][2024-04-29 15:48:46,215][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - PRE-CONDITION : ACTION : Get the DHCP Minimum and Maximum IP Range using WEBPA Parameter : Device.DHCPv4.Server.Pool.1.MaxAddress and Device.DHCPv4.Server.Pool.1.MinAddress
[INFO][2024-04-29 15:48:46,216][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - PRE-CONDITION : EXPECTED : Getting the Minimum and Maximum Range using WEBPA Parameter Should be successful.
[INFO][2024-04-29 15:48:46,216][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - #######################################################################################
[INFO][2024-04-29 15:48:46,232][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:48:46,232][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:48:46,232][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-04-29 15:48:46,233][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-04-29 15:48:46,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to execute commands
[INFO][2024-04-29 15:48:46,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:46,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:46,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:46,254][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:48:46,442][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanSubnetMask
[INFO][2024-04-29 15:48:46,442][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:48:46,565][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanSubnetMask
Execution succeed.
Parameter    1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanSubnetMask
               type:     string,    value: 255.255.255.128 

<=============================================================>
[INFO][2024-04-29 15:48:46,565][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:46,566][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanSubnetMask
Execution succeed.
Parameter    1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanSubnetMask
               type:     string,    value: 255.255.255.128 

[INFO][2024-04-29 15:48:46,566][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanSubnetMask
Execution succeed.
Parameter    1 name: Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanSubnetMask
               type:     string,    value: 255.255.255.128 

[INFO][2024-04-29 15:48:46,566][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli param value : 255.255.255.128
[INFO][2024-04-29 15:48:46,566][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanSubnetMask, protocolSpecificParamName=Device.X_CISCO_COM_DeviceControl.LanManagementEntry.1.LanSubnetMask, value=255.255.255.128, datatype=null, statusCode=0]
[INFO][2024-04-29 15:48:46,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:48:46,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:48:46,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-04-29 15:48:46,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-04-29 15:48:46,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to execute commands
[INFO][2024-04-29 15:48:46,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:46,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:46,567][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:46,578][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:48:46,779][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DHCPv4.Server.Pool.1.MinAddress
[INFO][2024-04-29 15:48:46,779][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Pool.1.MinAddress
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Pool.1.MinAddress
               type:     string,    value: 10.0.0.2 

<=============================================================>
[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Pool.1.MinAddress
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Pool.1.MinAddress
               type:     string,    value: 10.0.0.2 

[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Pool.1.MinAddress
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Pool.1.MinAddress
               type:     string,    value: 10.0.0.2 

[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli param value : 10.0.0.2
[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DHCPv4.Server.Pool.1.MinAddress, protocolSpecificParamName=Device.DHCPv4.Server.Pool.1.MinAddress, value=10.0.0.2, datatype=null, statusCode=0]
[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-04-29 15:48:46,912][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to execute commands
[INFO][2024-04-29 15:48:46,913][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:46,913][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:46,913][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:46,923][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:48:47,120][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DHCPv4.Server.Pool.1.MaxAddress
[INFO][2024-04-29 15:48:47,120][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:48:47,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Pool.1.MaxAddress
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Pool.1.MaxAddress
               type:     string,    value: 10.0.0.125 

<=============================================================>
[INFO][2024-04-29 15:48:47,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:47,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Pool.1.MaxAddress
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Pool.1.MaxAddress
               type:     string,    value: 10.0.0.125 

[INFO][2024-04-29 15:48:47,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Pool.1.MaxAddress
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Pool.1.MaxAddress
               type:     string,    value: 10.0.0.125 

[INFO][2024-04-29 15:48:47,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli param value : 10.0.0.125
[INFO][2024-04-29 15:48:47,243][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DHCPv4.Server.Pool.1.MaxAddress, protocolSpecificParamName=Device.DHCPv4.Server.Pool.1.MaxAddress, value=10.0.0.125, datatype=null, statusCode=0]
[INFO][2024-04-29 15:48:47,244][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:48:47,244][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:48:47,244][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-04-29 15:48:47,244][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-04-29 15:48:47,244][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to execute commands
[INFO][2024-04-29 15:48:47,244][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:47,244][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:47,244][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:47,254][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:48:47,437][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DHCPv4.Server.Pool.1.LeaseTime
[INFO][2024-04-29 15:48:47,438][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:48:47,560][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Pool.1.LeaseTime
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Pool.1.LeaseTime
               type:        int,    value: 604800 

<=============================================================>
[INFO][2024-04-29 15:48:47,560][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:47,560][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Pool.1.LeaseTime
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Pool.1.LeaseTime
               type:        int,    value: 604800 

[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Pool.1.LeaseTime
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Pool.1.LeaseTime
               type:        int,    value: 604800 

[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli param value : 604800
[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DHCPv4.Server.Pool.1.LeaseTime, protocolSpecificParamName=Device.DHCPv4.Server.Pool.1.LeaseTime, value=604800, datatype=null, statusCode=0]
[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - PRE-CONDITION : ACTUAL: Getting the Minimum and Maximum Range using WEBPA Parameter is successful.
[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - PRE-CONFIGURATIONS : FINAL STATUS -  false
[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - ################### COMPLETED PRE-CONFIGURATIONS ###################
[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 1: DESCRIPTION : Verify the DHCP Server Enable Status value for the client connected Via WEBPA GET Parameter: Device.DHCPv4.Server.Enable
[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 1: ACTION : Execute command  :curl -X -H Authorization: Bearer <SAT_TOKEN> GET  '<WEBPA_URL><CM-MAC>/config?names=Device.DHCPv4.Server.Enable
[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 1: EXPECTED : DHCP Server value obtained via WEBPA should be true as Expected.
[INFO][2024-04-29 15:48:47,561][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:47,562][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:48:47,562][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:48:47,562][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-04-29 15:48:47,562][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-04-29 15:48:47,562][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to execute commands
[INFO][2024-04-29 15:48:47,562][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:47,562][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:47,562][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:47,610][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:48:47,812][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DHCPv4.Server.Enable
[INFO][2024-04-29 15:48:47,812][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:48:47,935][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: true 

<=============================================================>
[INFO][2024-04-29 15:48:47,935][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:47,935][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: true 

[INFO][2024-04-29 15:48:47,935][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: true 

[INFO][2024-04-29 15:48:47,935][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli param value : true
[INFO][2024-04-29 15:48:47,935][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DHCPv4.Server.Enable, protocolSpecificParamName=Device.DHCPv4.Server.Enable, value=true, datatype=null, statusCode=0]
[INFO][2024-04-29 15:48:47,936][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - DHCP Server Enable Status value Retrieved From WEBPA GET is true
[INFO][2024-04-29 15:48:47,940][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Pattern Found = true
[INFO][2024-04-29 15:48:47,940][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - true
[INFO][2024-04-29 15:48:47,940][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - is patter found for true in target string = true
[INFO][2024-04-29 15:48:47,941][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 1: ACTUAL : DHCP Server value obtained via WEBPA is true As Expected.
[INFO][2024-04-29 15:48:47,941][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:47,950][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501] [step Number : S1][ Execution status : PASS] [Error Message : ]
[INFO][2024-04-29 15:48:47,953][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Fetching device property: FIRMWARE_VERSION
[INFO][2024-04-29 15:48:47,964][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Fetching device props for D8:3A:DD:13:8C:13 for props [FIRMWARE_VERSION] Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/getDeviceProps
[INFO][2024-04-29 15:48:47,988][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : 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-04-29 15:48:47,989][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Getting value for property: FIRMWARE_VERSION
[INFO][2024-04-29 15:48:47,989][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Value: RPI-RDKB
[INFO][2024-04-29 15:48:47,989][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Device property name: FIRMWARE_VERSION value obtained: RPI-RDKB
[INFO][2024-04-29 15:48:48,010][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Result Details ->{"JMD_ID":"4567","manualId":"TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501","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-LAN-DHCP-IPV4-DSBLE-5001"}
[ERROR][2024-04-29 15:48:48,052][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : 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-04-29 15:48:48,052][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:48,052][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 2: DESCRIPTION : Set and Verify the DHCP Server Disabled for client connected via WEBPA Parameter:Device.DHCPv4.Server.Enable with Set Value as FALSE.
[INFO][2024-04-29 15:48:48,053][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 2: ACTION : Execute WEBPA SET command :curl -4 -k -H Authorization: Bearer <SAT Token> -X PATCH <WEBPA_URL><MAC Address>/config?names=Device.DHCPv4.Server.Enable with value set as false
[INFO][2024-04-29 15:48:48,053][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 2: EXPECTED : DHCP Server should be disabled successfully via WEBPA SET.
[INFO][2024-04-29 15:48:48,053][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:48,069][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:48:48,069][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:48:48,069][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-04-29 15:48:48,069][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-04-29 15:48:48,069][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to execute commands
[INFO][2024-04-29 15:48:48,069][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:48,069][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:48,069][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:48,082][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:48:48,299][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DHCPv4.Server.Enable
[INFO][2024-04-29 15:48:48,300][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:48:48,422][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: true 

<=============================================================>
[INFO][2024-04-29 15:48:48,422][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:48,422][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: true 

[INFO][2024-04-29 15:48:48,422][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: true 

[INFO][2024-04-29 15:48:48,422][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli param value : true
[INFO][2024-04-29 15:48:48,423][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DHCPv4.Server.Enable, protocolSpecificParamName=Device.DHCPv4.Server.Enable, value=true, datatype=null, statusCode=0]
[INFO][2024-04-29 15:48:48,423][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WebPA get successful
[INFO][2024-04-29 15:48:48,436][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WebPa data type: BOOLEAN
[INFO][2024-04-29 15:48:48,437][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 parametrs status code  added  0
[INFO][2024-04-29 15:48:48,437][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:48:48,437][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:48:48,437][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.DHCPv4.Server.Enable bool false
[INFO][2024-04-29 15:48:48,437][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:48,437][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:48,438][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:48,448][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:48:48,635][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT setv Device.DHCPv4.Server.Enable bool false
[INFO][2024-04-29 15:48:48,636][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:48:53,762][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.

<=============================================================>
[INFO][2024-04-29 15:48:53,763][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:53,763][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.

[INFO][2024-04-29 15:48:53,763][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.

[INFO][2024-04-29 15:48:53,763][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : {Device.DHCPv4.Server.Enable=Success}
[INFO][2024-04-29 15:48:53,764][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-04-29 15:48:53,764][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-04-29 15:48:53,764][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-04-29 15:48:53,796][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WEBPA PARAM - Device.DHCPv4.Server.Enable SET WITH VALUE - false IS SUCCESSFUL: true
[INFO][2024-04-29 15:48:53,796][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WebPA set successful
[INFO][2024-04-29 15:48:53,797][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:48:53,797][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:48:53,797][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-04-29 15:48:53,797][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-04-29 15:48:53,797][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to execute commands
[INFO][2024-04-29 15:48:53,797][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:53,797][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:48:53,797][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:48:53,808][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:48:54,049][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DHCPv4.Server.Enable
[INFO][2024-04-29 15:48:54,050][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:48:54,172][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: false 

<=============================================================>
[INFO][2024-04-29 15:48:54,172][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:48:54,172][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: false 

[INFO][2024-04-29 15:48:54,172][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: false 

[INFO][2024-04-29 15:48:54,173][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli param value : false
[INFO][2024-04-29 15:48:54,173][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DHCPv4.Server.Enable, protocolSpecificParamName=Device.DHCPv4.Server.Enable, value=false, datatype=null, statusCode=0]
[INFO][2024-04-29 15:48:54,173][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WebPA get after set successful
[INFO][2024-04-29 15:48:54,173][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 2: ACTUAL : DHCP Server is disabled successfully via WEBPA SET with value as false
[INFO][2024-04-29 15:48:54,173][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:54,173][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501] [step Number : S2][ Execution status : PASS] [Error Message : ]
[INFO][2024-04-29 15:48:54,174][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Result Details ->{"JMD_ID":"4567","manualId":"TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501","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-LAN-DHCP-IPV4-DSBLE-5001"}
[ERROR][2024-04-29 15:48:54,183][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : 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-04-29 15:48:54,183][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:54,183][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 3 : DESCRIPTION : Obtain a ethernet client associated with the gateway.
[INFO][2024-04-29 15:48:54,183][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 3 : ACTION : Obtain a ethernet client associated with the gateway.
[INFO][2024-04-29 15:48:54,183][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 3 : EXPECTED : The ethernet client connection should be successful.
[INFO][2024-04-29 15:48:54,183][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:54,195][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - DUTINFO DEVICE LIST = [com.automatics.device.Device@209febc, com.automatics.device.Device@56165918]
[INFO][2024-04-29 15:48:54,195][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - # OF CONNECTED CLIENTS AVAILABLE = 2
[INFO][2024-04-29 15:48:54,195][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-04-29 15:48:54,195][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - CLIENT DEVICE CONNECTION TYPE: Ethernet
[INFO][2024-04-29 15:48:54,195][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - CLIENT DEVICE OS TYPE: WINDOWS
[INFO][2024-04-29 15:48:54,196][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - IS ETHERNET CLIENT AVAILABLE: true
[INFO][2024-04-29 15:48:54,196][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Step 3 : ACTUAL : Obtained a ethernet client Associated with the gateway successfully.
[INFO][2024-04-29 15:48:54,196][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:54,196][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501] [step Number : S3][ Execution status : PASS] [Error Message : ]
[INFO][2024-04-29 15:48:54,199][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Result Details ->{"JMD_ID":"4567","manualId":"TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501","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-LAN-DHCP-IPV4-DSBLE-5001"}
[ERROR][2024-04-29 15:48:54,207][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : 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-04-29 15:48:54,207][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:54,207][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 4: DESCRIPTION : Verify IPv4  assigned on the  client is not in the DHCP address range.
[INFO][2024-04-29 15:48:54,208][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 4: ACTION : Get the device IPv4 address using below command :For windows: ipconfig | grep -A 10 Wireless LAN adapter Wi-Fi |grep -i Pv4 Address For Linux :ifconfig | grep 'inet '
[INFO][2024-04-29 15:48:54,208][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 4: EXPECTED : Obtained IPv4 address value should not be within DHCP address range of the Gateway.
[INFO][2024-04-29 15:48:54,208][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:48:54,208][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-04-29 15:48:54,208][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE ipconfig /renew
[INFO][2024-04-29 15:48:54,209][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.81
[INFO][2024-04-29 15:48:54,358][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command ipconfig /renew  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.81] and Port Number [22]
[INFO][2024-04-29 15:48:54,359][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: ipconfig /renew

[INFO][2024-04-29 15:48:54,359][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-04-29 15:49:26,304][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 

Windows IP Configuration

An error occurred while renewing interface Ethernet 3 : unable to contact your DHCP server. Request has timed out.

An error occurred while renewing interface Wi-Fi 3 : unable to contact your DHCP server. Request has timed out.

No operation can be performed on Local Area Connection* 13 while it has its media disconnected.

No operation can be performed on Local Area Connection* 14 while it has its media disconnected.

Ethernet adapter Ethernet 3:

   Connection-specific DNS Suffix  . : utopia.net

   Link-local IPv6 Address . . . . . : fe80::dca1:6dd5:797d:f959%12

   IPv4 Address. . . . . . . . . . . : 10.0.0.230

   Subnet Mask . . . . . . . . . . . : 255.255.255.0

   Default Gateway . . . . . . . . . : 10.0.0.1

Wireless LAN adapter Wi-Fi 3:

   Connection-specific DNS Suffix  . : 

   Link-local IPv6 Address . . . . . : fe80::62d4:fa28:4d07:92e9%14

   IPv4 Address. . . . . . . . . . . : 10.0.0.48

   Subnet Mask . . . . . . . . . . . : 255.255.0.0

   Default Gateway . . . . . . . . . : 10.0.0.1

Ethernet adapter Ethernet:

   Connection-specific DNS Suffix  . : example.com

   IPv6 Address. . . . . . . . . . . : 2001:db8:1111:0:ed9e:737c:ea4f:826a

   Link-local IPv6 Address . . . . . : fe80::b5e3:a135:62d3:332f%25

   IPv4 Address. . . . . . . . . . . : 192.168.2.81

   Subnet Mask . . . . . . . . . . . : 255.255.255.0

   Default Gateway . . . . . . . . . : 192.168.2.254

Wireless LAN adapter Local Area Connection* 13:

   Media State . . . . . . . . . . . : Media disconnected

   Connection-specific DNS Suffix  . : 

Wireless LAN adapter Local Area Connection* 14:

   Media State . . . . . . . . . . . : Media disconnected

   Connection-specific DNS Suffix  . : 
<=============================================================>
[INFO][2024-04-29 15:49:26,304][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.81
[INFO][2024-04-29 15:49:26,306][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: 
Windows IP Configuration

An error occurred while renewing interface Ethernet 3 : unable to contact your DHCP server. Request has timed out.

An error occurred while renewing interface Wi-Fi 3 : unable to contact your DHCP server. Request has timed out.

No operation can be performed on Local Area Connection* 13 while it has its media disconnected.

No operation can be performed on Local Area Connection* 14 while it has its media disconnected.

Ethernet adapter Ethernet 3:

   Connection-specific DNS Suffix  . : utopia.net

   Link-local IPv6 Address . . . . . : fe80::dca1:6dd5:797d:f959%12

   IPv4 Address. . . . . . . . . . . : 10.0.0.230

   Subnet Mask . . . . . . . . . . . : 255.255.255.0

   Default Gateway . . . . . . . . . : 10.0.0.1

Wireless LAN adapter Wi-Fi 3:

   Connection-specific DNS Suffix  . : 

   Link-local IPv6 Address . . . . . : fe80::62d4:fa28:4d07:92e9%14

   IPv4 Address. . . . . . . . . . . : 10.0.0.48

   Subnet Mask . . . . . . . . . . . : 255.255.0.0

   Default Gateway . . . . . . . . . : 10.0.0.1

Ethernet adapter Ethernet:

   Connection-specific DNS Suffix  . : example.com

   IPv6 Address. . . . . . . . . . . : 2001:db8:1111:0:ed9e:737c:ea4f:826a

   Link-local IPv6 Address . . . . . : fe80::b5e3:a135:62d3:332f%25

   IPv4 Address. . . . . . . . . . . : 192.168.2.81

   Subnet Mask . . . . . . . . . . . : 255.255.255.0

   Default Gateway . . . . . . . . . : 192.168.2.254

Wireless LAN adapter Local Area Connection* 13:

   Media State . . . . . . . . . . . : Media disconnected

   Connection-specific DNS Suffix  . : 

Wireless LAN adapter Local Area Connection* 14:

   Media State . . . . . . . . . . . : Media disconnected

   Connection-specific DNS Suffix  . : 
[INFO][2024-04-29 15:49:26,307][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: 
Windows IP Configuration

An error occurred while renewing interface Ethernet 3 : unable to contact your DHCP server. Request has timed out.

An error occurred while renewing interface Wi-Fi 3 : unable to contact your DHCP server. Request has timed out.

No operation can be performed on Local Area Connection* 13 while it has its media disconnected.

No operation can be performed on Local Area Connection* 14 while it has its media disconnected.

Ethernet adapter Ethernet 3:

   Connection-specific DNS Suffix  . : utopia.net

   Link-local IPv6 Address . . . . . : fe80::dca1:6dd5:797d:f959%12

   IPv4 Address. . . . . . . . . . . : 10.0.0.230

   Subnet Mask . . . . . . . . . . . : 255.255.255.0

   Default Gateway . . . . . . . . . : 10.0.0.1

Wireless LAN adapter Wi-Fi 3:

   Connection-specific DNS Suffix  . : 

   Link-local IPv6 Address . . . . . : fe80::62d4:fa28:4d07:92e9%14

   IPv4 Address. . . . . . . . . . . : 10.0.0.48

   Subnet Mask . . . . . . . . . . . : 255.255.0.0

   Default Gateway . . . . . . . . . : 10.0.0.1

Ethernet adapter Ethernet:

   Connection-specific DNS Suffix  . : example.com

   IPv6 Address. . . . . . . . . . . : 2001:db8:1111:0:ed9e:737c:ea4f:826a

   Link-local IPv6 Address . . . . . : fe80::b5e3:a135:62d3:332f%25

   IPv4 Address. . . . . . . . . . . : 192.168.2.81

   Subnet Mask . . . . . . . . . . . : 255.255.255.0

   Default Gateway . . . . . . . . . : 192.168.2.254

Wireless LAN adapter Local Area Connection* 13:

   Media State . . . . . . . . . . . : Media disconnected

   Connection-specific DNS Suffix  . : 

Wireless LAN adapter Local Area Connection* 14:

   Media State . . . . . . . . . . . : Media disconnected

   Connection-specific DNS Suffix  . : 

[INFO][2024-04-29 15:49:26,307][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Waiting for two minutes to reflect IP changes
[INFO][2024-04-29 15:49:37,370][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Creating polling connection
[INFO][2024-04-29 15:49:37,371][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked 
[INFO][2024-04-29 15:49:37,371][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:49:37,371][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-04-29 15:49:37,382][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:49:37,573][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Checking if trace connection alive
[INFO][2024-04-29 15:49:37,573][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-04-29 15:49:37,574][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked 
[INFO][2024-04-29 15:49:37,574][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:49:37,574][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-04-29 15:49:37,586][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:49:37,770][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-04-29 15:49:37,771][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute: 
[INFO][2024-04-29 15:49:37,771][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-04-29 15:49:37,771][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:49:37,893][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-04-29 15:49:37,893][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@10ca8d22
[INFO][2024-04-29 15:49:37,893][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-04-29 15:50:37,894][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Creating polling connection
[INFO][2024-04-29 15:50:37,894][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked 
[INFO][2024-04-29 15:50:37,895][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:50:37,895][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-04-29 15:50:37,910][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:50:38,125][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Checking if trace connection alive
[INFO][2024-04-29 15:50:38,125][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-04-29 15:50:38,125][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked 
[INFO][2024-04-29 15:50:38,125][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:50:38,125][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-04-29 15:50:38,137][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-04-29 15:50:38,346][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-04-29 15:50:38,347][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute: 
[INFO][2024-04-29 15:50:38,347][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-04-29 15:50:38,347][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:50:38,469][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-04-29 15:50:38,469][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@10ca8d22
[INFO][2024-04-29 15:50:38,470][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-04-29 15:51:26,308][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Windows device is connected to Gateway device to Ethernet
[INFO][2024-04-29 15:51:26,309][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Command to be executed is ipconfig /all |grep -A 40 "Ethernet adapter Ethernet"|grep -i "IPv4 Address"
[INFO][2024-04-29 15:51:26,309][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] -  DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-04-29 15:51:26,309][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE ipconfig /all |grep -A 40 "Ethernet adapter Ethernet"|grep -i "IPv4 Address"
[INFO][2024-04-29 15:51:26,310][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.81
[INFO][2024-04-29 15:51:26,486][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
(SSH EXECUTION) : Executing command grep -i "IPv4 Address"< <((grep -A 40 "Ethernet adapter Ethernet")< <(ipconfig /all ))  on client : Mac Address [2C:44:FD:18:F9:FE] , User Name [User], IP Address [192.168.2.81] and Port Number [22]
[INFO][2024-04-29 15:51:26,486][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: grep -i "IPv4 Address"< <((grep -A 40 "Ethernet adapter Ethernet")< <(ipconfig /all ))

[INFO][2024-04-29 15:51:26,486][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-04-29 15:51:28,311][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
   IPv4 Address. . . . . . . . . . . : 10.0.0.230(Preferred) 

   IPv4 Address. . . . . . . . . . . : 192.168.2.81(Preferred) 
<=============================================================>
[INFO][2024-04-29 15:51:28,311][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.81
[INFO][2024-04-29 15:51:28,311][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response:    IPv4 Address. . . . . . . . . . . : 10.0.0.230(Preferred) 

   IPv4 Address. . . . . . . . . . . : 192.168.2.81(Preferred) 
[INFO][2024-04-29 15:51:28,311][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response:    IPv4 Address. . . . . . . . . . . : 10.0.0.230(Preferred) 

   IPv4 Address. . . . . . . . . . . : 192.168.2.81(Preferred) 

[INFO][2024-04-29 15:51:28,312][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Value of ip is - 10.0.0.230
[INFO][2024-04-29 15:51:28,312][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Value of Windows connected client is 10.0.0.230
[INFO][2024-04-29 15:51:28,313][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - IP ADDRESS ASSIGNED TO THE CONNECTED CLIENT FROM DHCP : 10.0.0.230
[INFO][2024-04-29 15:51:28,313][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - IS OBTAINED IPV4 BETWEEN DHCP RANGE :false
[INFO][2024-04-29 15:51:28,314][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - STEP 4: ACTUAL : Obtained IPv4 address value is not within DHCP address range of the Gateway as Expected.
[INFO][2024-04-29 15:51:28,314][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-04-29 15:51:28,314][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501] [step Number : S4][ Execution status : PASS] [Error Message : ]
[INFO][2024-04-29 15:51:28,314][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Result Details ->{"JMD_ID":"4567","manualId":"TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501","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-LAN-DHCP-IPV4-DSBLE-5001"}
[ERROR][2024-04-29 15:51:28,324][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - FAILED TO UPDATE EXECUTION RESULT.Kindly check if this manual id and step are added in Automatics - Manage scripts against the automation id
device Model :Rpi-RDKB
[INFO][2024-04-29 15:51:28,325][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - RPi setup dependency issue : skipping teststep...
[INFO][2024-04-29 15:51:28,325][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501] [step Number : S5][ Execution status : NA] [Error Message : ]
[INFO][2024-04-29 15:51:28,326][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Result Details ->{"JMD_ID":"4567","manualId":"TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-501","stepNumber":"S5","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"NA","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001"}
[ERROR][2024-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : 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-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - ################### STARTING POST-CONFIGURATIONS ###################
[INFO][2024-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - POST-CONDITION STEPS
[INFO][2024-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - #######################################################################################
[INFO][2024-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - POST-CONDITION : DESCRIPTION : Set and Verify the DHCP Server Enabled for 5GHZ WiFi client via WEBPA Parameter: Device.DHCPv4.Server.Enable with Set Value as true
[INFO][2024-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - POST-CONDITION : ACTION : Execute command :curl -4 -k -H Authorization: Bearer <SAT Token> -X PATCH <WEBPA_URL><MAC Address>/config -d Device.DHCPv4.Server.Enable
[INFO][2024-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - POST-CONDITION : EXPECTED : DHCP Server should be enabled successfully via WEBPA. 
[INFO][2024-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - #######################################################################################
[INFO][2024-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:51:28,332][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:51:28,333][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-04-29 15:51:28,333][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-04-29 15:51:28,333][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to execute commands
[INFO][2024-04-29 15:51:28,333][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:51:28,333][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:51:28,333][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:51:28,343][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:51:28,537][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DHCPv4.Server.Enable
[INFO][2024-04-29 15:51:28,537][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:51:28,660][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: false 

<=============================================================>
[INFO][2024-04-29 15:51:28,660][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:51:28,660][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: false 

[INFO][2024-04-29 15:51:28,661][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: false 

[INFO][2024-04-29 15:51:28,661][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli param value : false
[INFO][2024-04-29 15:51:28,662][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DHCPv4.Server.Enable, protocolSpecificParamName=Device.DHCPv4.Server.Enable, value=false, datatype=null, statusCode=0]
[INFO][2024-04-29 15:51:28,662][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WebPA get successful
[INFO][2024-04-29 15:51:28,663][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WebPa data type: BOOLEAN
[INFO][2024-04-29 15:51:28,663][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 parametrs status code  added  0
[INFO][2024-04-29 15:51:28,663][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:51:28,663][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:51:28,664][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.DHCPv4.Server.Enable bool true
[INFO][2024-04-29 15:51:28,664][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:51:28,664][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:51:28,664][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:51:28,677][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:51:28,878][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT setv Device.DHCPv4.Server.Enable bool true
[INFO][2024-04-29 15:51:28,879][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:51:34,007][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.

<=============================================================>
[INFO][2024-04-29 15:51:34,007][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:51:34,007][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.

[INFO][2024-04-29 15:51:34,008][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli Response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.

[INFO][2024-04-29 15:51:34,008][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : {Device.DHCPv4.Server.Enable=Success}
[INFO][2024-04-29 15:51:34,008][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-04-29 15:51:34,008][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-04-29 15:51:34,008][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-04-29 15:51:34,009][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WEBPA PARAM - Device.DHCPv4.Server.Enable SET WITH VALUE - true IS SUCCESSFUL: true
[INFO][2024-04-29 15:51:34,009][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WebPA set successful
[INFO][2024-04-29 15:51:34,009][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-04-29 15:51:34,009][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-04-29 15:51:34,009][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-04-29 15:51:34,009][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-04-29 15:51:34,009][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going to execute commands
[INFO][2024-04-29 15:51:34,009][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.173
[INFO][2024-04-29 15:51:34,010][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSH Host IP : 192.168.2.173
[INFO][2024-04-29 15:51:34,010][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-04-29 15:51:34,023][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-04-29 15:51:34,224][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DHCPv4.Server.Enable
[INFO][2024-04-29 15:51:34,225][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Conn is not null sendRecive: 
[INFO][2024-04-29 15:51:34,348][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - 
<===========================  RESPONSE =======================> 
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: true 

<=============================================================>
[INFO][2024-04-29 15:51:34,348][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.173
[INFO][2024-04-29 15:51:34,348][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Received response: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: true 

[INFO][2024-04-29 15:51:34,349][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli response : CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DHCPv4.Server.Enable
Execution succeed.
Parameter    1 name: Device.DHCPv4.Server.Enable
               type:       bool,    value: true 

[INFO][2024-04-29 15:51:34,349][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Dmcli param value : true
[INFO][2024-04-29 15:51:34,349][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DHCPv4.Server.Enable, protocolSpecificParamName=Device.DHCPv4.Server.Enable, value=true, datatype=null, statusCode=0]
[INFO][2024-04-29 15:51:34,349][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - WebPA get after set successful
[INFO][2024-04-29 15:51:34,349][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - POST-CONDITION : ACTUAL : DHCP Server is enabled for 5GHZ WiFi client successfully via WEBPA.
[INFO][2024-04-29 15:51:34,350][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - POST-CONFIGURATIONS : FINAL STATUS - true
[INFO][2024-04-29 15:51:34,350][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - ################### COMPLETED POST-CONFIGURATIONS ###################
[INFO][2024-04-29 15:51:34,350][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - ENDING TEST CASE: TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001
[INFO][2024-04-29 15:51:34,355][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - >>>[AFTER_METHOD]: Clear device trace buffer for D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:51:34,366][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - NewLocation for log saving logs  /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001/D83ADD138C13/settoptrace/
[INFO][2024-04-29 15:51:34,367][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - created a new directory true
[INFO][2024-04-29 15:51:34,368][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - New trace location from the strings /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-04-29 15:51:34,369][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Writing stated from line
[INFO][2024-04-29 15:51:34,370][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - NewLocation for log saving logs  /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001/D83ADD138C13/logs/
[INFO][2024-04-29 15:51:34,370][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - created a new directory true
[INFO][2024-04-29 15:51:34,370][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Destination Summary file doesn't exist. Creating one!
[INFO][2024-04-29 15:51:34,371][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Writing stated from line
[INFO][2024-04-29 15:51:34,563][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Mon Apr 29 15:51:34 IST 2024
[INFO][2024-04-29 15:51:34,563][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - >>>[AFTER_METHOD]: Sending test execution completion time for D8:3A:DD:13:8C:13
[INFO][2024-04-29 15:51:34,563][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-04-29 15:51:34,564][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - {"jobId":4567,"automationId":"TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":0,"endDateTimeEST":1714386094563}
[INFO][2024-04-29 15:51:34,568][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Capture execution time - Response : HTTP/1.1 400 Bad Request
[INFO][2024-04-29 15:51:34,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Restarting Trace in after method
[INFO][2024-04-29 15:51:34,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-04-29 15:51:34,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-04-29 15:51:34,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Going for if build changed : Test Type : GROUP_OR_AUTOID
[INFO][2024-04-29 15:51:34,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-04-29 15:51:34,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - >>>[AFTER_METHOD]: Build Change Status: NO_CHANGE
[INFO][2024-04-29 15:51:34,569][D8:3A:DD:13:8C:13[TC-RDKB-WIFI-LAN-DHCP-IPV4-DSBLE-5001 : D83ADD138C13] - Skipping parter specific after method clean up as it is not configured.
===== Invoked methods
  AutomaticsTestBase.performBeforeSuiteInit(org.testng.ITestContext)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadBandWiFiConnectedClientTests@73495518]org.testng.TestRunner@67dc7bfa  1934185752
  AutomaticsTestBase.performBeforeMethodInit([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadBandWiFiConnectedClientTests@73495518][Ljava.lang.Object;@216b7779 [TestResult name={null} status=CREATED method=BroadBandWiFiConnectedClientTests.testToVerifyIpv4ValuePostDhcpServerDisableForLanClient(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadBandWiFiConnectedClientTests@73495518] output={null}]  1934185752
    BroadBandWiFiConnectedClientTests.testToVerifyIpv4ValuePostDhcpServerDisableForLanClient(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadBandWiFiConnectedClientTests@73495518]com.automatics.device.Device@10ca8d22  1934185752
  AutomaticsTestBase.performAfterMethodCleanup([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadBandWiFiConnectedClientTests@73495518][Ljava.lang.Object;@216b7779 [TestResult name=testToVerifyIpv4ValuePostDhcpServerDisableForLanClient status=SUCCESS method=BroadBandWiFiConnectedClientTests.testToVerifyIpv4ValuePostDhcpServerDisableForLanClient(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.wifi.connectedclients.BroadBandWiFiConnectedClientTests@73495518] output={null}]  1934185752
=====
PASSED: testToVerifyIpv4ValuePostDhcpServerDisableForLanClient(com.automatics.device.Device@10ca8d22)

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

[INFO][2024-04-29 15:51:34,685][[com.automatics.executor.Starter.main()] - Finished testing class: org.testng.TestRunner
[INFO][2024-04-29 15:51:34,686][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Performing after suite cleanup
[INFO][2024-04-29 15:51:34,686][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-04-29 15:51:34,686][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Locked devices after suite execution 1
[INFO][2024-04-29 15:51:34,686][[com.automatics.executor.Starter.main()] - Value of intial testType : QUICK
[INFO][2024-04-29 15:51:34,686][[com.automatics.executor.Starter.main()] - Value of TestType provided by job : GROUP_OR_AUTOID
[INFO][2024-04-29 15:51:34,687][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Verifying if build changed after test
[INFO][2024-04-29 15:51:34,687][[com.automatics.executor.Starter.main()] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-04-29 15:51:34,687][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Build Change Status: NO_CHANGE
[INFO][2024-04-29 15:51:34,687][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[ERROR][2024-04-29 15:51:34,687][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Sleep interrupted sleep interrupted
[INFO][2024-04-29 15:51:34,688][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-04-29 15:51:34,688][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-04-29 15:51:34,688][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[INFO][2024-04-29 15:51:34,688][[com.automatics.executor.Starter.main()] - Skipping parter specific after suite clean up as it is not configured.
[INFO][2024-04-29 15:51:34,694][[com.automatics.executor.Starter.main()] - Releasing device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/device/release
[INFO][2024-04-29 15:51:34,704][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-04-29 15:51:34,711][[com.automatics.executor.Starter.main()] - Releasing device 2C:44:FD:18:F9:FE Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/device/release
[INFO][2024-04-29 15:51:34,719][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"2C:44:FD:18:F9:FE"}
[INFO][2024-04-29 15:51:34,724][[com.automatics.executor.Starter.main()] - Releasing device D8:3A:DD:13:8B:B3 Url Path: http://192.168.2.130:8080/DeviceManager/deviceManagement/device/release
[INFO][2024-04-29 15:51:34,733][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8B:B3"}
[INFO][2024-04-29 15:51:34,734][[com.automatics.executor.Starter.main()] - Successfully unlocked RDKB (D8:3A:DD:13:8C:13) mapped components - (WINDOWSVM) - 2C:44:FD:18:F9:FE, (Rpi-RDKV) - D8:3A:DD:13:8B:B3
[INFO][2024-04-29 15:51:34,734][[com.automatics.executor.Starter.main()] - SETTOP - D8:3A:DD:13:8C:13 RELEASED.
[INFO][2024-04-29 15:51:34,735][[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":1714385853356,"completionTime":1714386094735,"JMD_ID":4567,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID","tests":[]}}

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

[INFO][2024-04-29 15:51:34,849][[com.automatics.executor.Starter.main()] - Updating final execution status to Automatics
[INFO][2024-04-29 15:51:34,850][[Thread-67] - ============= MESSAGE SEND TO AUTOMATICS ==============
[INFO][2024-04-29 15:51:34,850][[Thread-67] - Tested build name : RPI-RDKB
[INFO][2024-04-29 15:51:34,851][[Thread-67] - Final execution status : COMPLETED
[INFO][2024-04-29 15:51:34,851][[Thread-67] - Final successful device list : ["D8:3A:DD:13:8C:13"]
[INFO][2024-04-29 15:51:34,851][[Thread-67] - Job Id : 4567
[INFO][2024-04-29 15:51:34,851][[Thread-67] - ==============================================================

[INFO][2024-04-29 15:51:34,851][[Thread-67] - >>>[INIT]: Automatics JSON ->
{"status":"COMPLETED","service":"","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1714385853356,"completionTime":1714386094735,"JMD_ID":4567,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID","tests":[]}}
[INFO][2024-04-29 15:51:34,852][[Thread-67] - http://192.168.2.59:8080/Automatics/executionResponse.htm
[INFO][2024-04-29 15:51:34,856][[Thread-67] - HTTP STATUS LINE : HTTP/1.1 200 OK
[INFO][2024-04-29 15:51:34,856][[Thread-67] - HTTP STATUS CODE : 200
[INFO][2024-04-29 15:51:34,857][[com.automatics.executor.Starter.main()] - ********  EXECUTION COMPLETED *********
[INFO][2024-04-29 15:51:39,857][[JVM Terminator] - ********  TERMINATING JVM *********
Inside Add Shutdown Hook
[INFO][2024-04-29 15:51:39,883][[Thread-1] - Bean testInitializer is not configured.
[INFO][2024-04-29 15:51:39,883][[Thread-1] - Closing partner application context
[INFO][2024-04-29 15:51:39,886][[Thread-1] - Closing core application context
[JOB1] $ /bin/sh -xe /tmp/jenkins16453166706314957337.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