Started by user RDK-Test
Running as SYSTEM
Building in workspace /var/lib/jenkins/workspace/JOB1
[JOB1] $ /var/lib/jenkins/tools/hudson.tasks.Maven_MavenInstallation/installmaven/bin/mvn -s /mnt/maven/settings.xml clean install -U exec:java -DskipTests=true -DretryByDefault=false -DbuildType=RDK -Dhttps.protocols=TLSv1.1,TLSv1.2 -Dsun.security.ssl.allowUnsafeRenegotiation=true -Dautomatics.properties.file=http://192.168.2.145:8080/AutomaticsProps/automatics.properties
[INFO] Scanning for projects...
[WARNING]
[WARNING] Some problems were encountered while building the effective model for RDKMAutomationRDKBTests:rdkb-automation:jar:0.0.1-SNAPSHOT
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 171
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 314
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 457
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 600
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 743
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 886
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1029
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1172
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1315
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1458
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1601
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1744
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 1887
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2030
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2173
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2316
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2459
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2602
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2745
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 2888
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3031
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3174
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3317
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3460
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3603
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3746
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 3889
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4032
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4175
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4318
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4461
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4604
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4747
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 4890
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5033
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5176
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5319
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5462
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5605
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5748
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 5891
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6034
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6177
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6320
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6463
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6606
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6749
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 6892
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 7035
[WARNING] 'dependencies.dependency.(groupId: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] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 17188
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 17331
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 17474
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 17617
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 17760
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 17903
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 18046
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 18189
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 18332
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 18475
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 18618
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 18761
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 18904
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 19047
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 19190
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 19333
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 19476
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 19619
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 19762
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 19905
[WARNING]
[WARNING] It is highly recommended to fix these problems because they threaten the stability of your build.
[WARNING]
[WARNING] For this reason, future Maven versions might no longer support building such malformed projects.
[WARNING]
[INFO]
[INFO] --------------< RDKMAutomationRDKBTests:rdkb-automation >---------------
[INFO] Building rdkb-automation 0.0.1-SNAPSHOT
[INFO] --------------------------------[ jar ]---------------------------------
[INFO]
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ rdkb-automation ---
[INFO] Deleting /var/lib/jenkins/workspace/JOB1/target
[INFO]
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ rdkb-automation ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Copying 3 resources
[INFO]
[INFO] --- maven-compiler-plugin:3.11.0:compile (default-compile) @ rdkb-automation ---
[INFO] No sources to compile
[INFO]
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ rdkb-automation ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] skip non existing resourceDirectory /var/lib/jenkins/workspace/JOB1/src/test/resources
[INFO]
[INFO] --- maven-compiler-plugin:3.11.0:testCompile (default-testCompile) @ rdkb-automation ---
[INFO] Changes detected - recompiling the module! :source
[INFO] Compiling 119 source files with javac [debug target 11] to target/test-classes
[INFO] /var/lib/jenkins/workspace/JOB1/src/test/java/com/automatics/rdkb/tests/docsis/BroadBandDocsisTest.java: /var/lib/jenkins/workspace/JOB1/src/test/java/com/automatics/rdkb/tests/docsis/BroadBandDocsisTest.java uses unchecked or unsafe operations.
[INFO] /var/lib/jenkins/workspace/JOB1/src/test/java/com/automatics/rdkb/tests/docsis/BroadBandDocsisTest.java: Recompile with -Xlint:unchecked for details.
[INFO]
[INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ rdkb-automation ---
[INFO] Tests are skipped.
[INFO]
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ rdkb-automation ---
[INFO] Building jar: /var/lib/jenkins/workspace/JOB1/target/rdkb-automation-0.0.1-SNAPSHOT.jar
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ rdkb-automation ---
[INFO] Installing /var/lib/jenkins/workspace/JOB1/target/rdkb-automation-0.0.1-SNAPSHOT.jar to /root/.m2/repository/RDKMAutomationRDKBTests/rdkb-automation/0.0.1-SNAPSHOT/rdkb-automation-0.0.1-SNAPSHOT.jar
[INFO] Installing /var/lib/jenkins/workspace/JOB1/pom.xml to /root/.m2/repository/RDKMAutomationRDKBTests/rdkb-automation/0.0.1-SNAPSHOT/rdkb-automation-0.0.1-SNAPSHOT.pom
[INFO]
[INFO] >>> exec-maven-plugin:1.2.1:java (default-cli) > validate @ rdkb-automation >>>
[INFO]
[INFO] <<< exec-maven-plugin:1.2.1:java (default-cli) < validate @ rdkb-automation <<<
[INFO]
[INFO]
[INFO] --- exec-maven-plugin:1.2.1:java (default-cli) @ rdkb-automation ---
11:19:04,605 |-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.22.0/automatics-core-2.22.0.jar!/logback-test.xml]
11:19:04,616 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@5df9b4bf - URL [jar:file:/root/.m2/repository/com/automatics/apps/automatics-core/2.22.0/automatics-core-2.22.0.jar!/logback-test.xml] is not of type file
11:19:04,810 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
11:19:04,812 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdOutAppender]
11:19:04,838 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@7:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]]
11:19:04,838 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@8:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]]
11:19:04,839 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
11:19:04,854 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
11:19:04,856 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [deviceSiftingAppender]
11:19:04,858 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@18:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]]
11:19:04,858 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@19:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]]
11:19:04,860 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
11:19:04,862 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
11:19:04,862 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [TraceLogger]
11:19:04,863 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
11:19:04,863 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
11:19:04,863 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SerialTrace]
11:19:04,863 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
11:19:04,864 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
11:19:04,864 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [crash-analysis]
11:19:04,864 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
11:19:04,865 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.apache.http] to false
11:19:04,865 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache.http level set to INFO
11:19:04,865 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[org.apache.http]
11:19:04,865 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ConnectionTrace] to TRACE
11:19:04,865 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ConnectionTrace] to false
11:19:04,865 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [TraceLogger] to Logger[ConnectionTrace]
11:19:04,865 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [SerialTrace] to TRACE
11:19:04,865 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [SerialTrace] to false
11:19:04,865 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SerialTrace] to Logger[SerialTrace]
11:19:04,865 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [crash-analysis] to DEBUG
11:19:04,865 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [crash-analysis] to false
11:19:04,865 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [crash-analysis] to Logger[crash-analysis]
11:19:04,865 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdOutAppender] to Logger[ROOT]
11:19:04,866 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[ROOT]
11:19:04,866 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
11:19:04,866 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@74a5892f - Registering current configuration as safe fallback point
[INFO][2024-07-25 11:19:04,867][[com.automatics.executor.Starter.main()] - Starting the execution process for the given test cases.
11:19:04,869 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
11:19:04,870 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-test-suite]
11:19:04,872 |-INFO in ch.qos.logback.core.FileAppender[FILE-test-suite] - File property is set to [target/logs/test-suite.log]
[INFO][2024-07-25 11:19:04,885][[com.automatics.executor.Starter.main()] - Initializing application context partner-applicationContext.xml
[INFO][2024-07-25 11:19:05,497][[com.automatics.executor.Starter.main()] - Initializing application context applicationContext.xml
[INFO][2024-07-25 11:19:05,620][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.145:8080/AutomaticsProps/automatics.properties
[INFO][2024-07-25 11:19:05,770][[com.automatics.executor.Starter.main()] - Automatics Response - {"message":"Data already fetched from Automatics by http:\/\/192.168.2.231:8080\/job\/python_JOB1\/31 for this job id. Will not be provided again."}
[INFO][2024-07-25 11:19:05,794][[com.automatics.executor.Starter.main()] -
***************** Automatics BUILD PARAMETERS *****************
[INFO][2024-07-25 11:19:05,803][[com.automatics.executor.Starter.main()] - message | Data already fetched from Automatics by http://192.168.2.231:8080/job/python_JOB1/31 for this job id. Will not be provided again.
[INFO][2024-07-25 11:19:05,804][[com.automatics.executor.Starter.main()] - Shut Down Hook Attached.
[INFO][2024-07-25 11:19:05,837][[com.automatics.executor.Starter.main()] - Start Execution Test Suite
[INFO][2024-07-25 11:19:05,838][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-07-25 11:19:05,838][[com.automatics.executor.Starter.main()] - Cleaning the trace log directory
[INFO][2024-07-25 11:19:05,898][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.145:8080/AutomaticsProps/automatics.properties
[INFO][2024-07-25 11:19:05,903][[com.automatics.executor.Starter.main()] - >>>[INIT]: Validating config files
[INFO][2024-07-25 11:19:05,904][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.145:8080/AutomaticsProps/deviceProps.json
[INFO][2024-07-25 11:19:06,183][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.145:8080/AutomaticsProps/deviceProps.json
[INFO][2024-07-25 11:19:06,190][[com.automatics.executor.Starter.main()] - frameworkSupportedModels=Rpi-RDKB,Rpi-RDKV,
[INFO][2024-07-25 11:19:06,190][[com.automatics.executor.Starter.main()] - rdkvGWModels=
[INFO][2024-07-25 11:19:06,190][[com.automatics.executor.Starter.main()] - rdkvCLModels=Rpi-RDKV,
[INFO][2024-07-25 11:19:06,191][[com.automatics.executor.Starter.main()] - rdkbModels=Rpi-RDKB,
[INFO][2024-07-25 11:19:06,191][[com.automatics.executor.Starter.main()] - rdkcModels=
[INFO][2024-07-25 11:19:06,264][[com.automatics.executor.Starter.main()] - Reading implementation from core for deviceProvider
[INFO][2024-07-25 11:19:06,289][[com.automatics.executor.Starter.main()] - Is Account based test: false
[INFO][2024-07-25 11:19:06,290][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-07-25 11:19:07,105][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8C:13 Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-07-25 11:19:07,373][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.107","hostIp6Address":null,"clientIpAddress":"192.168.2.107","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.107","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.107","connectedClients":"192.168.2.81","password":"root123","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","nodePort":"22","connectionType":"SSH","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.fasterxml.jackson.databind.util.ClassUtil (file:/root/.m2/repository/com/fasterxml/jackson/core/jackson-databind/2.4.1/jackson-databind-2.4.1.jar) to field java.time.format.DateTimeFormatter.locale
WARNING: Please consider reporting this to the maintainers of com.fasterxml.jackson.databind.util.ClassUtil
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
[INFO][2024-07-25 11:19:07,444][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-07-25 11:19:07,445][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model Rpi-RDKB
[INFO][2024-07-25 11:19:07,445][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model Rpi-RDKB
[INFO][2024-07-25 11:19:07,445][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model Rpi-RDKB to automatics model Rpi-RDKB
[INFO][2024-07-25 11:19:07,445][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device
[INFO][2024-07-25 11:19:07,450][[pool-2-thread-1] - INIT-{} Check if device already lockedD8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:07,463][[pool-2-thread-1] - Fetching lock status for device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/device/allocationStatus
[INFO][2024-07-25 11:19:07,471][[pool-2-thread-1] - Response: {"allocationStatus":"AVAILABLE"}
[INFO][2024-07-25 11:19:07,476][[pool-2-thread-1] - DeviceConfig allocation status
[INFO][2024-07-25 11:19:07,476][[pool-2-thread-1] - [INIT LOG] : Locking device D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:07,476][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Locking device
[INFO][2024-07-25 11:19:07,498][[pool-2-thread-1] - Locking device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/device/lock
[INFO][2024-07-25 11:19:07,550][[pool-2-thread-1] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-07-25 11:19:07,553][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Lock success
[INFO][2024-07-25 11:19:07,554][[pool-2-thread-1] - Setting access mechanism for device D8:3A:DD:13:8C:13 SSH
[INFO][2024-07-25 11:19:07,554][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Setting access method SSH
[INFO][2024-07-25 11:19:07,554][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required
[INFO][2024-07-25 11:19:07,554][[pool-2-thread-1] - Setting accessibility check required to false for device D8:3A:DD:13:8C:13 from device config
[INFO][2024-07-25 11:19:07,554][[pool-2-thread-1] - Accessibility check required for device D8:3A:DD:13:8C:13 false
[INFO][2024-07-25 11:19:07,554][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required is false
[INFO][2024-07-25 11:19:07,554][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Assuming device is accessible
[INFO][2024-07-25 11:19:07,554][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Is device accessible true
[INFO][2024-07-25 11:19:07,555][[pool-2-thread-1] - [INIT LOG] : Wiring device.D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:07,555][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Performing provider wiring
[INFO][2024-07-25 11:19:07,555][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 PowerProvider wiring
[INFO][2024-07-25 11:19:07,555][[pool-2-thread-1] - Reading implementation from core for powerProvider
[INFO][2024-07-25 11:19:07,557][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 DeviceConfig Connection Based TraceProvider wiring
[INFO][2024-07-25 11:19:07,571][[pool-2-thread-1] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-07-25 11:19:07,575][[pool-2-thread-1] - Additional trace support : null
[INFO][2024-07-25 11:19:07,575][[pool-2-thread-1] - settopObj.getModel() = Rpi-RDKB
[INFO][2024-07-25 11:19:07,576][[pool-2-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue
[INFO][2024-07-25 11:19:07,578][[pool-2-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-07-25 11:19:07,579][[pool-2-thread-1] - Crash Analysis not enabled during trace monitoring
[INFO][2024-07-25 11:19:07,580][[pool-2-thread-1] - Serial based trace to be initialized: false
[INFO][2024-07-25 11:19:07,580][[pool-2-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-07-25 11:19:07,580][[pool-2-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:07,580][[pool-2-thread-1] - Dut Added to locked list D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:10,447][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads
[INFO][2024-07-25 11:19:10,447][[com.automatics.executor.Starter.main()] - Collecting dut info
[INFO][2024-07-25 11:19:10,448][[com.automatics.executor.Starter.main()] - Requesting data from http://192.168.2.59:8084/api/rack/service/getmasterconfig?configName=TEST_TYPE_MAP
[INFO][2024-07-25 11:19:10,483][[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-07-25 11:19:11,123][[com.automatics.executor.Starter.main()] - AutomaticsTapApi instance not available. Creating new instance
[INFO][2024-07-25 11:19:11,124][[com.automatics.executor.Starter.main()] - Creating new instance for AutomaticsTapApi
[INFO][2024-07-25 11:19:11,124][[com.automatics.executor.Starter.main()] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-07-25 11:19:11,231][[com.automatics.executor.Starter.main()] - >>>[BEFORE_SUITE]: Perform before suite initialization
[INFO][2024-07-25 11:19:11,231][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-07-25 11:19:11,231][[com.automatics.executor.Starter.main()] - Adding locked devices to dut object in AutomaticsTestBase
[INFO][2024-07-25 11:19:11,232][[com.automatics.executor.Starter.main()] - Locked Settops: 1
[INFO][2024-07-25 11:19:11,233][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Verifying if build loaded in device as expected
[INFO][2024-07-25 11:19:11,233][[pool-6-thread-1] - [BEFORE-SUITE:]Expected build in device is rdkb-generic-broadband-image_TDK_nightly-20240603-kirkstone_20240603030301
[INFO][2024-07-25 11:19:11,233][[pool-6-thread-1] - [BEFORE-SUITE:]Verifying if build changed before test
[INFO][2024-07-25 11:19:11,233][[pool-6-thread-1] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-07-25 11:19:11,234][[pool-6-thread-1] - [BEFORE-SUITE:]Build Change Status: NO_CHANGE
[INFO][2024-07-25 11:19:11,234][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Build in device is as expected
[INFO][2024-07-25 11:19:11,234][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Setting appropritate build appender based on executionMode
[INFO][2024-07-25 11:19:11,249][[pool-6-thread-1] - Skipping setting of execution mode in device as partner specific initialization is not configured.
[INFO][2024-07-25 11:19:11,249][[pool-6-thread-1] - INIT-D8:3A:DD:13:8C:13 Perform before suite initialization
[INFO][2024-07-25 11:19:11,249][[pool-6-thread-1] - Skipping partner specific before suite initialization as it is not configured.
[INFO][2024-07-25 11:19:11,249][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Starting device connection trace
[INFO][2024-07-25 11:19:11,250][[pool-6-thread-1] - Starting trace with command : tail -F -n 0 /rdklogs/logs/*
[INFO][2024-07-25 11:19:11,258][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Starting reading..D83ADD138C13settop_trace.log,
11:19:11,258 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
11:19:11,259 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-D83ADD138C13settop_trace.log]
11:19:11,259 |-INFO in ch.qos.logback.core.FileAppender[FILE-D83ADD138C13settop_trace.log] - File property is set to [target/settoptrace/D83ADD138C13settop_trace.log]
[INFO][2024-07-25 11:19:11,260][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Going to connect device for trace monitoring .....!
[INFO][2024-07-25 11:19:11,260][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP Address : 192.168.2.107
[INFO][2024-07-25 11:19:11,260][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP6 Address : null
[INFO][2024-07-25 11:19:11,260][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host MAC Address : D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:11,261][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside Non RDKV client device
[INFO][2024-07-25 11:19:11,261][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:19:11,276][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:11,276][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:11,292][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Reading server-config.xml from /var/lib/jenkins/workspace/testrepo/server-config.xml
[INFO][2024-07-25 11:19:11,317][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:19:11,735][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:11,735][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:11,735][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:11,751][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:19:11,998][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Inside sendTraceCommand
[INFO][2024-07-25 11:19:17,008][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:17,013][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Received response:
[INFO][2024-07-25 11:19:17,015][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connectiongateway iscom.automatics.providers.connection.SshConnection@68b4c0d2
[INFO][2024-07-25 11:19:17,015][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - inside null != connectionGateway condition
[INFO][2024-07-25 11:19:17,015][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - gateWayDeviceInputStream iscom.jcraft.jsch.Channel$MyPipedInputStream@b37b642
[INFO][2024-07-25 11:19:17,017][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside else block
[INFO][2024-07-25 11:19:17,018][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:19:17,018][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Entered into readOutputFromChannel method
[INFO][2024-07-25 11:19:17,018][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:17,018][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Is RDKV Client trace false
[INFO][2024-07-25 11:19:17,018][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:17,037][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:19:17,265][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-07-25 11:19:17,265][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:19:17,265][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:17,265][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:17,283][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:19:17,518][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-07-25 11:19:17,518][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-07-25 11:19:17,518][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-07-25 11:19:17,520][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:17,644][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-07-25 11:19:17,645][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@7f41a955
[INFO][2024-07-25 11:19:17,651][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-07-25 11:19:41,258][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-07-25 11:19:41,259][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-07-25 11:19:44,236][[com.automatics.executor.Starter.main()] - Starting testing on class: org.testng.TestRunner
[INFO][2024-07-25 11:19:44,308][[com.automatics.executor.Starter.main()] - Doing initialization for D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:44,335][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Perform before method initialization D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:44,336][[TestNG-PoolService-0] - Setting dattime in patter yyyy-MM-dd HH:mm:ss
[INFO][2024-07-25 11:19:44,339][[TestNG-PoolService-0] - startTime 2024-07-25 05:49:44
[INFO][2024-07-25 11:19:44,339][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Sending test exection start time to Automatics D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:44,341][[TestNG-PoolService-0] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-07-25 11:19:44,342][[TestNG-PoolService-0] - {"jobId":456,"automationId":"TC-RDKB-BINARIES-1003","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":1721886584340,"endDateTimeEST":0}
[INFO][2024-07-25 11:19:44,441][[TestNG-PoolService-0] - Capture execution time - Response : HTTP/1.1 200
[INFO][2024-07-25 11:19:44,442][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Skipping partner specific before method initialization as it is not configured.
11:19:44,442 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
11:19:44,442 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-Rpi-RDKB-D83ADD138C13]
11:19:44,443 |-INFO in ch.qos.logback.core.FileAppender[FILE-Rpi-RDKB-D83ADD138C13] - File property is set to [target/logs/Rpi-RDKB-D83ADD138C13.log]
[INFO][2024-07-25 11:19:44,444][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-07-25 11:19:44,444][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Get the locked status: false
[INFO][2024-07-25 11:19:44,444][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-07-25 11:19:44,444][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-07-25 11:19:44,453][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - STARTED - testToVerifyRemovedOrEncryptedPrivateKeysAndWebGuiPageNavigation - com.automatics.device.Device@7f41a955
[INFO][2024-07-25 11:19:44,454][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Getting MDC = D83ADD138C13settop_trace.log
[INFO][2024-07-25 11:19:44,454][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Getting MDC = D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:19:44,462][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - #######################################################################################
[INFO][2024-07-25 11:19:44,462][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STARTING TEST CASE: TC-RDKB-BINARIES-1003
[INFO][2024-07-25 11:19:44,462][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TEST DESCRIPTION: To Verify Removed or Encrypted Private Keys in Binaries (CVSS 7.5) in WEBGUI
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TEST STEPS :
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - PRE-CONDITION 1: Verify getting the values of 2.4GHz SSID,5GHz SSID, WANIPv4 & WANIPv6 address via webpa.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 1. Verify the presence of server.pem in the device.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 2. Verify the presence of server.pem as soft link in /etc/Server.pem.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 3. Verify launching and login to WEB GUI page.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 4. Verify SSID name of 2.4GHz radio from GUI with WebPa value.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 5. Verify SSID name of 5GHz radio from GUI with WebPa value.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 6. Verify navigating to Partner Network page in WEB GUI.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 7. Verify the WAN IPv4 Address from GUI with WebPa value.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 8. Verify the WAN IPv6 Address from GUI with WebPa value.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 9. Verify lighttpd is running.
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 10. Retrieve SNR and Powerlevel params and values using webpa
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 11. Verify the READ-ONLY attribute of WebPA Parameter Device.X_RDKCENTRAL-COM_CableModem.DsOfdmChan.{i}.SNRLevel
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 12. Verify the READ-ONLY attribute of WebPA Parameter Device.X_RDKCENTRAL-COM_CableModem.DsOfdmChan.{i}.PowerLevel
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 13. Retrieve SNR and Power levels in GUI
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 14. Validate SNR and Power levels in GUI with webpa result
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 15. Retrieve SNR Level param via SNMP and cross validate with webpa value obtained
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 16. Retrieve Power Level param via SNMP and cross validate with webpa value obtained
[INFO][2024-07-25 11:19:44,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - #######################################################################################
[INFO][2024-07-25 11:19:44,464][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - ################### STARTING PRE-CONFIGURATIONS ###################
[INFO][2024-07-25 11:19:44,464][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - PRE-CONDITION STEP
[INFO][2024-07-25 11:19:44,464][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - PRE-CONDITION 1: DESCRIPTION : Obtain the values of 2.4GHz SSID, 5GHz SSID, WanIPv4 and WANIPv6 address using webpa.
[INFO][2024-07-25 11:19:44,464][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - PRE-CONDITION 1: ACTION : Execute the webpa commands to get above.
[INFO][2024-07-25 11:19:44,464][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - PRE-CONDITION 1: EXPECTED : Webpa Get should be executed successfully.
[INFO][2024-07-25 11:19:44,464][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - #######################################################################################
[INFO][2024-07-25 11:19:44,464][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-07-25 11:19:44,464][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-07-25 11:19:44,464][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-07-25 11:19:44,465][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-07-25 11:19:44,467][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to execute commands
[INFO][2024-07-25 11:19:44,468][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:44,468][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:44,468][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:44,484][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:44,682][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.SSID.1.SSID
[INFO][2024-07-25 11:19:44,683][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:44,806][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.wifi): Device.WiFi.SSID.1.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.1.SSID
type: string, value: yolo
<=============================================================>
[INFO][2024-07-25 11:19:44,807][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:44,807][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.1.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.1.SSID
type: string, value: yolo
[INFO][2024-07-25 11:19:44,808][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.1.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.1.SSID
type: string, value: yolo
[INFO][2024-07-25 11:19:44,808][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value : yolo
[INFO][2024-07-25 11:19:44,808][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:44,808][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:44,808][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:44,826][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:45,090][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.SSID.2.SSID
[INFO][2024-07-25 11:19:45,091][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:45,214][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.wifi): Device.WiFi.SSID.2.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.2.SSID
type: string, value: yolo
<=============================================================>
[INFO][2024-07-25 11:19:45,214][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:45,214][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.2.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.2.SSID
type: string, value: yolo
[INFO][2024-07-25 11:19:45,214][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.2.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.2.SSID
type: string, value: yolo
[INFO][2024-07-25 11:19:45,215][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value : yolo
[INFO][2024-07-25 11:19:45,215][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:45,215][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:45,215][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:45,232][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:45,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DeviceInfo.X_COMCAST-COM_WAN_IP
[INFO][2024-07-25 11:19:45,463][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:45,586][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_COMCAST-COM_WAN_IP
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IP
type: string, value: 192.168.2.107
<=============================================================>
[INFO][2024-07-25 11:19:45,586][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:45,586][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_COMCAST-COM_WAN_IP
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IP
type: string, value: 192.168.2.107
[INFO][2024-07-25 11:19:45,587][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_COMCAST-COM_WAN_IP
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IP
type: string, value: 192.168.2.107
[INFO][2024-07-25 11:19:45,587][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value : 192.168.2.107
[INFO][2024-07-25 11:19:45,587][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:45,588][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:45,588][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:45,604][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:45,840][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DeviceInfo.X_COMCAST-COM_WAN_IPv6
[INFO][2024-07-25 11:19:45,841][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:45,964][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_COMCAST-COM_WAN_IPv6
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IPv6
type: string, value:
<=============================================================>
[INFO][2024-07-25 11:19:45,964][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:45,964][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_COMCAST-COM_WAN_IPv6
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IPv6
type: string, value:
[INFO][2024-07-25 11:19:45,964][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_COMCAST-COM_WAN_IPv6
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IPv6
type: string, value:
[INFO][2024-07-25 11:19:45,965][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value :
[INFO][2024-07-25 11:19:45,966][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.WiFi.SSID.10001.SSID, protocolSpecificParamName=Device.WiFi.SSID.1.SSID, value=yolo, datatype=null, statusCode=0, tableName=null, index=null, name=Device.WiFi.SSID.10101.SSID, protocolSpecificParamName=Device.WiFi.SSID.2.SSID, value=yolo, datatype=null, statusCode=0, tableName=null, index=null, name=Device.DeviceInfo.X_COMCAST-COM_WAN_IP, protocolSpecificParamName=Device.DeviceInfo.X_COMCAST-COM_WAN_IP, value=192.168.2.107, datatype=null, statusCode=0, tableName=null, index=null, name=Device.DeviceInfo.X_COMCAST-COM_WAN_IPv6, protocolSpecificParamName=Device.DeviceInfo.X_COMCAST-COM_WAN_IPv6, value=, datatype=null, statusCode=0]
[INFO][2024-07-25 11:19:45,975][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - The SSIDs for 2.4 and 5 GHz private wifi: 2.4 GHz - 24GHZ_private_1721886585975 5 GHz - 5GHZ_private_1721886585975
[INFO][2024-07-25 11:19:45,985][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-07-25 11:19:45,986][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-07-25 11:19:45,986][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-07-25 11:19:45,986][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-07-25 11:19:45,986][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-07-25 11:19:45,986][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-07-25 11:19:45,986][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-07-25 11:19:45,986][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-07-25 11:19:45,986][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-07-25 11:19:45,987][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-07-25 11:19:45,987][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.SSID.1.SSID string 24GHZ_private_1721886585975
[INFO][2024-07-25 11:19:45,987][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:45,987][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:45,988][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:46,001][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:46,197][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.SSID.1.SSID string 24GHZ_private_1721886585975
[INFO][2024-07-25 11:19:46,198][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:46,321][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.wifi): Device.WiFi.SSID.1.SSID
Execution succeed.
<=============================================================>
[INFO][2024-07-25 11:19:46,321][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:46,321][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.1.SSID
Execution succeed.
[INFO][2024-07-25 11:19:46,321][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.1.SSID
Execution succeed.
[INFO][2024-07-25 11:19:46,322][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Apply settings dmcli command to be executed
[INFO][2024-07-25 11:19:46,322][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:46,322][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:46,323][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:46,335][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:46,543][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: ps | grep -i "onewifi"
[INFO][2024-07-25 11:19:46,544][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:46,666][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
1064 root 0:00 sh -c ps | grep -i "onewifi"
1066 root 0:00 grep -i onewifi
2896 root 0:08 /usr/bin/OneWifi -subsys eRT.
<=============================================================>
[INFO][2024-07-25 11:19:46,666][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:46,667][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: 1064 root 0:00 sh -c ps | grep -i "onewifi"
1066 root 0:00 grep -i onewifi
2896 root 0:08 /usr/bin/OneWifi -subsys eRT.
[INFO][2024-07-25 11:19:46,667][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - The device has One Wifi : 1064 root 0:00 sh -c ps | grep -i "onewifi"
1066 root 0:00 grep -i onewifi
2896 root 0:08 /usr/bin/OneWifi -subsys eRT.
[INFO][2024-07-25 11:19:46,667][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Apply settings dmcli command to be executed for One WIFI
[INFO][2024-07-25 11:19:46,667][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param name: Device.WiFi.SSID.1.SSID
[INFO][2024-07-25 11:19:46,668][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-07-25 11:19:46,668][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:19:46,668][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:46,668][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:46,684][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:46,905][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-07-25 11:19:46,905][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:47,028][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.wifi): Device.WiFi.ApplyAccessPointSettings
Execution succeed.
<=============================================================>
[INFO][2024-07-25 11:19:47,029][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.ApplyAccessPointSettings
Execution succeed.
[INFO][2024-07-25 11:19:47,029][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.ApplyAccessPointSettings
Execution succeed.
[INFO][2024-07-25 11:19:47,029][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.SSID.2.SSID string 5GHZ_private_1721886585975
[INFO][2024-07-25 11:19:47,029][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:47,029][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:47,029][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:47,045][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:47,246][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.SSID.2.SSID string 5GHZ_private_1721886585975
[INFO][2024-07-25 11:19:47,246][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:47,369][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.wifi): Device.WiFi.SSID.2.SSID
Execution succeed.
<=============================================================>
[INFO][2024-07-25 11:19:47,369][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:47,369][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.2.SSID
Execution succeed.
[INFO][2024-07-25 11:19:47,369][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.2.SSID
Execution succeed.
[INFO][2024-07-25 11:19:47,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Apply settings dmcli command to be executed
[INFO][2024-07-25 11:19:47,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:47,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:47,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:47,389][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:47,611][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: ps | grep -i "onewifi"
[INFO][2024-07-25 11:19:47,611][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:47,734][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
1099 root 0:00 sh -c ps | grep -i "onewifi"
1101 root 0:00 grep -i onewifi
2896 root 0:08 /usr/bin/OneWifi -subsys eRT.
<=============================================================>
[INFO][2024-07-25 11:19:47,734][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:47,734][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: 1099 root 0:00 sh -c ps | grep -i "onewifi"
1101 root 0:00 grep -i onewifi
2896 root 0:08 /usr/bin/OneWifi -subsys eRT.
[INFO][2024-07-25 11:19:47,734][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - The device has One Wifi : 1099 root 0:00 sh -c ps | grep -i "onewifi"
1101 root 0:00 grep -i onewifi
2896 root 0:08 /usr/bin/OneWifi -subsys eRT.
[INFO][2024-07-25 11:19:47,734][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Apply settings dmcli command to be executed for One WIFI
[INFO][2024-07-25 11:19:47,735][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param name: Device.WiFi.SSID.2.SSID
[INFO][2024-07-25 11:19:47,735][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-07-25 11:19:47,735][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:19:47,735][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:47,735][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:47,750][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:47,951][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.ApplyAccessPointSettings bool 1
[INFO][2024-07-25 11:19:47,952][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:48,075][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.wifi): Device.WiFi.ApplyAccessPointSettings
Execution succeed.
<=============================================================>
[INFO][2024-07-25 11:19:48,075][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.ApplyAccessPointSettings
Execution succeed.
[INFO][2024-07-25 11:19:48,075][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.ApplyAccessPointSettings
Execution succeed.
[INFO][2024-07-25 11:19:48,075][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.AccessPoint.1.Security.X_COMCAST-COM_KeyPassphrase string
[INFO][2024-07-25 11:19:48,076][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:48,076][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:48,076][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:48,091][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:48,323][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.AccessPoint.1.Security.X_COMCAST-COM_KeyPassphrase string
[INFO][2024-07-25 11:19:48,323][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:48,446][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
Syntax error. see help.
<=============================================================>
[INFO][2024-07-25 11:19:48,446][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:48,446][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: Syntax error. see help.
[INFO][2024-07-25 11:19:48,446][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli Response: Syntax error. see help.
[INFO][2024-07-25 11:19:48,447][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.WiFi.AccessPoint.2.Security.X_COMCAST-COM_KeyPassphrase string
[INFO][2024-07-25 11:19:48,447][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:48,447][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:19:48,447][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:19:48,462][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:19:48,684][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT setv Device.WiFi.AccessPoint.2.Security.X_COMCAST-COM_KeyPassphrase string
[INFO][2024-07-25 11:19:48,684][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:19:48,807][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
Syntax error. see help.
<=============================================================>
[INFO][2024-07-25 11:19:48,807][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:19:48,807][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: Syntax error. see help.
[INFO][2024-07-25 11:19:48,807][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli Response: Syntax error. see help.
[INFO][2024-07-25 11:19:48,807][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Response : {Device.WiFi.AccessPoint.10101.Security.X_COMCAST-COM_KeyPassphrase=Failed, Device.WiFi.SSID.10001.SSID=Success, Device.WiFi.SSID.10101.SSID=Success, Device.WiFi.AccessPoint.10001.Security.X_COMCAST-COM_KeyPassphrase=Failed}
[INFO][2024-07-25 11:19:48,844][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - VALIDATION RESULT OF OPERATION = TXT_COMPARISON, EXPECTED VALUE = success, ACTUAL VALUE = Success IS: true
[INFO][2024-07-25 11:19:48,845][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - VALIDATION RESULT OF OPERATION = TXT_COMPARISON, EXPECTED VALUE = success, ACTUAL VALUE = Success IS: true
[INFO][2024-07-25 11:19:48,845][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - VALIDATION RESULT OF OPERATION = TXT_COMPARISON, EXPECTED VALUE = success, ACTUAL VALUE = Success IS: true
[INFO][2024-07-25 11:19:48,845][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - VALIDATION RESULT OF OPERATION = TXT_COMPARISON, EXPECTED VALUE = success, ACTUAL VALUE = Success IS: true
[INFO][2024-07-25 11:19:48,846][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - The new SSIDs for 2.4 and 5 GHz are as given:true
[INFO][2024-07-25 11:19:48,846][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - PRE-CONDITION 1: ACTUAL : Attempt to customise the private wifi SSID and password has succeesfully completed.
[INFO][2024-07-25 11:19:48,846][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Waiting for 2 Minutes after changing the Wifi SSID names
[INFO][2024-07-25 11:20:17,651][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:20:17,651][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:20:17,651][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:20:17,667][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:20:17,883][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-07-25 11:20:17,883][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:20:17,883][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:20:17,883][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:20:17,896][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:20:18,134][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-07-25 11:20:18,134][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-07-25 11:20:18,134][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-07-25 11:20:18,134][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-07-25 11:20:18,256][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-07-25 11:20:18,257][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@7f41a955
[INFO][2024-07-25 11:20:18,257][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-07-25 11:21:18,259][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:21:18,259][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:21:18,259][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:21:18,273][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:21:18,497][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-07-25 11:21:18,497][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:21:18,497][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:21:18,497][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:21:18,510][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:21:18,736][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-07-25 11:21:18,737][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-07-25 11:21:18,737][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-07-25 11:21:18,737][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-07-25 11:21:18,859][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-07-25 11:21:18,859][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@7f41a955
[INFO][2024-07-25 11:21:18,860][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-07-25 11:21:48,846][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - ################### COMPLETED PRE-CONFIGURATIONS ###################
[INFO][2024-07-25 11:21:48,846][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:21:48,846][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 1: DESCRIPTION : Verify the presence of server.pem in the device.
[INFO][2024-07-25 11:21:48,847][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 1: ACTION : Execute the following command after SSH the device: grep / -iname "server.pem"
[INFO][2024-07-25 11:21:48,847][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 1: EXPECTED : Either the file should not be present or the file should be present in below two locations /etc/server.pem /fss/gw/etc/server.pem
Sample output:
~ # find / -name "server.pem"
/etc/server.pem
/fss/gw/etc/server.pem
[INFO][2024-07-25 11:21:48,847][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:21:48,847][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:21:48,847][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:21:48,847][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:21:48,847][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:21:48,862][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:21:49,103][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: find / -iname server.pem
[INFO][2024-07-25 11:21:49,103][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:21:59,125][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
<=============================================================>
[INFO][2024-07-25 11:21:59,137][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response:
[INFO][2024-07-25 11:21:59,137][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:21:59,138][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:21:59,138][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:21:59,138][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:21:59,138][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:21:59,152][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:21:59,391][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: find / -iname server.pem
[INFO][2024-07-25 11:21:59,392][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:09,413][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
<=============================================================>
[INFO][2024-07-25 11:22:09,424][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response:
[INFO][2024-07-25 11:22:09,425][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:22:18,860][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:22:18,860][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:18,860][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:18,876][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:22:19,114][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-07-25 11:22:19,114][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:22:19,115][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:19,115][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:19,127][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:22:19,343][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-07-25 11:22:19,343][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-07-25 11:22:19,343][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-07-25 11:22:19,343][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:19,465][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-07-25 11:22:19,465][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@7f41a955
[INFO][2024-07-25 11:22:19,466][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-07-25 11:22:39,426][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 1: ACTUAL :The server.pem file is not present in device.
[INFO][2024-07-25 11:22:39,427][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:22:39,428][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s1][ Execution status : PASS] [Error Message : ]
[INFO][2024-07-25 11:22:39,431][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Fetching device property: FIRMWARE_VERSION
[INFO][2024-07-25 11:22:39,438][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Fetching device props for D8:3A:DD:13:8C:13 for props [FIRMWARE_VERSION] Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/getDeviceProps
[INFO][2024-07-25 11:22:39,455][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:22:39,455][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Getting value for property: FIRMWARE_VERSION
[INFO][2024-07-25 11:22:39,455][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Value: RPI-RDKB
[INFO][2024-07-25 11:22:39,455][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Device property name: FIRMWARE_VERSION value obtained: RPI-RDKB
[INFO][2024-07-25 11:22:39,468][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","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-BINARIES-1003"}
[ERROR][2024-07-25 11:22:39,499][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:22:39,499][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:22:39,499][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 2: DESCRIPTION : Verify the presence of server.pem as soft link in /etc/Server.pem.
[INFO][2024-07-25 11:22:39,500][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 2: ACTION : Execute the following command after SSH the device: ls -al /etc/Server.pem
[INFO][2024-07-25 11:22:39,500][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 2: EXPECTED : Sample Output:
Ex : root@Docsis-Gateway:/# ls -al /etc/server.pem
lrwxrwxrwx 1 root root 18 Mar 23 08:10 /etc/server.pem -> /tmp/nwcwtynbb.ehm
[INFO][2024-07-25 11:22:39,500][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:22:39,500][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:22:39,500][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:22:39,500][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:39,500][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:39,512][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:22:39,714][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: ls -ltr /etc/ | grep -i server.pem
[INFO][2024-07-25 11:22:39,714][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:39,837][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
-rw-r--r-- 1 root root 3014 Apr 28 2022 server.pem.bak
lrwxrwxrwx 1 root root 18 Jul 25 05:47 server.pem -> /tmp/nwcwtynbb.ehm
<=============================================================>
[INFO][2024-07-25 11:22:39,837][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: -rw-r--r-- 1 root root 3014 Apr 28 2022 server.pem.bak
lrwxrwxrwx 1 root root 18 Jul 25 05:47 server.pem -> /tmp/nwcwtynbb.ehm
[INFO][2024-07-25 11:22:39,837][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:22:39,837][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 2: ACTUAL : Presence of server.pem as soft link in /etc/Server.pem is verified successfully.
[INFO][2024-07-25 11:22:39,838][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:22:39,838][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s2][ Execution status : PASS] [Error Message : ]
[INFO][2024-07-25 11:22:39,840][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s2","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 3: DESCRIPTION : LAUNCH LAN GUI Admin LOGIN PAGE AND VERIFY LOGIN STATUS
[INFO][2024-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 3: ACTION : LAUNCH WEBGUI PAGE OF THE ROUTER DEVICE AND LOGIN WITH PASSWORD OF THE DAY
[INFO][2024-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 3: EXPECTED : LAN GUI Admin LOGIN SHOULD BE SUCCESSFUL
[INFO][2024-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-07-25 11:22:39,854][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to execute commands
[INFO][2024-07-25 11:22:39,855][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:39,855][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:39,855][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:39,867][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:22:40,070][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DeviceInfo.X_COMCAST-COM_WAN_IP
[INFO][2024-07-25 11:22:40,071][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:40,193][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_COMCAST-COM_WAN_IP
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IP
type: string, value: 192.168.2.107
<=============================================================>
[INFO][2024-07-25 11:22:40,194][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:40,194][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_COMCAST-COM_WAN_IP
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IP
type: string, value: 192.168.2.107
[INFO][2024-07-25 11:22:40,194][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_COMCAST-COM_WAN_IP
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IP
type: string, value: 192.168.2.107
[INFO][2024-07-25 11:22:40,194][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value : 192.168.2.107
[INFO][2024-07-25 11:22:40,194][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_COMCAST-COM_WAN_IP, protocolSpecificParamName=Device.DeviceInfo.X_COMCAST-COM_WAN_IP, value=192.168.2.107, datatype=null, statusCode=0]
[INFO][2024-07-25 11:22:40,223][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - INIT-2C:44:FD:18:F9:FE Get device details
[INFO][2024-07-25 11:22:40,228][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Fetching device details for 2C:44:FD:18:F9:FE Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-07-25 11:22:40,241][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 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","connectionType":"Ethernet","nodePort":"4444","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-07-25 11:22:40,259][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - INIT-2C:44:FD:18:F9:FE Obtained device details
[INFO][2024-07-25 11:22:40,260][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - >>>[INIT]: No device config mapped for rack model WINDOWSVM. Proceeding with rack model
[INFO][2024-07-25 11:22:40,260][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - WAN DUT instance : com.automatics.device.Device@d345615
[INFO][2024-07-25 11:22:40,261][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 2C:44:FD:18:F9:FE : Mac Address of the WAN PC
[INFO][2024-07-25 11:22:40,261][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - 192.168.2.81 : IP Address of the WAN PC
[INFO][2024-07-25 11:22:40,261][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Starting Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-07-25 11:22:40,261][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Is WebpaConnectionBroken : false
[INFO][2024-07-25 11:22:40,261][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-07-25 11:22:40,261][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-07-25 11:22:40,261][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-07-25 11:22:40,262][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-07-25 11:22:40,262][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to execute commands
[INFO][2024-07-25 11:22:40,262][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:40,262][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:40,262][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:40,274][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:22:40,467][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.X_CISCO_COM_DeviceControl.HTTPPort
[INFO][2024-07-25 11:22:40,467][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:40,659][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.HTTPPort
Execution succeed.
Parameter 1 name: Device.X_CISCO_COM_DeviceControl.HTTPPort
type: uint, value: 8080
<=============================================================>
[INFO][2024-07-25 11:22:40,660][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:40,660][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.HTTPPort
Execution succeed.
Parameter 1 name: Device.X_CISCO_COM_DeviceControl.HTTPPort
type: uint, value: 8080
[INFO][2024-07-25 11:22:40,660][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.HTTPPort
Execution succeed.
Parameter 1 name: Device.X_CISCO_COM_DeviceControl.HTTPPort
type: uint, value: 8080
[INFO][2024-07-25 11:22:40,660][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value : 8080
[INFO][2024-07-25 11:22:40,660][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.X_CISCO_COM_DeviceControl.HTTPPort, protocolSpecificParamName=Device.X_CISCO_COM_DeviceControl.HTTPPort, value=8080, datatype=null, statusCode=0]
[INFO][2024-07-25 11:22:40,661][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Parameter Value is : 8080
[INFO][2024-07-25 11:22:40,661][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Ending Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-07-25 11:22:40,661][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Starting Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-07-25 11:22:40,661][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Is WebpaConnectionBroken : false
[INFO][2024-07-25 11:22:40,661][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-07-25 11:22:40,662][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-07-25 11:22:40,662][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-07-25 11:22:40,662][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-07-25 11:22:40,662][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to execute commands
[INFO][2024-07-25 11:22:40,662][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:40,662][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:40,662][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:40,679][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:22:40,923][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.X_CISCO_COM_DeviceControl.HTTPSPort
[INFO][2024-07-25 11:22:40,923][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:41,069][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.HTTPSPort
Execution succeed.
Parameter 1 name: Device.X_CISCO_COM_DeviceControl.HTTPSPort
type: uint, value: 8181
<=============================================================>
[INFO][2024-07-25 11:22:41,069][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:41,069][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.HTTPSPort
Execution succeed.
Parameter 1 name: Device.X_CISCO_COM_DeviceControl.HTTPSPort
type: uint, value: 8181
[INFO][2024-07-25 11:22:41,069][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.HTTPSPort
Execution succeed.
Parameter 1 name: Device.X_CISCO_COM_DeviceControl.HTTPSPort
type: uint, value: 8181
[INFO][2024-07-25 11:22:41,070][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value : 8181
[INFO][2024-07-25 11:22:41,070][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.X_CISCO_COM_DeviceControl.HTTPSPort, protocolSpecificParamName=Device.X_CISCO_COM_DeviceControl.HTTPSPort, value=8181, datatype=null, statusCode=0]
[INFO][2024-07-25 11:22:41,070][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Parameter Value is : 8181
[INFO][2024-07-25 11:22:41,070][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Ending Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-07-25 11:22:41,080][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - URL to be launched: http://192.168.2.107:8080/
[INFO][2024-07-25 11:22:41,081][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-07-25 11:22:41,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-07-25 11:22:41,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-07-25 11:22:41,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-07-25 11:22:41,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to execute commands
[INFO][2024-07-25 11:22:41,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:41,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:41,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:41,097][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:22:41,287][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId
[INFO][2024-07-25 11:22:41,287][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:41,410][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId
type: string, value: RDKM
<=============================================================>
[INFO][2024-07-25 11:22:41,410][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:41,410][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId
type: string, value: RDKM
[INFO][2024-07-25 11:22:41,410][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId
type: string, value: RDKM
[INFO][2024-07-25 11:22:41,410][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value : RDKM
[INFO][2024-07-25 11:22:41,411][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId, protocolSpecificParamName=Device.DeviceInfo.X_RDKCENTRAL-COM_Syndication.PartnerId, value=RDKM, datatype=null, statusCode=0]
[INFO][2024-07-25 11:22:41,411][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Current Partner ID of the device Retrieved via WEBPA is :RDKM
[INFO][2024-07-25 11:22:41,411][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:22:41,411][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:22:41,411][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:41,412][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:41,425][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:22:41,640][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: syscfg get user_name_3
[INFO][2024-07-25 11:22:41,640][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:41,762][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
admin
<=============================================================>
[INFO][2024-07-25 11:22:41,762][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: admin
[INFO][2024-07-25 11:22:41,763][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:22:41,763][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:22:41,763][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:22:41,763][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:41,763][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:41,776][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:22:42,034][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: syscfg get user_password_3
[INFO][2024-07-25 11:22:42,034][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:42,156][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
password
<=============================================================>
[INFO][2024-07-25 11:22:42,157][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: password
[INFO][2024-07-25 11:22:42,157][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:22:42,157][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Setting password using WEBPA: password1
[INFO][2024-07-25 11:22:42,158][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-07-25 11:22:42,158][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-07-25 11:22:42,158][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-07-25 11:22:42,158][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-07-25 11:22:42,158][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.Users.User.3.Password string password1
[INFO][2024-07-25 11:22:42,158][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:42,159][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:22:42,159][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:22:42,172][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:22:42,373][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT setv Device.Users.User.3.Password string password1
[INFO][2024-07-25 11:22:42,374][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:22:42,496][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.Users.User.3.Password
Execution succeed.
<=============================================================>
[INFO][2024-07-25 11:22:42,497][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:22:42,497][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.Users.User.3.Password
Execution succeed.
[INFO][2024-07-25 11:22:42,497][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.Users.User.3.Password
Execution succeed.
[INFO][2024-07-25 11:22:42,498][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Response : {Device.Users.User.3.Password=Success}
[INFO][2024-07-25 11:22:42,498][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-07-25 11:22:42,498][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-07-25 11:22:42,499][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-07-25 11:22:42,509][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - WEBPA PARAM - Device.Users.User.3.Password SET WITH VALUE - password1 IS SUCCESSFUL: true
[INFO][2024-07-25 11:22:42,522][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - USER NAME: User
[INFO][2024-07-25 11:22:42,523][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - CLIENT TYPE: Ethernet
[INFO][2024-07-25 11:22:42,523][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-07-25 11:22:42,523][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command on non-RDK device: 2C:44:FD:18:F9:FE unzip -v
[INFO][2024-07-25 11:22:42,523][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.81
[INFO][2024-07-25 11:22:42,922][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
(SSH EXECUTION) : Executing command unzip -v 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-07-25 11:22:42,922][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: unzip -v
[INFO][2024-07-25 11:22:42,922][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Using configured response timeout: 50000
[INFO][2024-07-25 11:22:43,286][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
UnZip 6.00 of 20 April 2009, by Info-ZIP. Maintained by C. Spieler. Send
bug reports using http://www.info-zip.org/zip-bug.html; see README for details.
Latest sources and executables are at ftp://ftp.info-zip.org/pub/infozip/ ;
see ftp://ftp.info-zip.org/pub/infozip/UnZip.html for other sites.
Compiled with gcc 5.4.0 for Unix (Cygwin) on Feb 22 2017.
UnZip special compilation options:
COPYRIGHT_CLEAN (PKZIP 0.9x unreducing method not supported)
SET_DIR_ATTRIB
SYMLINKS (symbolic links supported, if RTL and file system permit)
TIMESTAMP
UNIXBACKUP
USE_EF_UT_TIME
USE_UNSHRINK (PKZIP/Zip 1.x unshrinking method supported)
USE_DEFLATE64 (PKZIP 4.x Deflate64(tm) supported)
UNICODE_SUPPORT [wide-chars, char coding: UTF-8] (handle UTF-8 paths)
MBCS-support (multibyte character support, MB_CUR_MAX = 6)
LARGE_FILE_SUPPORT (large files over 2 GiB supported)
ZIP64_SUPPORT (archives using Zip64 for large files supported)
USE_BZIP2 (PKZIP 4.6+, using bzip2 lib version 1.0.8, 13-Jul-2019)
VMS_TEXT_CONV
[decryption, version 2.11 of 05 Jan 2007]
UnZip and ZipInfo environment options:
UNZIP: [none]
UNZIPOPT: [none]
ZIPINFO: [none]
ZIPINFOOPT: [none]
<=============================================================>
[INFO][2024-07-25 11:22:43,286][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.81
[INFO][2024-07-25 11:22:43,286][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: UnZip 6.00 of 20 April 2009, by Info-ZIP. Maintained by C. Spieler. Send
bug reports using http://www.info-zip.org/zip-bug.html; see README for details.
Latest sources and executables are at ftp://ftp.info-zip.org/pub/infozip/ ;
see ftp://ftp.info-zip.org/pub/infozip/UnZip.html for other sites.
Compiled with gcc 5.4.0 for Unix (Cygwin) on Feb 22 2017.
UnZip special compilation options:
COPYRIGHT_CLEAN (PKZIP 0.9x unreducing method not supported)
SET_DIR_ATTRIB
SYMLINKS (symbolic links supported, if RTL and file system permit)
TIMESTAMP
UNIXBACKUP
USE_EF_UT_TIME
USE_UNSHRINK (PKZIP/Zip 1.x unshrinking method supported)
USE_DEFLATE64 (PKZIP 4.x Deflate64(tm) supported)
UNICODE_SUPPORT [wide-chars, char coding: UTF-8] (handle UTF-8 paths)
MBCS-support (multibyte character support, MB_CUR_MAX = 6)
LARGE_FILE_SUPPORT (large files over 2 GiB supported)
ZIP64_SUPPORT (archives using Zip64 for large files supported)
USE_BZIP2 (PKZIP 4.6+, using bzip2 lib version 1.0.8, 13-Jul-2019)
VMS_TEXT_CONV
[decryption, version 2.11 of 05 Jan 2007]
UnZip and ZipInfo environment options:
UNZIP: [none]
UNZIPOPT: [none]
ZIPINFO: [none]
ZIPINFOOPT: [none]
[INFO][2024-07-25 11:22:43,288][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: UnZip 6.00 of 20 April 2009, by Info-ZIP. Maintained by C. Spieler. Send
bug reports using http://www.info-zip.org/zip-bug.html; see README for details.
Latest sources and executables are at ftp://ftp.info-zip.org/pub/infozip/ ;
see ftp://ftp.info-zip.org/pub/infozip/UnZip.html for other sites.
Compiled with gcc 5.4.0 for Unix (Cygwin) on Feb 22 2017.
UnZip special compilation options:
COPYRIGHT_CLEAN (PKZIP 0.9x unreducing method not supported)
SET_DIR_ATTRIB
SYMLINKS (symbolic links supported, if RTL and file system permit)
TIMESTAMP
UNIXBACKUP
USE_EF_UT_TIME
USE_UNSHRINK (PKZIP/Zip 1.x unshrinking method supported)
USE_DEFLATE64 (PKZIP 4.x Deflate64(tm) supported)
UNICODE_SUPPORT [wide-chars, char coding: UTF-8] (handle UTF-8 paths)
MBCS-support (multibyte character support, MB_CUR_MAX = 6)
LARGE_FILE_SUPPORT (large files over 2 GiB supported)
ZIP64_SUPPORT (archives using Zip64 for large files supported)
USE_BZIP2 (PKZIP 4.6+, using bzip2 lib version 1.0.8, 13-Jul-2019)
VMS_TEXT_CONV
[decryption, version 2.11 of 05 Jan 2007]
UnZip and ZipInfo environment options:
UNZIP: [none]
UNZIPOPT: [none]
ZIPINFO: [none]
ZIPINFOOPT: [none]
[INFO][2024-07-25 11:22:43,294][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Presence of parameter command not found in the command output : false
[INFO][2024-07-25 11:22:43,295][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Unzip support available in Cygwin
[INFO][2024-07-25 11:22:43,332][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
java.lang.ExceptionInInitializerError: null
at com.automatics.rdkb.utils.wifi.connectedclients.BroadBandConnectedClientUtils.verifyBrowserAndDriverCapabilityOnConnectedClient(BroadBandConnectedClientUtils.java:5599)
at com.automatics.rdkb.webui.page.LanSideBasePage.invokeBrowserinConnectedClient(LanSideBasePage.java:709)
at com.automatics.rdkb.webui.page.LanWebGuiLoginPage.logintoLanPageinConnectedClient(LanWebGuiLoginPage.java:176)
at com.automatics.rdkb.webui.page.LanWebGuiLoginPage.logintoLanPageUsingWanIp(LanWebGuiLoginPage.java:1857)
at com.automatics.rdkb.tests.security.BroadBandSecurityTest.testToVerifyRemovedOrEncryptedPrivateKeysAndWebGuiPageNavigation(BroadBandSecurityTest.java:7292)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:134)
at org.testng.internal.TestInvoker.invokeMethod(TestInvoker.java:597)
at org.testng.internal.TestInvoker.invokeTestMethod(TestInvoker.java:173)
at org.testng.internal.TestMethodWithDataProviderMethodWorker.call(TestMethodWithDataProviderMethodWorker.java:77)
at org.testng.internal.TestMethodWithDataProviderMethodWorker.call(TestMethodWithDataProviderMethodWorker.java:15)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
Caused by: java.lang.NullPointerException: null
at com.automatics.rdkb.constants.BroadBandTestConstants$16.(BroadBandTestConstants.java:4747)
at com.automatics.rdkb.constants.BroadBandTestConstants.(BroadBandTestConstants.java:4744)
... 20 common frames omitted
[INFO][2024-07-25 11:22:43,340][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STARTING METHOD: invokeBrowserInNode()
[INFO][2024-07-25 11:22:43,341][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Validating browser selection for client - true
[INFO][2024-07-25 11:22:43,348][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [Selenium Configuration] : Connecting to Selenium Node:http://192.168.2.81:4444/wd/hub
[INFO][2024-07-25 11:22:43,349][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [Selenium Configuration] : Browser Type:chrome
[INFO][2024-07-25 11:22:43,349][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [Selenium Configuration] : Setting capabilities for chrome driver
Jul 25, 2024 11:22:43 AM org.openqa.selenium.remote.DesiredCapabilities chrome
INFO: Using `new ChromeOptions()` is preferred to `DesiredCapabilities.chrome()`
Jul 25, 2024 11:22:45 AM org.openqa.selenium.remote.ProtocolHandshake createSession
INFO: Detected dialect: W3C
[INFO][2024-07-25 11:22:45,484][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - URL to be launched in ADMIN UI login page ==> http://192.168.2.107:8080/
[INFO][2024-07-25 11:23:19,245][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Element not foundExpected condition failed: waiting for text ('Gateway > Login') to be present in element found by By.xpath: //*[@id="content"]/h1 (tried for 30 second(s) with 500 milliseconds interval)
[INFO][2024-07-25 11:23:19,322][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - cusAdminPageTitle : Login - RDKM
[INFO][2024-07-25 11:23:19,323][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Pattern Found = Login - RDKM
[INFO][2024-07-25 11:23:19,323][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Login - RDKM
[INFO][2024-07-25 11:23:19,324][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - is patter found for Login - RDKM in target string = true
[INFO][2024-07-25 11:23:19,324][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Successfully verified Lan Side Admin page launched status : true
[INFO][2024-07-25 11:23:19,466][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:23:19,466][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:23:19,467][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:23:19,477][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:23:19,526][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Username :admin
[INFO][2024-07-25 11:23:19,588][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Password :password1
[INFO][2024-07-25 11:23:19,683][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-07-25 11:23:19,683][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:23:19,683][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:23:19,684][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:23:19,696][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:23:19,909][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-07-25 11:23:19,909][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-07-25 11:23:19,909][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-07-25 11:23:19,910][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-07-25 11:23:20,032][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-07-25 11:23:20,032][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@7f41a955
[INFO][2024-07-25 11:23:20,033][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-07-25 11:23:54,294][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exception occurred while validating Browser Alert :Expected condition failed: waiting for alert to be present (tried for 30 second(s) with 500 milliseconds interval)
Build info: version: '3.12.0', revision: '7c6e0b3', time: '2018-05-08T14:04:26.12Z'
System info: host: 'COMCAST2', ip: '192.168.2.74', os.name: 'Linux', os.arch: 'amd64', os.version: '3.10.0-1160.80.1.el7.x86_64', java.version: '11.0.17'
Driver info: org.openqa.selenium.remote.RemoteWebDriver
Capabilities {acceptInsecureCerts: true, browserName: chrome, browserVersion: 127.0.6533.72, chrome: {chromedriverVersion: 127.0.6533.72 (9755e24ca85a..., userDataDir: C:\Windows\SystemTemp\scope...}, fedcm:accounts: true, goog:chromeOptions: {debuggerAddress: localhost:61779}, javascriptEnabled: true, networkConnectionEnabled: false, pageLoadStrategy: normal, platform: WINDOWS, platformName: WINDOWS, proxy: Proxy(), setWindowRect: true, strictFileInteractability: false, timeouts: {implicit: 0, pageLoad: 300000, script: 30000}, unhandledPromptBehavior: dismiss and notify, webauthn:extension:credBlob: true, webauthn:extension:largeBlob: true, webauthn:extension:minPinLength: true, webauthn:extension:prf: true, webauthn:virtualAuthenticators: true, webdriver.remote.sessionid: b8576a102b06f7f7481f76b6efe...}
Session ID: b8576a102b06f7f7481f76b6efe7829f
[INFO][2024-07-25 11:23:54,294][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Validating UI Alert for Residential class device
[INFO][2024-07-25 11:23:54,294][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Waiting for 'Alert?' PopUp message.
[INFO][2024-07-25 11:24:20,033][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:24:20,033][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:24:20,033][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:24:20,048][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:24:20,294][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-07-25 11:24:20,295][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:24:20,295][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:24:20,295][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:24:20,308][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:24:20,514][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-07-25 11:24:20,514][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-07-25 11:24:20,514][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-07-25 11:24:20,515][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-07-25 11:24:20,637][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-07-25 11:24:20,637][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@7f41a955
[INFO][2024-07-25 11:24:20,637][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-07-25 11:24:24,339][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exception occurred while validating UI Alert for Residential Device:no such element: Unable to locate element: {"method":"xpath","selector":"//*[@id="popup_title"]"}
(Session info: chrome=127.0.6533.72)
For documentation on this error, please visit: http://seleniumhq.org/exceptions/no_such_element.html
Build info: version: '3.12.0', revision: '7c6e0b3', time: '2018-05-08T14:04:26.12Z'
System info: host: 'COMCAST2', ip: '192.168.2.74', os.name: 'Linux', os.arch: 'amd64', os.version: '3.10.0-1160.80.1.el7.x86_64', java.version: '11.0.17'
Driver info: org.openqa.selenium.remote.RemoteWebDriver
Capabilities {acceptInsecureCerts: true, browserName: chrome, browserVersion: 127.0.6533.72, chrome: {chromedriverVersion: 127.0.6533.72 (9755e24ca85a..., userDataDir: C:\Windows\SystemTemp\scope...}, fedcm:accounts: true, goog:chromeOptions: {debuggerAddress: localhost:61779}, javascriptEnabled: true, networkConnectionEnabled: false, pageLoadStrategy: normal, platform: WINDOWS, platformName: WINDOWS, proxy: Proxy(), setWindowRect: true, strictFileInteractability: false, timeouts: {implicit: 0, pageLoad: 300000, script: 30000}, unhandledPromptBehavior: dismiss and notify, webauthn:extension:credBlob: true, webauthn:extension:largeBlob: true, webauthn:extension:minPinLength: true, webauthn:extension:prf: true, webauthn:virtualAuthenticators: true, webdriver.remote.sessionid: b8576a102b06f7f7481f76b6efe...}
Session ID: b8576a102b06f7f7481f76b6efe7829f
*** Element info: {Using=xpath, value=//*[@id="popup_title"]}
[INFO][2024-07-25 11:24:24,339][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - UI Alert :false
[INFO][2024-07-25 11:24:24,341][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Driver:::RemoteWebDriver: chrome on WINDOWS (b8576a102b06f7f7481f76b6efe7829f)
[INFO][2024-07-25 11:24:24,354][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - pageTitle:::Gateway > At a Glance - RDKM
[INFO][2024-07-25 11:24:24,354][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Obtained Page title from driver instance : Gateway > At a Glance - RDKM
[INFO][2024-07-25 11:24:24,354][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Verified AdminUI Home page title, Expected title:Gateway > At a Glance - RDKMActual title: Gateway > At a Glance - RDKM status for launching atGlancePage:true
[INFO][2024-07-25 11:24:24,367][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - #######################################################################################
[INFO][2024-07-25 11:24:24,367][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exception occured for DSL gateway page verification
[INFO][2024-07-25 11:24:24,367][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - #######################################################################################
[INFO][2024-07-25 11:24:24,367][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 3: ACTUAL : LAN GUI ADMIN LOGIN SUCCESSFUL
[INFO][2024-07-25 11:24:24,367][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:24:27,746][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s3][ Execution status : PASS] [Error Message : ]
[INFO][2024-07-25 11:24:27,747][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","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-BINARIES-1003"}
[ERROR][2024-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 4: DESCRIPTION : Verify SSID name of 2.4GHz radio from GUI with WebPa value.
[INFO][2024-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 4: ACTION : Get SSID name from WebPa and verify it matches with GUI.
[INFO][2024-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 4: EXPECTED : SSID name of 2.4GHz radio retrieved from Webpa should match with GUI.
[INFO][2024-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Starting Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Is WebpaConnectionBroken : false
[INFO][2024-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-07-25 11:24:27,774][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-07-25 11:24:27,775][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-07-25 11:24:27,775][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-07-25 11:24:27,775][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to execute commands
[INFO][2024-07-25 11:24:27,775][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:24:27,775][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:24:27,775][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:24:27,786][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:24:27,958][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.SSID.1.SSID
[INFO][2024-07-25 11:24:27,958][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:24:28,081][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.wifi): Device.WiFi.SSID.1.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.1.SSID
type: string, value: 24GHZ_private_1721886585975
<=============================================================>
[INFO][2024-07-25 11:24:28,081][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:24:28,081][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.1.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.1.SSID
type: string, value: 24GHZ_private_1721886585975
[INFO][2024-07-25 11:24:28,081][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.1.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.1.SSID
type: string, value: 24GHZ_private_1721886585975
[INFO][2024-07-25 11:24:28,081][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value : 24GHZ_private_1721886585975
[INFO][2024-07-25 11:24:28,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.WiFi.SSID.10001.SSID, protocolSpecificParamName=Device.WiFi.SSID.1.SSID, value=24GHZ_private_1721886585975, datatype=null, statusCode=0]
[INFO][2024-07-25 11:24:28,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Parameter Value is : 24GHZ_private_1721886585975
[INFO][2024-07-25 11:24:28,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Ending Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-07-25 11:24:28,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSID for 2.4G: 24GHZ_private_1721886585975
[INFO][2024-07-25 11:24:28,083][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - webdriver :RemoteWebDriver: chrome on WINDOWS (b8576a102b06f7f7481f76b6efe7829f)
[INFO][2024-07-25 11:24:28,083][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - property key of xpath for wifi 2 ghz SSID NAME not configured : Getting the default value
[INFO][2024-07-25 11:24:28,125][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSID NAME obtained from home page: 24GHZ_private_1721886585975
[INFO][2024-07-25 11:24:28,125][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 4: ACTUAL : SSID name of 2.4GHz radio obtained from WebPa and GUI are same.
[INFO][2024-07-25 11:24:28,125][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:24:30,900][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s4][ Execution status : PASS] [Error Message : ]
[INFO][2024-07-25 11:24:30,901][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","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-BINARIES-1003"}
[ERROR][2024-07-25 11:24:30,927][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:24:30,927][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:24:30,927][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 5: DESCRIPTION : Verify SSID name of 5GHz radio from GUI with WebPa value.
[INFO][2024-07-25 11:24:30,927][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 5: ACTION : Get SSID name from WebPa and verify it matches with GUI.
[INFO][2024-07-25 11:24:30,927][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 5: EXPECTED : SSID name of 5GHz radio retrieved from Webpa should match with GUI.
[INFO][2024-07-25 11:24:30,927][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:24:30,927][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Starting Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-07-25 11:24:30,927][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Is WebpaConnectionBroken : false
[INFO][2024-07-25 11:24:30,928][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-07-25 11:24:30,928][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-07-25 11:24:30,928][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-07-25 11:24:30,928][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-07-25 11:24:30,928][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going to execute commands
[INFO][2024-07-25 11:24:30,928][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.107
[INFO][2024-07-25 11:24:30,928][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:24:30,928][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:24:30,938][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:24:31,132][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: dmcli eRT getv Device.WiFi.SSID.2.SSID
[INFO][2024-07-25 11:24:31,132][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:24:31,255][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.wifi): Device.WiFi.SSID.2.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.2.SSID
type: string, value: 5GHZ_private_1721886585975
<=============================================================>
[INFO][2024-07-25 11:24:31,255][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.107
[INFO][2024-07-25 11:24:31,255][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.2.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.2.SSID
type: string, value: 5GHZ_private_1721886585975
[INFO][2024-07-25 11:24:31,255][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.wifi): Device.WiFi.SSID.2.SSID
Execution succeed.
Parameter 1 name: Device.WiFi.SSID.2.SSID
type: string, value: 5GHZ_private_1721886585975
[INFO][2024-07-25 11:24:31,256][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Dmcli param value : 5GHZ_private_1721886585975
[INFO][2024-07-25 11:24:31,256][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.WiFi.SSID.10101.SSID, protocolSpecificParamName=Device.WiFi.SSID.2.SSID, value=5GHZ_private_1721886585975, datatype=null, statusCode=0]
[INFO][2024-07-25 11:24:31,256][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Parameter Value is : 5GHZ_private_1721886585975
[INFO][2024-07-25 11:24:31,256][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Ending Method getParameterValuesUsingWebPaOrDmcli()
[INFO][2024-07-25 11:24:31,257][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSID for 5G: 5GHZ_private_1721886585975
[INFO][2024-07-25 11:24:31,257][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - property key of xpath for wifi 5 ghz SSID NAME not configured : Getting the default value
[INFO][2024-07-25 11:24:31,296][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 5: ACTUAL : SSID name of 5GHz radio obtained from WebPa and GUI are same.
[INFO][2024-07-25 11:24:31,297][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:24:34,092][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s5][ Execution status : PASS] [Error Message : ]
[INFO][2024-07-25 11:24:34,093][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s5","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:24:34,104][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:24:34,105][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:24:34,105][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 6: DESCRIPTION : Verify navigating to Network page in WEB GUI.
[INFO][2024-07-25 11:24:34,105][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 6: ACTION : Click on ' Network' link or Launch URL for Network page & Verify page title.
[INFO][2024-07-25 11:24:34,105][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 6: EXPECTED : Navigation to Network page should be successful.
[INFO][2024-07-25 11:24:34,105][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:24:34,106][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - LINK TEXT :RDKM
[INFO][2024-07-25 11:24:34,117][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - PAGE TITLE BEFORE LINK TEXT CLICK :Gateway > At a Glance - RDKM
[ERROR][2024-07-25 11:25:04,185][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Excepton Occured using Link Text,Launching URL for RDKM
[INFO][2024-07-25 11:25:04,185][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - command to be executed to get device properties is cat /etc/device.properties | grep
[INFO][2024-07-25 11:25:04,186][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:25:04,186][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:25:04,186][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:25:04,186][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:25:04,199][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:25:04,394][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: cat /etc/device.properties | grep ATOM_SYNC
[INFO][2024-07-25 11:25:04,394][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:25:04,516][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
<=============================================================>
[INFO][2024-07-25 11:25:04,516][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response:
[INFO][2024-07-25 11:25:04,516][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:25:04,517][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:25:04,517][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:25:04,517][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:25:04,517][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:25:04,530][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:25:04,756][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: cat /etc/device.properties | grep ATOM_SYNC
[INFO][2024-07-25 11:25:04,756][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:25:04,878][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
<=============================================================>
[INFO][2024-07-25 11:25:04,878][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response:
[INFO][2024-07-25 11:25:04,878][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:25:04,878][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:25:04,879][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:25:04,879][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:25:04,879][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:25:04,888][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:25:05,100][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: cat /etc/device.properties | grep ATOM_SYNC
[INFO][2024-07-25 11:25:05,100][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:25:05,223][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
<=============================================================>
[INFO][2024-07-25 11:25:05,223][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response:
[INFO][2024-07-25 11:25:05,223][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:25:05,223][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STARTING METHOD: isFileExists()
[INFO][2024-07-25 11:25:05,224][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:25:05,224][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:25:05,224][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:25:05,224][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:25:05,237][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:25:05,453][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: if [ -f /usr/sbin/jst ] ; then echo "true" ; else echo "false" ; fi
[INFO][2024-07-25 11:25:05,453][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:25:05,576][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
true
<=============================================================>
[INFO][2024-07-25 11:25:05,576][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: true
[INFO][2024-07-25 11:25:05,576][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:25:05,576][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - ENDING METHOD: isFileExists()
[INFO][2024-07-25 11:25:05,577][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - JST file path present status: true
[INFO][2024-07-25 11:25:05,577][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - JST PAGE NAME :/network_setup.jst
[INFO][2024-07-25 11:25:05,578][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - URL TO LAUNCH :http://192.168.2.107/network_setup.jst
[INFO][2024-07-25 11:25:20,638][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:25:20,638][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:25:20,639][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:25:20,652][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:25:20,870][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-07-25 11:25:20,871][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-07-25 11:25:20,871][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:25:20,871][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-07-25 11:25:20,882][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-07-25 11:25:21,099][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-07-25 11:25:21,100][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-07-25 11:25:21,100][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-07-25 11:25:21,100][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-07-25 11:25:21,222][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-07-25 11:25:21,222][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@7f41a955
[INFO][2024-07-25 11:25:21,223][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-07-25 11:25:28,578][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Current Page title - Gateway > Connection > RDKM - RDKM
[INFO][2024-07-25 11:25:28,578][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Parameter string - Gateway > Connection > RDKM - RDKM
[INFO][2024-07-25 11:25:28,578][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Successfully verified page navigation statustrue
[INFO][2024-07-25 11:25:28,590][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Current URL: http://192.168.2.107/network_setup.jst
[INFO][2024-07-25 11:25:28,590][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP 6: ACTUAL : Navigation to Network page in WEB GUI is successful.
[INFO][2024-07-25 11:25:28,590][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:34,089][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s6][ Execution status : PASS] [Error Message : ]
[INFO][2024-07-25 11:25:34,089][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s6","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":true,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:34,116][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:34,116][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:34,116][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s7: DESCRIPTION : Verify the WAN IPv4 Address from GUI with WebPa value.
[INFO][2024-07-25 11:25:34,116][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s7: ACTION : Get the WAN IPv4 address from network page and cross verify it with WebPa value.
[INFO][2024-07-25 11:25:34,116][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s7: EXPECTED : Wan IPv4 address obtained form LAN page should match WebPa value
Sample : WAN IP Address (IPv4):68.81.38.72
dmcli eRT getv Device.DeviceInfo.X_COMCAST-COM_WAN_IP
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DeviceInfo.X_COMCAST-COM_WAN_IP
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IP
type: string, value: 68.81.38.72
[INFO][2024-07-25 11:25:34,116][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:34,117][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - property key of xpath for WAN IP Address (IPv6) not configured : Getting the default value
[INFO][2024-07-25 11:25:34,160][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - WAN IP Address (IPv4) retrieved from WebGUI : 192.168.2.107
[INFO][2024-07-25 11:25:34,161][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - WAN IP Address (IPv4) retrieved from WebPa : 192.168.2.107
[INFO][2024-07-25 11:25:34,161][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - VALIDATION RESULT OF OPERATION = TXT_COMPARISON, EXPECTED VALUE = 192.168.2.107, ACTUAL VALUE = 192.168.2.107 IS: true
[INFO][2024-07-25 11:25:34,161][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s7: ACTUAL : Wan IPv4 address obtained form WebGUI matches webpa value.
[INFO][2024-07-25 11:25:34,161][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:39,655][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s7][ Execution status : PASS] [Error Message : ]
[INFO][2024-07-25 11:25:39,656][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s7","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:39,682][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:39,682][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:39,682][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s8: DESCRIPTION : Verify the WAN IPv6 Address from GUI with WebPa value.
[INFO][2024-07-25 11:25:39,682][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s8: ACTION : Get the WAN IPv6 address from Partner network page and cross verify it with WebPa value.
[INFO][2024-07-25 11:25:39,683][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s8: EXPECTED : WAN IPv6 address obtained form LAN page should match WebPa value.
Sample : WAN IP Address (IPv6): 2001:558:6027:14:e584:7736:4902:95ca
~ # dmcli eRT getv Device.DeviceInfo.X_COMCAST-COM_WAN_IPv6
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.pam): Device.DeviceInfo.X_COMCAST-COM_WAN_IPv6
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_COMCAST-COM_WAN_IPv6
type: string, value: 2001:558:6027:14:e584:7736:4902:95ca
[INFO][2024-07-25 11:25:39,683][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:39,683][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - IPv6 is not Enabled : skipping step
[INFO][2024-07-25 11:25:39,683][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:39,683][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-1003] [step Number : s6][ Execution status : NA] [Error Message : ]
[INFO][2024-07-25 11:25:39,684][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-1003","stepNumber":"s6","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"NA","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:39,695][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:39,695][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:39,695][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s9: DESCRIPTION : Verify lighttpd is running.
[INFO][2024-07-25 11:25:39,695][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s9: ACTION : Execute the following command after SSH the device: ps -ef|grep "lighttpd"
[INFO][2024-07-25 11:25:39,696][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s9: EXPECTED : Lighttpd should be running successfully.
[INFO][2024-07-25 11:25:39,696][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:39,696][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-07-25 11:25:39,696][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.107
[INFO][2024-07-25 11:25:39,696][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSH Host IP : 192.168.2.107
[INFO][2024-07-25 11:25:39,696][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-07-25 11:25:39,707][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-07-25 11:25:39,910][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Executing command: pidof "lighttpd"
[INFO][2024-07-25 11:25:39,910][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-07-25 11:25:40,032][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] -
<=========================== RESPONSE =======================>
3389
<=============================================================>
[INFO][2024-07-25 11:25:40,033][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Received response: 3389
[INFO][2024-07-25 11:25:40,033][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-07-25 11:25:40,034][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - PID of 'lighttpd' is: 3389
[INFO][2024-07-25 11:25:40,034][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s9: ACTUAL : Lighttpd is running successfully.
[INFO][2024-07-25 11:25:40,034][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:40,034][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s9][ Execution status : PASS] [Error Message : ]
[INFO][2024-07-25 11:25:40,035][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s9","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:40,049][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:40,058][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Platform specific resolved property key is : Device.ledlogs.Available._rpi-rdkb
[INFO][2024-07-25 11:25:40,058][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - No device specific value found
[INFO][2024-07-25 11:25:40,058][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s10: NOT APPLICABLE FOR THIS DEVICE
[INFO][2024-07-25 11:25:40,058][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:40,058][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s10][ Execution status : NA] [Error Message : ]
[INFO][2024-07-25 11:25:40,059][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s10","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"NA","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:40,072][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:40,072][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s11: NOT APPLICABLE FOR THIS DEVICE
[INFO][2024-07-25 11:25:40,072][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:40,072][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s11][ Execution status : NA] [Error Message : ]
[INFO][2024-07-25 11:25:40,073][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s11","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"NA","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:40,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:40,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s12: NOT APPLICABLE FOR THIS DEVICE
[INFO][2024-07-25 11:25:40,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:40,082][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s12][ Execution status : NA] [Error Message : ]
[INFO][2024-07-25 11:25:40,083][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s12","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"NA","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:40,092][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:40,093][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s13: NOT APPLICABLE FOR THIS DEVICE
[INFO][2024-07-25 11:25:40,093][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:40,093][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s13][ Execution status : NA] [Error Message : ]
[INFO][2024-07-25 11:25:40,093][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s13","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"NA","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:40,102][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:40,102][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s14: NOT APPLICABLE FOR THIS DEVICE
[INFO][2024-07-25 11:25:40,102][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:40,102][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s14][ Execution status : NA] [Error Message : ]
[INFO][2024-07-25 11:25:40,103][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s14","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"NA","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:40,112][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:40,112][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s15: NOT APPLICABLE FOR THIS DEVICE
[INFO][2024-07-25 11:25:40,112][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:40,112][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s15][ Execution status : NA] [Error Message : ]
[INFO][2024-07-25 11:25:40,113][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s15","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"NA","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:40,121][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:40,121][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - STEP s16: NOT APPLICABLE FOR THIS DEVICE
[INFO][2024-07-25 11:25:40,121][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - **********************************************************************************
[INFO][2024-07-25 11:25:40,121][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-BINARIES-103] [step Number : s16][ Execution status : NA] [Error Message : ]
[INFO][2024-07-25 11:25:40,122][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-BINARIES-103","stepNumber":"s16","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"NA","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-BINARIES-1003"}
[ERROR][2024-07-25 11:25:40,130][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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-07-25 11:25:40,130][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - ENDING TEST CASE: TC-RDKB-BINARIES-1003
[INFO][2024-07-25 11:25:40,133][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - >>>[AFTER_METHOD]: Clear device trace buffer for D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:25:40,140][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-BINARIES-1003/D83ADD138C13/settoptrace/
[INFO][2024-07-25 11:25:40,141][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - created a new directory true
[INFO][2024-07-25 11:25:40,141][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - New trace location from the strings /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-07-25 11:25:40,143][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Writing stated from line
[INFO][2024-07-25 11:25:40,215][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-BINARIES-1003/D83ADD138C13/logs/
[INFO][2024-07-25 11:25:40,215][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - created a new directory true
[INFO][2024-07-25 11:25:40,215][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Destination Summary file doesn't exist. Creating one!
[INFO][2024-07-25 11:25:40,216][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Writing stated from line
[INFO][2024-07-25 11:25:40,356][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Thu Jul 25 11:25:40 IST 2024
[INFO][2024-07-25 11:25:40,356][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - >>>[AFTER_METHOD]: Sending test execution completion time for D8:3A:DD:13:8C:13
[INFO][2024-07-25 11:25:40,356][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-07-25 11:25:40,356][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - {"jobId":456,"automationId":"TC-RDKB-BINARIES-1003","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":0,"endDateTimeEST":1721886940356}
[INFO][2024-07-25 11:25:40,368][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Capture execution time - Response : HTTP/1.1 200
[INFO][2024-07-25 11:25:40,368][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Restarting Trace in after method
[INFO][2024-07-25 11:25:40,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-07-25 11:25:40,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-07-25 11:25:40,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Going for if build changed : Test Type : GROUP_OR_AUTOID
[INFO][2024-07-25 11:25:40,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-07-25 11:25:40,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : D83ADD138C13] - >>>[AFTER_METHOD]: Build Change Status: NO_CHANGE
[INFO][2024-07-25 11:25:40,370][D8:3A:DD:13:8C:13[TC-RDKB-BINARIES-1003 : 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.security.BroadBandSecurityTest@24684e8f]org.testng.TestRunner@3c3ba024 610815631
AutomaticsTestBase.performBeforeMethodInit([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.security.BroadBandSecurityTest@24684e8f][Ljava.lang.Object;@58003df6 [TestResult name={null} status=CREATED method=BroadBandSecurityTest.testToVerifyRemovedOrEncryptedPrivateKeysAndWebGuiPageNavigation(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.security.BroadBandSecurityTest@24684e8f] output={null}] 610815631
BroadBandSecurityTest.testToVerifyRemovedOrEncryptedPrivateKeysAndWebGuiPageNavigation(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.security.BroadBandSecurityTest@24684e8f]com.automatics.device.Device@7f41a955 610815631
AutomaticsTestBase.performAfterMethodCleanup([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.security.BroadBandSecurityTest@24684e8f][Ljava.lang.Object;@58003df6 [TestResult name=testToVerifyRemovedOrEncryptedPrivateKeysAndWebGuiPageNavigation status=SUCCESS method=BroadBandSecurityTest.testToVerifyRemovedOrEncryptedPrivateKeysAndWebGuiPageNavigation(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.security.BroadBandSecurityTest@24684e8f] output={null}] 610815631
=====
PASSED: testToVerifyRemovedOrEncryptedPrivateKeysAndWebGuiPageNavigation(com.automatics.device.Device@7f41a955)
===============================================
MyTest
Tests run: 1, Failures: 0, Skips: 0
===============================================
[INFO][2024-07-25 11:25:40,406][[com.automatics.executor.Starter.main()] - Finished testing class: org.testng.TestRunner
[INFO][2024-07-25 11:25:40,407][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Performing after suite cleanup
[INFO][2024-07-25 11:25:40,407][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-07-25 11:25:40,408][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Locked devices after suite execution 1
[INFO][2024-07-25 11:25:40,408][[com.automatics.executor.Starter.main()] - Value of intial testType : QUICK
[INFO][2024-07-25 11:25:40,408][[com.automatics.executor.Starter.main()] - Value of TestType provided by job : GROUP_OR_AUTOID
[INFO][2024-07-25 11:25:40,408][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Verifying if build changed after test
[INFO][2024-07-25 11:25:40,408][[com.automatics.executor.Starter.main()] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-07-25 11:25:40,408][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Build Change Status: NO_CHANGE
[INFO][2024-07-25 11:25:40,408][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[ERROR][2024-07-25 11:25:40,409][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Sleep interrupted sleep interrupted
[ERROR][2024-07-25 11:25:40,409][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - readOutputFromChannel - Exception Details ------
java.io.InterruptedIOException: null
at java.base/java.io.PipedInputStream.read(PipedInputStream.java:328)
at java.base/java.io.PipedInputStream.read(PipedInputStream.java:377)
at java.base/sun.nio.cs.StreamDecoder.readBytes(StreamDecoder.java:284)
at java.base/sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:326)
at java.base/sun.nio.cs.StreamDecoder.read(StreamDecoder.java:178)
at java.base/java.io.InputStreamReader.read(InputStreamReader.java:181)
at java.base/java.io.BufferedReader.fill(BufferedReader.java:161)
at java.base/java.io.BufferedReader.readLine(BufferedReader.java:326)
at java.base/java.io.BufferedReader.readLine(BufferedReader.java:392)
at com.automatics.providers.trace.AbstractTraceProviderImpl.readOutputFromChannel(AbstractTraceProviderImpl.java:820)
at com.automatics.providers.trace.AbstractTraceProviderImpl.connectAndRead(AbstractTraceProviderImpl.java:734)
at com.automatics.providers.trace.AbstractTraceProviderImpl$ConnectionThread.run(AbstractTraceProviderImpl.java:1135)
[INFO][2024-07-25 11:25:40,409][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-07-25 11:25:40,409][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-07-25 11:25:40,409][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[INFO][2024-07-25 11:25:40,409][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-07-25 11:25:40,409][[com.automatics.executor.Starter.main()] - Skipping parter specific after suite clean up as it is not configured.
[INFO][2024-07-25 11:25:40,409][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-07-25 11:25:40,410][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Exited from readOutputFromChannel method
[INFO][2024-07-25 11:25:40,413][[com.automatics.executor.Starter.main()] - Releasing device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/device/release
[INFO][2024-07-25 11:25:40,457][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-07-25 11:25:40,458][[com.automatics.executor.Starter.main()] - Successfully unlocked RDKB (D8:3A:DD:13:8C:13) mapped components -
[INFO][2024-07-25 11:25:40,458][[com.automatics.executor.Starter.main()] - SETTOP - D8:3A:DD:13:8C:13 RELEASED.
[INFO][2024-07-25 11:25:40,459][[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":1721886551232,"completionTime":1721886940459,"JMD_ID":456,"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-07-25 11:25:40,599][[com.automatics.executor.Starter.main()] - Updating final execution status to Automatics
[INFO][2024-07-25 11:25:40,599][[Thread-138] - ============= MESSAGE SEND TO AUTOMATICS ==============
[INFO][2024-07-25 11:25:40,600][[Thread-138] - Tested build name : RPI-RDKB
[INFO][2024-07-25 11:25:40,600][[Thread-138] - Final execution status : COMPLETED
[INFO][2024-07-25 11:25:40,600][[Thread-138] - Final successful device list : ["D8:3A:DD:13:8C:13"]
[INFO][2024-07-25 11:25:40,600][[Thread-138] - Job Id : 456
[INFO][2024-07-25 11:25:40,600][[Thread-138] - ==============================================================
[INFO][2024-07-25 11:25:40,600][[Thread-138] - >>>[INIT]: Automatics JSON ->
{"status":"COMPLETED","service":"","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1721886551232,"completionTime":1721886940459,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}}
[INFO][2024-07-25 11:25:40,600][[Thread-138] - http://192.168.2.59:8080/Automatics/executionResponse.htm
[INFO][2024-07-25 11:27:40,727][[Thread-138] - HTTP STATUS LINE : HTTP/1.1 200
[INFO][2024-07-25 11:27:40,728][[Thread-138] - HTTP STATUS CODE : 200
[INFO][2024-07-25 11:27:40,728][[com.automatics.executor.Starter.main()] - ******** EXECUTION COMPLETED *********
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 08:59 min
[INFO] Finished at: 2024-07-25T11:27:40+05:30
[INFO] ------------------------------------------------------------------------
Inside Add Shutdown Hook
[INFO][2024-07-25 11:27:40,735][[Thread-1] - Bean testInitializer is not configured.
[INFO][2024-07-25 11:27:40,736][[Thread-1] - Closing partner application context
[INFO][2024-07-25 11:27:40,738][[Thread-1] - Closing core application context
[JOB1] $ /bin/sh -xe /tmp/jenkins11892692811513475499.sh
+ /var/lib/jenkins/pre-test-config.sh -g com.automatics.providers -a rpi-provider-impl -v 0.0.1-SNAPSHOT
Mode: RDKB
Dependency already exist.
Dependency already exist.
Adding dependency com.automatics.providersrpi-provider-impl0.0.1-SNAPSHOT
Finished updating pom.xml
Archiving artifacts
Finished: SUCCESS