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] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 20048
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 20191
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 20334
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 20477
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 20620
[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 ---
18:43:50,348 |-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.23.0/automatics-core-2.23.0.jar!/logback-test.xml]
18:43:50,359 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@7c5b5bfd - URL [jar:file:/root/.m2/repository/com/automatics/apps/automatics-core/2.23.0/automatics-core-2.23.0.jar!/logback-test.xml] is not of type file
18:43:50,464 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
18:43:50,466 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdOutAppender]
18:43:50,473 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@7:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]]
18:43:50,473 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@8:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]]
18:43:50,474 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
18:43:50,489 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
18:43:50,491 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [deviceSiftingAppender]
18:43:50,493 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@18:13 - no applicable action for [onMatch], current ElementPath is [[configuration][appender][filter][onMatch]]
18:43:50,493 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@19:16 - no applicable action for [onMismatch], current ElementPath is [[configuration][appender][filter][onMismatch]]
18:43:50,495 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
18:43:50,498 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
18:43:50,498 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [TraceLogger]
18:43:50,498 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
18:43:50,498 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
18:43:50,499 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SerialTrace]
18:43:50,499 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
18:43:50,499 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
18:43:50,499 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [crash-analysis]
18:43:50,499 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
18:43:50,500 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.apache.http] to false
18:43:50,500 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache.http level set to INFO
18:43:50,500 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[org.apache.http]
18:43:50,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ConnectionTrace] to TRACE
18:43:50,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ConnectionTrace] to false
18:43:50,501 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [TraceLogger] to Logger[ConnectionTrace]
18:43:50,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [SerialTrace] to TRACE
18:43:50,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [SerialTrace] to false
18:43:50,501 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SerialTrace] to Logger[SerialTrace]
18:43:50,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [crash-analysis] to DEBUG
18:43:50,501 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [crash-analysis] to false
18:43:50,501 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [crash-analysis] to Logger[crash-analysis]
18:43:50,501 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdOutAppender] to Logger[ROOT]
18:43:50,501 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[ROOT]
18:43:50,501 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
18:43:50,502 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@4c12394 - Registering current configuration as safe fallback point
[INFO][2024-08-28 18:43:50,504][[com.automatics.executor.Starter.main()] - Starting the execution process for the given test cases.
18:43:50,505 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
18:43:50,507 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-test-suite]
18:43:50,509 |-INFO in ch.qos.logback.core.FileAppender[FILE-test-suite] - File property is set to [target/logs/test-suite.log]
[INFO][2024-08-28 18:43:50,520][[com.automatics.executor.Starter.main()] - Initializing application context partner-applicationContext.xml
[INFO][2024-08-28 18:43:50,791][[com.automatics.executor.Starter.main()] - Initializing application context applicationContext.xml
[INFO][2024-08-28 18:43:50,833][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.145:8080/AutomaticsProps/automatics.properties
[INFO][2024-08-28 18:43:50,865][[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-08-28 18:43:50,868][[com.automatics.executor.Starter.main()] -
***************** Automatics BUILD PARAMETERS *****************
[INFO][2024-08-28 18:43:50,875][[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-08-28 18:43:50,876][[com.automatics.executor.Starter.main()] - Shut Down Hook Attached.
[INFO][2024-08-28 18:43:50,879][[com.automatics.executor.Starter.main()] - Start Execution Test Suite
[INFO][2024-08-28 18:43:50,880][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-08-28 18:43:50,881][[com.automatics.executor.Starter.main()] - Cleaning the trace log directory
[INFO][2024-08-28 18:43:50,902][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.145:8080/AutomaticsProps/automatics.properties
[INFO][2024-08-28 18:43:50,906][[com.automatics.executor.Starter.main()] - >>>[INIT]: Validating config files
[INFO][2024-08-28 18:43:50,907][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.145:8080/AutomaticsProps/deviceProps.json
[INFO][2024-08-28 18:43:51,060][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.145:8080/AutomaticsProps/deviceProps.json
[INFO][2024-08-28 18:43:51,067][[com.automatics.executor.Starter.main()] - frameworkSupportedModels=Rpi-RDKB,Rpi-RDKV,
[INFO][2024-08-28 18:43:51,067][[com.automatics.executor.Starter.main()] - rdkvGWModels=
[INFO][2024-08-28 18:43:51,068][[com.automatics.executor.Starter.main()] - rdkvCLModels=Rpi-RDKV,
[INFO][2024-08-28 18:43:51,068][[com.automatics.executor.Starter.main()] - rdkbModels=Rpi-RDKB,
[INFO][2024-08-28 18:43:51,069][[com.automatics.executor.Starter.main()] - rdkcModels=
[INFO][2024-08-28 18:43:51,082][[com.automatics.executor.Starter.main()] - Reading implementation from core for deviceProvider
[INFO][2024-08-28 18:43:51,096][[com.automatics.executor.Starter.main()] - Is Account based test: false
[INFO][2024-08-28 18:43:51,098][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-08-28 18:43:51,536][[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-08-28 18:43:51,677][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.249","hostIp6Address":null,"clientIpAddress":"192.168.2.249","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.249","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.249","password":"root123","connectedClients":"192.168.2.81","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","connectionType":"SSH","nodePort":"22","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"GOOD","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.fasterxml.jackson.databind.util.ClassUtil (file:/root/.m2/repository/com/fasterxml/jackson/core/jackson-databind/2.4.1/jackson-databind-2.4.1.jar) to field java.time.format.DateTimeFormatter.locale
WARNING: Please consider reporting this to the maintainers of com.fasterxml.jackson.databind.util.ClassUtil
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
[INFO][2024-08-28 18:43:51,715][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-08-28 18:43:51,715][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model Rpi-RDKB
[INFO][2024-08-28 18:43:51,716][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model Rpi-RDKB
[INFO][2024-08-28 18:43:51,716][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model Rpi-RDKB to automatics model Rpi-RDKB
[INFO][2024-08-28 18:43:51,716][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device
[INFO][2024-08-28 18:43:51,720][[pool-2-thread-1] - INIT-{} Check if device already lockedD8:3A:DD:13:8C:13
[INFO][2024-08-28 18:43:51,732][[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-08-28 18:43:51,740][[pool-2-thread-1] - Response: {"allocationStatus":"AVAILABLE"}
[INFO][2024-08-28 18:43:51,742][[pool-2-thread-1] - DeviceConfig allocation status
[INFO][2024-08-28 18:43:51,743][[pool-2-thread-1] - [INIT LOG] : Locking device D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:43:51,744][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Locking device
[INFO][2024-08-28 18:43:51,751][[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-08-28 18:43:51,801][[pool-2-thread-1] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-08-28 18:43:51,804][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Lock success
[INFO][2024-08-28 18:43:51,804][[pool-2-thread-1] - Setting access mechanism for device D8:3A:DD:13:8C:13 SSH
[INFO][2024-08-28 18:43:51,804][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Setting access method SSH
[INFO][2024-08-28 18:43:51,804][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required
[INFO][2024-08-28 18:43:51,804][[pool-2-thread-1] - Setting accessibility check required to false for device D8:3A:DD:13:8C:13 from device config
[INFO][2024-08-28 18:43:51,804][[pool-2-thread-1] - Accessibility check required for device D8:3A:DD:13:8C:13 false
[INFO][2024-08-28 18:43:51,805][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required is false
[INFO][2024-08-28 18:43:51,805][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Assuming device is accessible
[INFO][2024-08-28 18:43:51,805][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Is device accessible true
[INFO][2024-08-28 18:43:51,805][[pool-2-thread-1] - [INIT LOG] : Wiring device.D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:43:51,806][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Performing provider wiring
[INFO][2024-08-28 18:43:51,806][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 PowerProvider wiring
[INFO][2024-08-28 18:43:51,806][[pool-2-thread-1] - Reading implementation from core for powerProvider
[INFO][2024-08-28 18:43:51,807][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 DeviceConfig Connection Based TraceProvider wiring
[INFO][2024-08-28 18:43:51,809][[pool-2-thread-1] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-08-28 18:43:51,811][[pool-2-thread-1] - Additional trace support : null
[INFO][2024-08-28 18:43:51,812][[pool-2-thread-1] - settopObj.getModel() = Rpi-RDKB
[INFO][2024-08-28 18:43:51,812][[pool-2-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue
[INFO][2024-08-28 18:43:51,815][[pool-2-thread-1] - Trace Log File location /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-08-28 18:43:51,815][[pool-2-thread-1] - Crash Analysis not enabled during trace monitoring
[INFO][2024-08-28 18:43:51,816][[pool-2-thread-1] - Serial based trace to be initialized: false
[INFO][2024-08-28 18:43:51,816][[pool-2-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-08-28 18:43:51,816][[pool-2-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:43:51,816][[pool-2-thread-1] - Dut Added to locked list D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:43:54,717][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads
[INFO][2024-08-28 18:43:54,717][[com.automatics.executor.Starter.main()] - Collecting dut info
[INFO][2024-08-28 18:43:54,718][[com.automatics.executor.Starter.main()] - Requesting data from http://192.168.2.59:8084/api/rack/service/getmasterconfig?configName=TEST_TYPE_MAP
[INFO][2024-08-28 18:43:54,737][[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-08-28 18:43:55,325][[com.automatics.executor.Starter.main()] - AutomaticsTapApi instance not available. Creating new instance
[INFO][2024-08-28 18:43:55,326][[com.automatics.executor.Starter.main()] - Creating new instance for AutomaticsTapApi
[INFO][2024-08-28 18:43:55,326][[com.automatics.executor.Starter.main()] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-08-28 18:43:55,417][[com.automatics.executor.Starter.main()] - >>>[BEFORE_SUITE]: Perform before suite initialization
[INFO][2024-08-28 18:43:55,418][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-08-28 18:43:55,418][[com.automatics.executor.Starter.main()] - Adding locked devices to dut object in AutomaticsTestBase
[INFO][2024-08-28 18:43:55,418][[com.automatics.executor.Starter.main()] - Locked Settops: 1
[INFO][2024-08-28 18:43:55,419][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Verifying if build loaded in device as expected
[INFO][2024-08-28 18:43:55,419][[pool-6-thread-1] - [BEFORE-SUITE:]Expected build in device is rdkb-generic-broadband-image_TDK_nightly-20240603-kirkstone_20240603030301
[INFO][2024-08-28 18:43:55,420][[pool-6-thread-1] - [BEFORE-SUITE:]Verifying if build changed before test
[INFO][2024-08-28 18:43:55,420][[pool-6-thread-1] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-08-28 18:43:55,421][[pool-6-thread-1] - [BEFORE-SUITE:]Build Change Status: NO_CHANGE
[INFO][2024-08-28 18:43:55,421][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Build in device is as expected
[INFO][2024-08-28 18:43:55,421][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Setting appropritate build appender based on executionMode
[INFO][2024-08-28 18:43:55,426][[pool-6-thread-1] - Skipping setting of execution mode in device as partner specific initialization is not configured.
[INFO][2024-08-28 18:43:55,427][[pool-6-thread-1] - INIT-D8:3A:DD:13:8C:13 Perform before suite initialization
[INFO][2024-08-28 18:43:55,427][[pool-6-thread-1] - Skipping partner specific before suite initialization as it is not configured.
[INFO][2024-08-28 18:43:55,427][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Starting device connection trace
[INFO][2024-08-28 18:43:55,427][[pool-6-thread-1] - Starting trace with command : tail -F -n 0 /rdklogs/logs/*
[INFO][2024-08-28 18:43:55,436][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Starting reading..D83ADD138C13settop_trace.log,
18:43:55,436 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
18:43:55,439 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-D83ADD138C13settop_trace.log]
18:43:55,440 |-INFO in ch.qos.logback.core.FileAppender[FILE-D83ADD138C13settop_trace.log] - File property is set to [target/settoptrace/D83ADD138C13settop_trace.log]
[INFO][2024-08-28 18:43:55,440][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Going to connect device for trace monitoring .....!
[INFO][2024-08-28 18:43:55,441][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP Address : 192.168.2.249
[INFO][2024-08-28 18:43:55,441][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP6 Address : null
[INFO][2024-08-28 18:43:55,441][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host MAC Address : D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:43:55,441][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside Non RDKV client device
[INFO][2024-08-28 18:43:55,441][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-08-28 18:43:55,445][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:43:55,445][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-08-28 18:43:55,451][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Reading server-config.xml from /var/lib/jenkins/workspace/testrepo/server-config.xml
[INFO][2024-08-28 18:43:55,470][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-08-28 18:43:55,726][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:43:55,726][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:43:55,726][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-08-28 18:43:55,741][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-08-28 18:43:55,888][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Inside sendTraceCommand
[INFO][2024-08-28 18:44:00,895][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:00,900][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Received response:
[INFO][2024-08-28 18:44:00,901][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connectiongateway iscom.automatics.providers.connection.SshConnection@6ffc0864
[INFO][2024-08-28 18:44:00,902][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - inside null != connectionGateway condition
[INFO][2024-08-28 18:44:00,902][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - gateWayDeviceInputStream iscom.jcraft.jsch.Channel$MyPipedInputStream@1a6216b6
[INFO][2024-08-28 18:44:00,904][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside else block
[INFO][2024-08-28 18:44:00,904][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-08-28 18:44:00,904][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Entered into readOutputFromChannel method
[INFO][2024-08-28 18:44:00,905][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Is RDKV Client trace false
[INFO][2024-08-28 18:44:00,905][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:00,905][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:00,924][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-08-28 18:44:01,085][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-08-28 18:44:01,086][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-08-28 18:44:01,086][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:01,086][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:01,099][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-08-28 18:44:01,261][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-08-28 18:44:01,261][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-08-28 18:44:01,261][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-08-28 18:44:01,266][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:01,391][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-08-28 18:44:01,392][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@652bd221
[INFO][2024-08-28 18:44:01,401][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-08-28 18:44:25,436][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-08-28 18:44:25,437][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-08-28 18:44:28,423][[com.automatics.executor.Starter.main()] - Starting testing on class: org.testng.TestRunner
[INFO][2024-08-28 18:44:28,456][[com.automatics.executor.Starter.main()] - Doing initialization for D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:44:28,473][[com.automatics.executor.Starter.main()] - Getting device account no. for D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:44:28,474][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-08-28 18:44:28,487][[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-08-28 18:44:28,502][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.249","hostIp6Address":null,"clientIpAddress":"192.168.2.249","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.249","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.249","connectedClients":"192.168.2.81","password":"root123","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","connectionType":"SSH","nodePort":"22","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"IN_USE","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
[INFO][2024-08-28 18:44:28,518][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-08-28 18:44:28,518][[com.automatics.executor.Starter.main()] - Device account 123456789 for device D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:44:28,518][[com.automatics.executor.Starter.main()] - Getting device account no. for D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:44:28,518][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-08-28 18:44:28,524][[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-08-28 18:44:28,536][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.249","hostIp6Address":null,"clientIpAddress":"192.168.2.249","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.249","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.249","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":"IN_USE","rackGroups":null,"features":null,"components":null,"wanMacAddress":""}],"errorMsg":null,"remarks":null}
[INFO][2024-08-28 18:44:28,549][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-08-28 18:44:28,549][[com.automatics.executor.Starter.main()] - Device account 123456789 for device D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:44:28,549][[com.automatics.executor.Starter.main()] - Locking connected devices
[INFO][2024-08-28 18:44:28,549][[com.automatics.executor.Starter.main()] - Getting devices for home account 123456789
[INFO][2024-08-28 18:44:28,556][[com.automatics.executor.Starter.main()] - Fetching account details for 123456789 Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/getAccountDetails
[INFO][2024-08-28 18:44:28,571][[com.automatics.executor.Starter.main()] - Response: {"id":"","name":"","accountNumber":"123456789","phoneNumber":"","address":"","homeAccountGroup":"","devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.249","hostIp6Address":null,"clientIpAddress":"192.168.2.249","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.249","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.249","connectedClients":"192.168.2.81","password":"root123","ethernetMacAddress":"D8:3A:DD:13:8C:13","wi-ficapability":"Dual band","connectionType":"SSH","nodePort":"22","username":"root"},"rackId":"","deviceType":"","rackName":"","slotName":"","slotNumber":"","settopGroupName":"","homeAccountName":"","homeAccountNumber":"123456789","homeAccountGroupName":"","rackServerHost":"","rackServerPort":0,"status":"IN_USE","rackGroups":null,"features":null,"components":null,"wanMacAddress":""},{"id":"3853","name":"","hardwareRevision":"","hostMacAddress":"2C:44:FD:18:F9:FE","hostIp4Address":"192.168.2.81","hostIp6Address":null,"clientIpAddress":"192.168.2.81","model":"WINDOWSVM","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"2C:44:FD:18:F9:FE","mtaMacAddress":"","mtaIpAddress":"","ecmMacAddress":"2C:44:FD:18:F9:FE","ecmIpAddress":"192.168.2.81","headend":"HE","gatewayMac":"","extraProperties":{"deviceIp":"192.168.2.81","password":"asdf@1234","ethernetMacAddress":"2C:44:FD:18:F9:FE","osType":"WINDOWS","devicePort":"22","wifiCapability":"Dual Band","connectionType":"Ethernet","nodePort":"4444","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":""}]}
[INFO][2024-08-28 18:44:28,583][[com.automatics.executor.Starter.main()] - Devices for home account 123456789 are [D8:3A:DD:13:8C:13, 2C:44:FD:18:F9:FE]
[INFO][2024-08-28 18:44:28,584][[com.automatics.executor.Starter.main()] - Obtained all connected device macs for the account
[INFO][2024-08-28 18:44:28,584][[com.automatics.executor.Starter.main()] - INIT-2C:44:FD:18:F9:FE Get device details
[INFO][2024-08-28 18:44:28,590][[com.automatics.executor.Starter.main()] - Fetching device details for 2C:44:FD:18:F9:FE Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-08-28 18:44:28,601][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3853","name":"","hardwareRevision":"","hostMacAddress":"2C:44:FD:18:F9:FE","hostIp4Address":"192.168.2.81","hostIp6Address":null,"clientIpAddress":"192.168.2.81","model":"WINDOWSVM","manufacturer":"","serialNumber":"","unitAddress":null,"remoteType":"","estbMacAddress":"2C:44:FD:18:F9:FE","mtaMacAddress":"","mtaIpAddress":"","ecmMacAddress":"2C:44:FD:18:F9:FE","ecmIpAddress":"192.168.2.81","headend":"HE","gatewayMac":"","extraProperties":{"deviceIp":"192.168.2.81","password":"asdf@1234","ethernetMacAddress":"2C:44:FD:18:F9:FE","osType":"WINDOWS","devicePort":"22","wifiCapability":"Dual Band","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-08-28 18:44:28,612][[com.automatics.executor.Starter.main()] - INIT-2C:44:FD:18:F9:FE Obtained device details
[INFO][2024-08-28 18:44:28,613][[com.automatics.executor.Starter.main()] - >>>[INIT]: No device config mapped for rack model WINDOWSVM. Proceeding with rack model
[INFO][2024-08-28 18:44:28,613][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device
[INFO][2024-08-28 18:44:28,613][[pool-15-thread-1] - INIT-{} Check if device already locked2C:44:FD:18:F9:FE
[INFO][2024-08-28 18:44:28,622][[pool-15-thread-1] - Fetching lock status for device 2C:44:FD:18:F9:FE Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/device/allocationStatus
[INFO][2024-08-28 18:44:28,629][[pool-15-thread-1] - Response: {"allocationStatus":"AVAILABLE"}
[INFO][2024-08-28 18:44:28,630][[pool-15-thread-1] - DeviceConfig allocation status
[INFO][2024-08-28 18:44:28,630][[pool-15-thread-1] - [INIT LOG] : Locking device 2C:44:FD:18:F9:FE
[INFO][2024-08-28 18:44:28,631][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Locking device
[INFO][2024-08-28 18:44:28,637][[pool-15-thread-1] - Locking device 2C:44:FD:18:F9:FE Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/device/lock
[INFO][2024-08-28 18:44:28,684][[pool-15-thread-1] - Response: {"status":"SUCCESS","mac":"2C:44:FD:18:F9:FE"}
[INFO][2024-08-28 18:44:28,685][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Lock success
[INFO][2024-08-28 18:44:28,686][[pool-15-thread-1] - DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-08-28 18:44:28,686][[pool-15-thread-1] - Setting default SSH access mechanism for device null
[INFO][2024-08-28 18:44:28,686][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Setting access method SSH
[INFO][2024-08-28 18:44:28,686][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Checking if accessibility check required
[INFO][2024-08-28 18:44:28,686][[pool-15-thread-1] - Accessibility check required for device 2C:44:FD:18:F9:FE false
[INFO][2024-08-28 18:44:28,686][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Checking if accessibility check required is false
[INFO][2024-08-28 18:44:28,687][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Assuming device is accessible
[INFO][2024-08-28 18:44:28,687][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Is device accessible true
[INFO][2024-08-28 18:44:28,687][[pool-15-thread-1] - [INIT LOG] : Wiring device.2C:44:FD:18:F9:FE
[INFO][2024-08-28 18:44:28,687][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE Performing provider wiring
[INFO][2024-08-28 18:44:28,687][[pool-15-thread-1] - INIT-2C:44:FD:18:F9:FE PowerProvider wiring
[INFO][2024-08-28 18:44:28,687][[pool-15-thread-1] - Reading implementation from core for powerProvider
[INFO][2024-08-28 18:44:28,687][[pool-15-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-08-28 18:44:28,687][[pool-15-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:44:28,687][[pool-15-thread-1] - Dut Added to locked list 2C:44:FD:18:F9:FE
[INFO][2024-08-28 18:44:31,613][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads
[INFO][2024-08-28 18:44:31,614][[com.automatics.executor.Starter.main()] - Starting trace from provider for connected clients if not already started
[INFO][2024-08-28 18:44:31,614][[com.automatics.executor.Starter.main()] - =========================================================================
[INFO][2024-08-28 18:44:31,614][[com.automatics.executor.Starter.main()] - DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-08-28 18:44:31,614][[com.automatics.executor.Starter.main()] - Trace not applicable for Non RDK Devices - 2C:44:FD:18:F9:FE
[INFO][2024-08-28 18:44:31,621][[com.automatics.executor.Starter.main()] - NUMBER OF CONNECTED CLIENTS ASSOCIATED WITH DEVICE D8:3A:DD:13:8C:13 IS 1
[INFO][2024-08-28 18:44:31,621][[com.automatics.executor.Starter.main()] - CONNECTED CLIENTS ASSOCIATED WITH DEVICE D8:3A:DD:13:8C:13 IS 2C:44:FD:18:F9:FE
[INFO][2024-08-28 18:44:31,633][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Perform before method initialization D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:44:31,634][[TestNG-PoolService-0] - Setting dattime in patter yyyy-MM-dd HH:mm:ss
[INFO][2024-08-28 18:44:31,638][[TestNG-PoolService-0] - startTime 2024-08-28 13:14:31
[INFO][2024-08-28 18:44:31,638][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Sending test exection start time to Automatics D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:44:31,640][[TestNG-PoolService-0] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-08-28 18:44:31,641][[TestNG-PoolService-0] - {"jobId":456,"automationId":"TC-RDKB-XDNS_DNSSEC-1001","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":1724850871639,"endDateTimeEST":0}
[INFO][2024-08-28 18:44:31,727][[TestNG-PoolService-0] - Capture execution time - Response : HTTP/1.1 200
[INFO][2024-08-28 18:44:31,728][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Skipping partner specific before method initialization as it is not configured.
18:44:31,728 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
18:44:31,728 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-Rpi-RDKB-D83ADD138C13]
18:44:31,729 |-INFO in ch.qos.logback.core.FileAppender[FILE-Rpi-RDKB-D83ADD138C13] - File property is set to [target/logs/Rpi-RDKB-D83ADD138C13.log]
[INFO][2024-08-28 18:44:31,730][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-08-28 18:44:31,730][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Get the locked status: false
[INFO][2024-08-28 18:44:31,730][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Get the locked status: false
[INFO][2024-08-28 18:44:31,730][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-08-28 18:44:31,730][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-08-28 18:44:31,740][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - STARTED - testDnsSecWhenXDNSEnabled - com.automatics.device.Device@652bd221
[INFO][2024-08-28 18:44:31,741][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Getting MDC = D83ADD138C13settop_trace.log
[INFO][2024-08-28 18:44:31,742][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Getting MDC = D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:44:31,750][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - #######################################################################################
[INFO][2024-08-28 18:44:31,750][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STARTING TEST CASE: TC-RDKB-XDNS_DNSSEC-1001
[INFO][2024-08-28 18:44:31,750][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TEST DESCRIPTION: Verify DNSSec flag when XDNS is enabled
[INFO][2024-08-28 18:44:31,750][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TEST STEPS :
[INFO][2024-08-28 18:44:31,750][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 1: Verify dnsmasq process id
[INFO][2024-08-28 18:44:31,750][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 2 : Verify ethernet connected client
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 1. Verify setting primary and secondary ipv4 and ipv6 XDNS servers
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 2. Enable XDNS using webpa command
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 3. Verify dnsmasq process restarted with new pid
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 4. Verify XDNS server values in /etc/resolv.conf
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 5. Verify ad flag in dig output of ethernet client
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 6. Disable XDNS using webpa command
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 7. Verify dnsmasq process restarted with new pid
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 9. Verify XDNS server values not present in /etc/resolv.conf
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - POST-CONDITION : Disable XDNS using webpa command
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - #######################################################################################
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ################### STARTING PRE-CONFIGURATIONS ###################
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION STEPS
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 1 : DESCRIPTION : Verify dnsmasq process id
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 1 : ACTION : Execute command: pidof dnsmasq
[INFO][2024-08-28 18:44:31,751][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 1 : EXPECTED : Obtained pid for dnsmasq process
[INFO][2024-08-28 18:44:31,752][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-08-28 18:44:31,752][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.249
[INFO][2024-08-28 18:44:31,752][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:31,752][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:31,764][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:31,887][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: pidof "dnsmasq"
[INFO][2024-08-28 18:44:31,887][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:32,010][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
300309
<=============================================================>
[INFO][2024-08-28 18:44:32,010][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Received response: 300309
[INFO][2024-08-28 18:44:32,010][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-08-28 18:44:32,011][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PID of 'dnsmasq' is: 300309
[INFO][2024-08-28 18:44:32,011][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 1 : ACTUAL : Obtained pid for dnsmasq process
[INFO][2024-08-28 18:44:32,011][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:44:32,011][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 2 : DESCRIPTION : Verify ethernet connected client
[INFO][2024-08-28 18:44:32,011][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 2 : ACTION : Get ethernet connected linux client from list of connected devices in CATS inventory
[INFO][2024-08-28 18:44:32,011][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 2 : EXPECTED : Obtained ethernet connected linux client
[INFO][2024-08-28 18:44:32,021][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - DUTINFO DEVICE LIST = [com.automatics.device.Device@12992ce]
[INFO][2024-08-28 18:44:32,021][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - # OF CONNECTED CLIENTS AVAILABLE = 1
[INFO][2024-08-28 18:44:32,021][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - DeviceConfig [2C:44:FD:18:F9:FE] is identified as NON RDK DeviceConfig.
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - CLIENT DEVICE CONNECTION TYPE: Ethernet
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - CLIENT DEVICE OS TYPE: WINDOWS
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - IS ETHERNET CLIENT AVAILABLE: true
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PRE-CONDITION 2 : ACTUAL : Obtained ethernet connected linux client
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 1: DESCRIPTION : Verify setting primary and secondary ipv4 and ipv6 XDNS servers
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 1: ACTION : Execute webpa or dmcli command for following sets:
Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4 string74.121.125.53
Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6 string 2620:104:a00b::53
Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4 string 74.121.125.54
Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6 string 2620:104:a00b::54
Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag string high_xdns
Verify above values with get after performing set operation.
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 1: EXPECTED : Successfully set ipv4 and ipv6 XDNS servers
[INFO][2024-08-28 18:44:32,022][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:44:32,043][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-08-28 18:44:32,044][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-08-28 18:44:32,044][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:32,044][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:32,045][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4 string 74.121.125.53
[INFO][2024-08-28 18:44:32,045][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:32,045][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:32,045][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:32,057][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:32,180][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4 string 74.121.125.53
[INFO][2024-08-28 18:44:32,180][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:32,305][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
Execution succeed.
<=============================================================>
[INFO][2024-08-28 18:44:32,306][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:32,306][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
Execution succeed.
[INFO][2024-08-28 18:44:32,306][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
Execution succeed.
[INFO][2024-08-28 18:44:32,307][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : {Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4=Success}
[INFO][2024-08-28 18:44:32,307][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-08-28 18:44:32,308][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-08-28 18:44:32,308][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-08-28 18:44:32,320][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WEBPA PARAM - Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4 SET WITH VALUE - 74.121.125.53 IS SUCCESSFUL: true
[INFO][2024-08-28 18:44:32,321][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of setting WebPaParameter values: true
[INFO][2024-08-28 18:44:32,321][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:32,321][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:32,321][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-08-28 18:44:32,321][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-08-28 18:44:32,321][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to execute commands
[INFO][2024-08-28 18:44:32,322][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:32,322][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:32,322][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:32,338][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:32,478][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
[INFO][2024-08-28 18:44:32,478][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:32,601][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
type: string, value: 74.121.125.53
<=============================================================>
[INFO][2024-08-28 18:44:32,601][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:32,601][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
type: string, value: 74.121.125.53
[INFO][2024-08-28 18:44:32,601][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4
type: string, value: 74.121.125.53
[INFO][2024-08-28 18:44:32,602][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli param value : 74.121.125.53
[INFO][2024-08-28 18:44:32,603][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4, protocolSpecificParamName=Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv4, value=74.121.125.53, datatype=null, statusCode=0]
[INFO][2024-08-28 18:44:32,603][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - valueToBePassed: 74.121.125.53
[INFO][2024-08-28 18:44:32,603][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - response: 74.121.125.53
[INFO][2024-08-28 18:44:32,609][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Pattern Found = 74.121.125.53
[INFO][2024-08-28 18:44:32,609][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 74.121.125.53
[INFO][2024-08-28 18:44:32,610][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - is patter found for 74.121.125.53 in target string = true
[INFO][2024-08-28 18:44:32,610][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of getting WebPaParameter values: true
[INFO][2024-08-28 18:44:32,611][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-08-28 18:44:32,611][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-08-28 18:44:32,611][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:32,611][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:32,611][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6 string 2620:104:a00b::53
[INFO][2024-08-28 18:44:32,611][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:32,611][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:32,611][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:32,625][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:32,760][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6 string 2620:104:a00b::53
[INFO][2024-08-28 18:44:32,760][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:32,883][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
Execution succeed.
<=============================================================>
[INFO][2024-08-28 18:44:32,883][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:32,883][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
Execution succeed.
[INFO][2024-08-28 18:44:32,883][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
Execution succeed.
[INFO][2024-08-28 18:44:32,884][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : {Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6=Success}
[INFO][2024-08-28 18:44:32,884][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-08-28 18:44:32,884][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-08-28 18:44:32,884][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-08-28 18:44:32,884][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WEBPA PARAM - Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6 SET WITH VALUE - 2620:104:a00b::53 IS SUCCESSFUL: true
[INFO][2024-08-28 18:44:32,885][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of setting WebPaParameter values: true
[INFO][2024-08-28 18:44:32,885][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:32,885][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:32,885][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-08-28 18:44:32,885][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-08-28 18:44:32,885][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to execute commands
[INFO][2024-08-28 18:44:32,885][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:32,885][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:32,885][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:32,899][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:33,028][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
[INFO][2024-08-28 18:44:33,028][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:33,151][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
type: string, value: 2620:104:a00b::53
<=============================================================>
[INFO][2024-08-28 18:44:33,151][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:33,151][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
type: string, value: 2620:104:a00b::53
[INFO][2024-08-28 18:44:33,151][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6
type: string, value: 2620:104:a00b::53
[INFO][2024-08-28 18:44:33,152][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli param value : 2620:104:a00b::53
[INFO][2024-08-28 18:44:33,152][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6, protocolSpecificParamName=Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceDnsIPv6, value=2620:104:a00b::53, datatype=null, statusCode=0]
[INFO][2024-08-28 18:44:33,152][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - valueToBePassed: 2620:104:a00b::53
[INFO][2024-08-28 18:44:33,152][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - response: 2620:104:a00b::53
[INFO][2024-08-28 18:44:33,153][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Pattern Found = 2620:104:a00b::53
[INFO][2024-08-28 18:44:33,153][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 2620:104:a00b::53
[INFO][2024-08-28 18:44:33,153][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - is patter found for 2620:104:a00b::53 in target string = true
[INFO][2024-08-28 18:44:33,153][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of getting WebPaParameter values: true
[INFO][2024-08-28 18:44:33,153][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-08-28 18:44:33,154][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-08-28 18:44:33,154][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:33,154][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:33,154][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4 string 74.121.125.54
[INFO][2024-08-28 18:44:33,154][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:33,154][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:33,154][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:33,168][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:33,321][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4 string 74.121.125.54
[INFO][2024-08-28 18:44:33,321][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:33,444][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
Execution succeed.
<=============================================================>
[INFO][2024-08-28 18:44:33,444][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:33,444][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
Execution succeed.
[INFO][2024-08-28 18:44:33,444][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
Execution succeed.
[INFO][2024-08-28 18:44:33,444][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : {Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4=Success}
[INFO][2024-08-28 18:44:33,444][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-08-28 18:44:33,445][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-08-28 18:44:33,445][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-08-28 18:44:33,445][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WEBPA PARAM - Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4 SET WITH VALUE - 74.121.125.54 IS SUCCESSFUL: true
[INFO][2024-08-28 18:44:33,445][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of setting WebPaParameter values: true
[INFO][2024-08-28 18:44:33,445][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:33,445][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:33,445][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-08-28 18:44:33,445][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-08-28 18:44:33,446][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to execute commands
[INFO][2024-08-28 18:44:33,446][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:33,446][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:33,446][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:33,457][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:33,595][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
[INFO][2024-08-28 18:44:33,596][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:33,719][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
type: string, value: 74.121.125.54
<=============================================================>
[INFO][2024-08-28 18:44:33,719][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:33,719][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
type: string, value: 74.121.125.54
[INFO][2024-08-28 18:44:33,719][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4
type: string, value: 74.121.125.54
[INFO][2024-08-28 18:44:33,720][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli param value : 74.121.125.54
[INFO][2024-08-28 18:44:33,720][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4, protocolSpecificParamName=Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv4, value=74.121.125.54, datatype=null, statusCode=0]
[INFO][2024-08-28 18:44:33,720][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - valueToBePassed: 74.121.125.54
[INFO][2024-08-28 18:44:33,720][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - response: 74.121.125.54
[INFO][2024-08-28 18:44:33,721][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Pattern Found = 74.121.125.54
[INFO][2024-08-28 18:44:33,721][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 74.121.125.54
[INFO][2024-08-28 18:44:33,721][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - is patter found for 74.121.125.54 in target string = true
[INFO][2024-08-28 18:44:33,721][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of getting WebPaParameter values: true
[INFO][2024-08-28 18:44:33,721][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-08-28 18:44:33,722][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-08-28 18:44:33,722][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:33,722][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:33,722][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6 string 2620:104:a00b::54
[INFO][2024-08-28 18:44:33,722][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:33,722][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:33,722][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:33,736][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:33,860][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6 string 2620:104:a00b::54
[INFO][2024-08-28 18:44:33,860][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:33,983][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
Execution succeed.
<=============================================================>
[INFO][2024-08-28 18:44:33,983][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:33,983][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
Execution succeed.
[INFO][2024-08-28 18:44:33,984][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
Execution succeed.
[INFO][2024-08-28 18:44:33,984][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : {Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6=Success}
[INFO][2024-08-28 18:44:33,984][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-08-28 18:44:33,984][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-08-28 18:44:33,984][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-08-28 18:44:33,984][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WEBPA PARAM - Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6 SET WITH VALUE - 2620:104:a00b::54 IS SUCCESSFUL: true
[INFO][2024-08-28 18:44:33,985][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of setting WebPaParameter values: true
[INFO][2024-08-28 18:44:33,985][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:33,985][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:33,985][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-08-28 18:44:33,985][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-08-28 18:44:33,985][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to execute commands
[INFO][2024-08-28 18:44:33,985][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:33,985][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:33,985][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:33,998][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:34,123][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
[INFO][2024-08-28 18:44:34,123][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:34,245][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
type: string, value: 2620:104:a00b::54
<=============================================================>
[INFO][2024-08-28 18:44:34,246][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:34,246][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
type: string, value: 2620:104:a00b::54
[INFO][2024-08-28 18:44:34,246][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6
type: string, value: 2620:104:a00b::54
[INFO][2024-08-28 18:44:34,246][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli param value : 2620:104:a00b::54
[INFO][2024-08-28 18:44:34,246][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6, protocolSpecificParamName=Device.X_RDKCENTRAL-COM_XDNS.DefaultSecondaryDeviceDnsIPv6, value=2620:104:a00b::54, datatype=null, statusCode=0]
[INFO][2024-08-28 18:44:34,246][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - valueToBePassed: 2620:104:a00b::54
[INFO][2024-08-28 18:44:34,247][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - response: 2620:104:a00b::54
[INFO][2024-08-28 18:44:34,247][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Pattern Found = 2620:104:a00b::54
[INFO][2024-08-28 18:44:34,247][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - 2620:104:a00b::54
[INFO][2024-08-28 18:44:34,247][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - is patter found for 2620:104:a00b::54 in target string = true
[INFO][2024-08-28 18:44:34,247][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of getting WebPaParameter values: true
[INFO][2024-08-28 18:44:34,247][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WebPa data type: STRING
[INFO][2024-08-28 18:44:34,248][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-08-28 18:44:34,248][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:34,248][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:34,248][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag string high_xdns
[INFO][2024-08-28 18:44:34,248][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:34,248][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:34,248][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:34,261][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:34,379][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT setv Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag string high_xdns
[INFO][2024-08-28 18:44:34,379][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:34,502][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
Execution succeed.
<=============================================================>
[INFO][2024-08-28 18:44:34,502][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:34,502][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
Execution succeed.
[INFO][2024-08-28 18:44:34,502][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
Execution succeed.
[INFO][2024-08-28 18:44:34,503][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : {Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag=Success}
[INFO][2024-08-28 18:44:34,503][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-08-28 18:44:34,503][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-08-28 18:44:34,503][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-08-28 18:44:34,503][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WEBPA PARAM - Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag SET WITH VALUE - high_xdns IS SUCCESSFUL: true
[INFO][2024-08-28 18:44:34,503][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of setting WebPaParameter values: true
[INFO][2024-08-28 18:44:34,504][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:34,504][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:34,504][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-08-28 18:44:34,504][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-08-28 18:44:34,504][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to execute commands
[INFO][2024-08-28 18:44:34,504][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:34,504][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:34,504][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:34,516][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:34,640][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
[INFO][2024-08-28 18:44:34,699][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:34,829][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
type: string, value: high_xdns
<=============================================================>
[INFO][2024-08-28 18:44:34,829][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:34,829][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
type: string, value: high_xdns
[INFO][2024-08-28 18:44:34,829][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
Execution succeed.
Parameter 1 name: Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag
type: string, value: high_xdns
[INFO][2024-08-28 18:44:34,829][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli param value : high_xdns
[INFO][2024-08-28 18:44:34,830][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag, protocolSpecificParamName=Device.X_RDKCENTRAL-COM_XDNS.DefaultDeviceTag, value=high_xdns, datatype=null, statusCode=0]
[INFO][2024-08-28 18:44:34,830][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - valueToBePassed: high_xdns
[INFO][2024-08-28 18:44:34,830][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - response: high_xdns
[INFO][2024-08-28 18:44:34,830][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Pattern Found = high_xdns
[INFO][2024-08-28 18:44:34,830][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - high_xdns
[INFO][2024-08-28 18:44:34,830][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - is patter found for high_xdns in target string = true
[INFO][2024-08-28 18:44:34,830][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of getting WebPaParameter values: true
[INFO][2024-08-28 18:44:34,830][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 1: ACTUAL : Successfully set ipv4 and ipv6 XDNS servers
[INFO][2024-08-28 18:44:34,830][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:44:34,831][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-XDNS_DNSSEC-101] [step Number : s1][ Execution status : PASS] [Error Message : ]
[INFO][2024-08-28 18:44:34,833][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-08-28 18:44:34,834][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Fetching device property: FIRMWARE_VERSION
[INFO][2024-08-28 18:44:34,864][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:44:34,874][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:44:34,875][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Getting value for property: FIRMWARE_VERSION
[INFO][2024-08-28 18:44:34,875][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Value: RPI-RDKB
[INFO][2024-08-28 18:44:34,875][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Device property name: FIRMWARE_VERSION value obtained: RPI-RDKB
[INFO][2024-08-28 18:44:34,880][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-XDNS_DNSSEC-101","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-XDNS_DNSSEC-1001"}
[ERROR][2024-08-28 18:44:34,893][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:44:34,893][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 2: DESCRIPTION : Enable XDNS using webpa command
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 2: ACTION : Execute webpa or dmcli command to set Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS bool true
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 2: EXPECTED : Successfully enabled XDNS feature
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WebPa data type: BOOLEAN
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS bool true
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:34,894][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:34,905][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:35,065][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT setv Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS bool true
[INFO][2024-08-28 18:44:35,065][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:35,188][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
<=============================================================>
[INFO][2024-08-28 18:44:35,188][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:35,189][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
[INFO][2024-08-28 18:44:35,189][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
[INFO][2024-08-28 18:44:35,189][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : {Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS=Success}
[INFO][2024-08-28 18:44:35,189][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-08-28 18:44:35,190][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-08-28 18:44:35,190][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-08-28 18:44:35,190][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WEBPA PARAM - Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS SET WITH VALUE - true IS SUCCESSFUL: true
[INFO][2024-08-28 18:44:35,190][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of setting WebPaParameter values: true
[INFO][2024-08-28 18:44:35,190][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:44:35,190][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:44:35,190][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-08-28 18:44:35,191][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-08-28 18:44:35,191][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to execute commands
[INFO][2024-08-28 18:44:35,191][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:35,191][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:44:35,191][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:44:35,206][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:44:35,316][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
[INFO][2024-08-28 18:44:35,317][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:44:35,439][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
type: bool, value: true
<=============================================================>
[INFO][2024-08-28 18:44:35,439][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:44:35,440][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
type: bool, value: true
[INFO][2024-08-28 18:44:35,440][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
type: bool, value: true
[INFO][2024-08-28 18:44:35,440][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli param value : true
[INFO][2024-08-28 18:44:35,440][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS, protocolSpecificParamName=Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS, value=true, datatype=null, statusCode=0]
[INFO][2024-08-28 18:44:35,440][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - valueToBePassed: true
[INFO][2024-08-28 18:44:35,441][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - response: true
[INFO][2024-08-28 18:44:35,441][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Pattern Found = true
[INFO][2024-08-28 18:44:35,441][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - true
[INFO][2024-08-28 18:44:35,441][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - is patter found for true in target string = true
[INFO][2024-08-28 18:44:35,441][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of getting WebPaParameter values: true
[INFO][2024-08-28 18:44:35,441][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 2: ACTUAL : Successfully enabled XDNS feature
[INFO][2024-08-28 18:44:35,441][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:44:35,441][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-XDNS_DNSSEC-101] [step Number : s2][ Execution status : PASS] [Error Message : ]
[INFO][2024-08-28 18:44:35,441][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-08-28 18:44:35,443][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-XDNS_DNSSEC-101","stepNumber":"s2","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":true,"partnerName":"","automationId":"TC-RDKB-XDNS_DNSSEC-1001"}
[ERROR][2024-08-28 18:44:35,456][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:44:35,456][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-08-28 18:44:35,456][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:44:35,456][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 3: DESCRIPTION : Verify dnsmasq process restarted with new pid
[INFO][2024-08-28 18:44:35,456][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 3: ACTION : Execute command:pidof dnsmasq Compare with value from pre condition
[INFO][2024-08-28 18:44:35,456][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 3: EXPECTED : Dnsmasq process is restarted
[INFO][2024-08-28 18:44:35,456][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:01,401][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-08-28 18:45:01,401][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:45:01,402][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-08-28 18:45:01,415][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-08-28 18:45:01,543][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-08-28 18:45:01,543][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-08-28 18:45:01,543][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:45:01,544][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-08-28 18:45:01,555][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-08-28 18:45:01,699][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-08-28 18:45:01,700][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-08-28 18:45:01,700][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-08-28 18:45:01,700][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-08-28 18:45:01,822][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-08-28 18:45:01,822][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@652bd221
[INFO][2024-08-28 18:45:01,823][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-08-28 18:45:35,457][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-08-28 18:45:35,457][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.249
[INFO][2024-08-28 18:45:35,457][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:45:35,457][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:45:35,471][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:45:35,603][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: pidof "dnsmasq"
[INFO][2024-08-28 18:45:35,603][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:45:35,726][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
305135
<=============================================================>
[INFO][2024-08-28 18:45:35,726][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Received response: 305135
[INFO][2024-08-28 18:45:35,726][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-08-28 18:45:35,726][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PID of 'dnsmasq' is: 305135
[INFO][2024-08-28 18:45:35,726][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 3: ACTUAL : Dnsmasq process is restarted
[INFO][2024-08-28 18:45:35,726][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:35,727][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-XDNS_DNSSEC-101] [step Number : s3][ Execution status : PASS] [Error Message : ]
[INFO][2024-08-28 18:45:35,727][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-08-28 18:45:35,728][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-XDNS_DNSSEC-101","stepNumber":"s3","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-XDNS_DNSSEC-1001"}
[ERROR][2024-08-28 18:45:35,741][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:45:35,741][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-08-28 18:45:35,741][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:35,741][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 4: DESCRIPTION : Verify XDNS server values in /etc/resolv.conf
[INFO][2024-08-28 18:45:35,741][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 4: ACTION : Execute command:cat /etc/resolv.conf
[INFO][2024-08-28 18:45:35,741][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 4: EXPECTED : Resolv.conf has updated XDNS values
[INFO][2024-08-28 18:45:35,741][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:35,741][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-08-28 18:45:35,742][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.249
[INFO][2024-08-28 18:45:35,742][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:45:35,742][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:45:35,754][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:45:35,879][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: cat /etc/resolv.conf
[INFO][2024-08-28 18:45:35,880][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:45:36,002][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
nameserver 208.67.220.220
nameserver 208.67.222.222
dnsoverride 00:00:00:00:00:00 74.121.125.53 2620:104:a00b::53 high_xdns
dnsoverride 00:00:00:00:00:00 74.121.125.54 2620:104:a00b::54 high_xdns
<=============================================================>
[INFO][2024-08-28 18:45:36,002][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Received response: nameserver 208.67.220.220
nameserver 208.67.222.222
dnsoverride 00:00:00:00:00:00 74.121.125.53 2620:104:a00b::53 high_xdns
dnsoverride 00:00:00:00:00:00 74.121.125.54 2620:104:a00b::54 high_xdns
[INFO][2024-08-28 18:45:36,002][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-08-28 18:45:36,003][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 4: ACTUAL : Resolv.conf has updated XDNS values
[INFO][2024-08-28 18:45:36,003][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:36,003][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-XDNS_DNSSEC-101] [step Number : s4][ Execution status : PASS] [Error Message : ]
[INFO][2024-08-28 18:45:36,003][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-08-28 18:45:36,004][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-XDNS_DNSSEC-101","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-XDNS_DNSSEC-1001"}
[ERROR][2024-08-28 18:45:36,017][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:45:36,017][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-08-28 18:45:36,018][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:36,018][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 5: DESCRIPTION : Verify ad flag in dig output of ethernet client
[INFO][2024-08-28 18:45:36,018][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 5: ACTION : Execute command on ethernet client:dig ipfire.org +dnssec | grep flags
[INFO][2024-08-28 18:45:36,018][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 5: EXPECTED : Ad flag is present in the output
[INFO][2024-08-28 18:45:36,018][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
device Model :Rpi-RDKB
[INFO][2024-08-28 18:45:36,019][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Skipping the step as device detected is RPI
[INFO][2024-08-28 18:45:36,019][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:36,020][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-XDNS_DNSSEC-101] [step Number : s5][ Execution status : PASS] [Error Message : ]
[INFO][2024-08-28 18:45:36,020][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-08-28 18:45:36,021][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-XDNS_DNSSEC-101","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-XDNS_DNSSEC-1001"}
[ERROR][2024-08-28 18:45:36,031][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:45:36,031][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-08-28 18:45:36,032][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:36,032][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 6: DESCRIPTION : Disable XDNS using webpa command
[INFO][2024-08-28 18:45:36,032][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 6: ACTION : Execute webpa or dmcli command to set Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS bool false
[INFO][2024-08-28 18:45:36,032][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 6: EXPECTED : Successfully disabled XDNS feature
[INFO][2024-08-28 18:45:36,032][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:36,032][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WebPa data type: BOOLEAN
[INFO][2024-08-28 18:45:36,032][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 parametrs status code added 0
[INFO][2024-08-28 18:45:36,032][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:45:36,032][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:45:36,033][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli Command to be executed: dmcli eRT setv Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS bool false
[INFO][2024-08-28 18:45:36,033][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:45:36,033][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:45:36,033][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:45:36,045][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:45:36,185][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT setv Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS bool false
[INFO][2024-08-28 18:45:36,186][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:45:36,308][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
<=============================================================>
[INFO][2024-08-28 18:45:36,308][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:45:36,309][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
[INFO][2024-08-28 18:45:36,309][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
[INFO][2024-08-28 18:45:36,309][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : {Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS=Success}
[INFO][2024-08-28 18:45:36,309][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ENDING METHOD: setWebPaParameterValues : success
[INFO][2024-08-28 18:45:36,310][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE CODE: 0
[INFO][2024-08-28 18:45:36,310][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - RESPONSE MESSAGE: success
[INFO][2024-08-28 18:45:36,310][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WEBPA PARAM - Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS SET WITH VALUE - false IS SUCCESSFUL: true
[INFO][2024-08-28 18:45:36,310][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of setting WebPaParameter values: true
[INFO][2024-08-28 18:45:36,310][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:45:36,310][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:45:36,310][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-08-28 18:45:36,310][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-08-28 18:45:36,311][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to execute commands
[INFO][2024-08-28 18:45:36,311][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:45:36,311][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:45:36,311][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:45:36,324][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:45:36,447][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
[INFO][2024-08-28 18:45:36,447][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:45:36,580][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
type: bool, value: false
<=============================================================>
[INFO][2024-08-28 18:45:36,580][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:45:36,580][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
type: bool, value: false
[INFO][2024-08-28 18:45:36,580][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
type: bool, value: false
[INFO][2024-08-28 18:45:36,581][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli param value : false
[INFO][2024-08-28 18:45:36,581][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS, protocolSpecificParamName=Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS, value=false, datatype=null, statusCode=0]
[INFO][2024-08-28 18:45:36,581][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - valueToBePassed: false
[INFO][2024-08-28 18:45:36,581][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - response: false
[INFO][2024-08-28 18:45:36,581][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Pattern Found = false
[INFO][2024-08-28 18:45:36,582][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - false
[INFO][2024-08-28 18:45:36,582][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - is patter found for false in target string = true
[INFO][2024-08-28 18:45:36,582][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Status of getting WebPaParameter values: true
[INFO][2024-08-28 18:45:36,582][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 6: ACTUAL : Successfully disabled XDNS feature
[INFO][2024-08-28 18:45:36,582][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:36,582][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-XDNS_DNSSEC-101] [step Number : s6][ Execution status : PASS] [Error Message : ]
[INFO][2024-08-28 18:45:36,582][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-08-28 18:45:36,583][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-XDNS_DNSSEC-101","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-XDNS_DNSSEC-1001"}
[ERROR][2024-08-28 18:45:36,596][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:45:36,596][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-08-28 18:45:36,596][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:45:36,597][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 7: DESCRIPTION : Verify dnsmasq process restarted with new pid
[INFO][2024-08-28 18:45:36,597][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 7: ACTION : Execute command:pidof dnsmasq Compare with value from step 3
[INFO][2024-08-28 18:45:36,597][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 7: EXPECTED : Dnsmasq process is restarted
[INFO][2024-08-28 18:45:36,597][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:46:01,823][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-08-28 18:46:01,824][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:46:01,824][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-08-28 18:46:01,837][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-08-28 18:46:01,966][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-08-28 18:46:01,966][[PollingThread_26_(D8:3A:DD:13:8C:13)] - getConnection method invoked
[INFO][2024-08-28 18:46:01,966][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:46:01,966][[PollingThread_26_(D8:3A:DD:13:8C:13)] - SSh connection attempet : 1
[INFO][2024-08-28 18:46:01,976][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-08-28 18:46:02,113][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-08-28 18:46:02,113][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not nul in execute:
[INFO][2024-08-28 18:46:02,113][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-08-28 18:46:02,113][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive:
[INFO][2024-08-28 18:46:02,236][[PollingThread_26_(D8:3A:DD:13:8C:13)] -
<=========================== RESPONSE =======================>
CONNECTION_IS_ALIVE
<=============================================================>
[INFO][2024-08-28 18:46:02,236][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@652bd221
[INFO][2024-08-28 18:46:02,237][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-08-28 18:46:36,597][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-08-28 18:46:36,598][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.249
[INFO][2024-08-28 18:46:36,598][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:46:36,598][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:46:36,611][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:46:36,737][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: pidof "dnsmasq"
[INFO][2024-08-28 18:46:36,737][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:46:36,859][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
311339
<=============================================================>
[INFO][2024-08-28 18:46:36,860][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Received response: 311339
[INFO][2024-08-28 18:46:36,860][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-08-28 18:46:36,860][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - PID of 'dnsmasq' is: 311339
[INFO][2024-08-28 18:46:36,860][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 7: ACTUAL : Dnsmasq process is restarted
[INFO][2024-08-28 18:46:36,860][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:46:36,860][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-XDNS_DNSSEC-101] [step Number : s7][ Execution status : PASS] [Error Message : ]
[INFO][2024-08-28 18:46:36,861][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-08-28 18:46:36,862][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-XDNS_DNSSEC-101","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-XDNS_DNSSEC-1001"}
[ERROR][2024-08-28 18:46:36,876][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:46:36,876][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-08-28 18:46:36,876][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:46:36,876][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 8: DESCRIPTION : Verify XDNS server values not present in /etc/resolv.conf
[INFO][2024-08-28 18:46:36,876][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 8: ACTION : Execute command:cat /etc/resolv.conf
[INFO][2024-08-28 18:46:36,876][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 8: EXPECTED : Resolv.conf does not have XDNS values
[INFO][2024-08-28 18:46:36,876][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:46:36,877][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entering executeCommandUsingSsh method
[INFO][2024-08-28 18:46:36,877][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DutIP:192.168.2.249
[INFO][2024-08-28 18:46:36,877][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:46:36,877][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:46:36,889][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:46:37,028][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: cat /etc/resolv.conf
[INFO][2024-08-28 18:46:37,028][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:46:37,150][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
nameserver 208.67.220.220
nameserver 208.67.222.222
<=============================================================>
[INFO][2024-08-28 18:46:37,151][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Received response: nameserver 208.67.220.220
nameserver 208.67.222.222
[INFO][2024-08-28 18:46:37,151][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exiting executeCommandUsingSsh method
[INFO][2024-08-28 18:46:37,152][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - STEP 8: ACTUAL : Resolv.conf does not have XDNS values
[INFO][2024-08-28 18:46:37,152][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - **********************************************************************************
[INFO][2024-08-28 18:46:37,152][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : TC-RDKB-XDNS_DNSSEC-101] [step Number : s8][ Execution status : PASS] [Error Message : ]
[INFO][2024-08-28 18:46:37,152][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Entered into updateExecutionStatus method
[INFO][2024-08-28 18:46:37,154][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Result Details ->{"JMD_ID":"456","manualId":"TC-RDKB-XDNS_DNSSEC-101","stepNumber":"s8","testType":"GROUP_OR_AUTOID ","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"","automationId":"TC-RDKB-XDNS_DNSSEC-1001"}
[ERROR][2024-08-28 18:46:37,165][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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-08-28 18:46:37,165][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Exited from updateExecutionStatus method
[INFO][2024-08-28 18:46:37,165][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ################### STARTING POST-CONFIGURATIONS ###################
[INFO][2024-08-28 18:46:37,165][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - POST-CONDITION STEPS
[INFO][2024-08-28 18:46:37,165][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - POST-CONDITION : DESCRIPTION : Disable XDNS using webpa command
[INFO][2024-08-28 18:46:37,165][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - POST-CONDITION : ACTION : Execute webpa or dmcli command to set Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS bool false
[INFO][2024-08-28 18:46:37,165][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - POST-CONDITION : EXPECTED : Successfully disabled XDNS feature
[INFO][2024-08-28 18:46:37,165][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method configured in Automatics Props: null
[INFO][2024-08-28 18:46:37,166][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method going to use: DMCLI
[INFO][2024-08-28 18:46:37,166][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Access Method: DMCLI
[INFO][2024-08-28 18:46:37,166][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to find protocol specific names
[INFO][2024-08-28 18:46:37,166][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going to execute commands
[INFO][2024-08-28 18:46:37,166][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - About to create SSH connection to DeviceIP:192.168.2.249
[INFO][2024-08-28 18:46:37,166][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSH Host IP : 192.168.2.249
[INFO][2024-08-28 18:46:37,166][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - SSh connection attempet : 1
[INFO][2024-08-28 18:46:37,178][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Default SSH custom port is set
[INFO][2024-08-28 18:46:37,337][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Executing command: dmcli eRT getv Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
[INFO][2024-08-28 18:46:37,337][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Conn is not null sendRecive:
[INFO][2024-08-28 18:46:37,460][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] -
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
getv from/to component(eRT.com.cisco.spvtg.ccsp.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
type: bool, value: false
<=============================================================>
[INFO][2024-08-28 18:46:37,460][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Closing SSH connection from DeviceIP:192.168.2.249
[INFO][2024-08-28 18:46:37,460][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
type: bool, value: false
[INFO][2024-08-28 18:46:37,460][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.xdns): Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
Execution succeed.
Parameter 1 name: Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS
type: bool, value: false
[INFO][2024-08-28 18:46:37,461][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Dmcli param value : false
[INFO][2024-08-28 18:46:37,461][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS, protocolSpecificParamName=Device.DeviceInfo.X_RDKCENTRAL-COM_EnableXDNS, value=false, datatype=null, statusCode=0]
[INFO][2024-08-28 18:46:37,461][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - WebPA get successful
[INFO][2024-08-28 18:46:37,461][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - POST-CONDITION : ACTUAL : Post condition executed successfully
[INFO][2024-08-28 18:46:37,462][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - POST-CONFIGURATIONS : FINAL STATUS - true
[INFO][2024-08-28 18:46:37,462][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ################### COMPLETED POST-CONFIGURATIONS ###################
[INFO][2024-08-28 18:46:37,462][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - ENDING TEST CASE: TC-RDKB-XDNS_DNSSEC-1001
[INFO][2024-08-28 18:46:37,466][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - >>>[AFTER_METHOD]: Clear device trace buffer for D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:46:37,475][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-XDNS_DNSSEC-1001/D83ADD138C13/settoptrace/
[INFO][2024-08-28 18:46:37,477][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - created a new directory true
[INFO][2024-08-28 18:46:37,477][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - New trace location from the strings /var/lib/jenkins/workspace/JOB1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-08-28 18:46:37,478][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Writing stated from line
[INFO][2024-08-28 18:46:37,631][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Wed Aug 28 18:46:37 IST 2024
[INFO][2024-08-28 18:46:37,631][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - NewLocation for log saving logs /var/lib/jenkins/workspace/JOB1/target/TC-RDKB-XDNS_DNSSEC-1001/D83ADD138C13/logs/
[INFO][2024-08-28 18:46:37,631][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - created a new directory true
[INFO][2024-08-28 18:46:37,631][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Destination Summary file doesn't exist. Creating one!
[INFO][2024-08-28 18:46:37,632][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Writing stated from line
[INFO][2024-08-28 18:46:37,687][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - [ HTML LOG PARSER ] : End of log parser Wed Aug 28 18:46:37 IST 2024
[INFO][2024-08-28 18:46:37,687][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - >>>[AFTER_METHOD]: Sending test execution completion time for D8:3A:DD:13:8C:13
[INFO][2024-08-28 18:46:37,687][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-08-28 18:46:37,687][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - {"jobId":456,"automationId":"TC-RDKB-XDNS_DNSSEC-1001","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":0,"endDateTimeEST":1724850997687}
[INFO][2024-08-28 18:46:37,697][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Capture execution time - Response : HTTP/1.1 200
[INFO][2024-08-28 18:46:37,697][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Restarting Trace in after method
[INFO][2024-08-28 18:46:37,698][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-08-28 18:46:37,698][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Is trace required for connected gateway: false
[INFO][2024-08-28 18:46:37,699][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Going for if build changed : Test Type : GROUP_OR_AUTOID
[INFO][2024-08-28 18:46:37,699][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-08-28 18:46:37,699][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13] - >>>[AFTER_METHOD]: Build Change Status: NO_CHANGE
[INFO][2024-08-28 18:46:37,699][D8:3A:DD:13:8C:13[TC-RDKB-XDNS_DNSSEC-1001 : 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.webgui.BroadBandWebGuiTests@156926d]org.testng.TestRunner@5dab68fd 22450797
AutomaticsTestBase.performBeforeMethodInit([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.webgui.BroadBandWebGuiTests@156926d][Ljava.lang.Object;@4931b69b [TestResult name={null} status=CREATED method=BroadBandWebGuiTests.testDnsSecWhenXDNSEnabled(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.webgui.BroadBandWebGuiTests@156926d] output={null}] 22450797
BroadBandWebGuiTests.testDnsSecWhenXDNSEnabled(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.webgui.BroadBandWebGuiTests@156926d]com.automatics.device.Device@652bd221 22450797
AutomaticsTestBase.performAfterMethodCleanup([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.rdkb.tests.webgui.BroadBandWebGuiTests@156926d][Ljava.lang.Object;@4931b69b [TestResult name=testDnsSecWhenXDNSEnabled status=SUCCESS method=BroadBandWebGuiTests.testDnsSecWhenXDNSEnabled(com.automatics.device.Dut)[pri:0, instance:com.automatics.rdkb.tests.webgui.BroadBandWebGuiTests@156926d] output={null}] 22450797
=====
PASSED: testDnsSecWhenXDNSEnabled(com.automatics.device.Device@652bd221)
===============================================
MyTest
Tests run: 1, Failures: 0, Skips: 0
===============================================
[INFO][2024-08-28 18:46:38,007][[com.automatics.executor.Starter.main()] - Finished testing class: org.testng.TestRunner
[INFO][2024-08-28 18:46:38,009][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Performing after suite cleanup
[INFO][2024-08-28 18:46:38,009][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-08-28 18:46:38,009][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Locked devices after suite execution 1
[INFO][2024-08-28 18:46:38,009][[com.automatics.executor.Starter.main()] - Value of intial testType : QUICK
[INFO][2024-08-28 18:46:38,010][[com.automatics.executor.Starter.main()] - Value of TestType provided by job : GROUP_OR_AUTOID
[INFO][2024-08-28 18:46:38,010][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Verifying if build changed after test
[INFO][2024-08-28 18:46:38,010][[com.automatics.executor.Starter.main()] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-08-28 18:46:38,010][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Build Change Status: NO_CHANGE
[INFO][2024-08-28 18:46:38,010][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[ERROR][2024-08-28 18:46:38,011][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Sleep interrupted sleep interrupted
[ERROR][2024-08-28 18:46:38,013][[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-08-28 18:46:38,018][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-08-28 18:46:38,018][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-08-28 18:46:38,019][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[INFO][2024-08-28 18:46:38,019][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-08-28 18:46:38,019][[com.automatics.executor.Starter.main()] - Skipping parter specific after suite clean up as it is not configured.
[INFO][2024-08-28 18:46:38,019][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-08-28 18:46:38,019][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Exited from readOutputFromChannel method
[INFO][2024-08-28 18:46:38,025][[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-08-28 18:46:38,075][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-08-28 18:46:38,080][[com.automatics.executor.Starter.main()] - Releasing device 2C:44:FD:18:F9:FE Url Path: http://192.168.2.145:8080/DeviceManager/deviceManagement/device/release
[INFO][2024-08-28 18:46:38,117][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"2C:44:FD:18:F9:FE"}
[INFO][2024-08-28 18:46:38,118][[com.automatics.executor.Starter.main()] - Successfully unlocked RDKB (D8:3A:DD:13:8C:13) mapped components - (WINDOWSVM) - 2C:44:FD:18:F9:FE
[INFO][2024-08-28 18:46:38,118][[com.automatics.executor.Starter.main()] - SETTOP - D8:3A:DD:13:8C:13 RELEASED.
[INFO][2024-08-28 18:46:38,119][[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":1724850835418,"completionTime":1724850998119,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}}
[INFO][2024-08-28 18:46:38,130][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [INFO][2024-08-28 18:44:31,740][456][D8:3A:DD:13:8C:13|TestNG-PoolService-0|com.automatics.executor.AutomaticsTestListener:onTestStart:110] STARTED - testDnsSecWhenXDNSEnabled - com.automatics.device.Device@652bd221
[INFO][2024-08-28 18:46:38,130][[com.automatics.executor.Starter.main()] - [ HTML LOG PARSER ] : line [DEBUG][2024-08-28 18:44:31,741][456][D8:3A:DD:13:8C:13|TC-RDKB-XDNS_DNSSEC-1001 : D83ADD138C13|com.automatics.executor.AutomaticsTestListener:onTestStart:150] Started appending log
===============================================
MySuite
Total tests run: 1, Passes: 1, Failures: 0, Skips: 0
===============================================
[INFO][2024-08-28 18:46:38,195][[com.automatics.executor.Starter.main()] - Updating final execution status to Automatics
[INFO][2024-08-28 18:46:38,196][[Thread-83] - ============= MESSAGE SEND TO AUTOMATICS ==============
[INFO][2024-08-28 18:46:38,196][[Thread-83] - Tested build name : RPI-RDKB
[INFO][2024-08-28 18:46:38,196][[Thread-83] - Final execution status : COMPLETED
[INFO][2024-08-28 18:46:38,196][[Thread-83] - Final successful device list : ["D8:3A:DD:13:8C:13"]
[INFO][2024-08-28 18:46:38,197][[Thread-83] - Job Id : 456
[INFO][2024-08-28 18:46:38,197][[Thread-83] - ==============================================================
[INFO][2024-08-28 18:46:38,197][[Thread-83] - >>>[INIT]: Automatics JSON ->
{"status":"COMPLETED","service":"","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1724850835418,"completionTime":1724850998119,"JMD_ID":456,"updateRdkPortal":true,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID ","tests":[]}}
[INFO][2024-08-28 18:46:38,197][[Thread-83] - http://192.168.2.59:8080/Automatics/executionResponse.htm
[INFO][2024-08-28 18:48:38,307][[Thread-83] - HTTP STATUS LINE : HTTP/1.1 200
[INFO][2024-08-28 18:48:38,308][[Thread-83] - HTTP STATUS CODE : 200
[INFO][2024-08-28 18:48:38,308][[com.automatics.executor.Starter.main()] - ******** EXECUTION COMPLETED *********
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 05:01 min
[INFO] Finished at: 2024-08-28T18:48:38+05:30
[INFO] ------------------------------------------------------------------------
Inside Add Shutdown Hook
[INFO][2024-08-28 18:48:38,316][[Thread-1] - Bean testInitializer is not configured.
[INFO][2024-08-28 18:48:38,316][[Thread-1] - Closing partner application context
[INFO][2024-08-28 18:48:38,318][[Thread-1] - Closing core application context
[JOB1] $ /bin/sh -xe /tmp/jenkins17418770583126749654.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