Started by user RDK-Test
Running as SYSTEM
Building in workspace /var/lib/jenkins/workspace/Scriptless1
[Scriptless1] $ /var/lib/jenkins/tools/hudson.tasks.Maven_MavenInstallation/installmaven/bin/mvn -s /mnt/maven/settings.xml -DsettopList=D8:3A:DD:13:8C:13 -DfilterTestType=GROUP_OR_AUTOID -DupdateRdkPortal=false -Dgrb=rdk-next -Dend_point=RACK_DEVICE -DfilterTestIds=3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130 -DexecutionMode=RDKB -Dgr=scriptless-automation -DBUILD_NAME=rdkb-generic-broadband-image_TDK_nightly-20241011-kirkstone_20241011030307 -DJMD_ID=2590 clean install -U exec:java -DskipTests=true -DretryByDefault=false -DbuildType=RDK -Dhttps.protocols=TLSv1.1,TLSv1.2 -Dsun.security.ssl.allowUnsafeRenegotiation=true -Dautomatics.properties.file=http://192.168.2.12:8080/AutomaticsProps/automatics.properties
[INFO] Scanning for projects...
[WARNING] 
[WARNING] Some problems were encountered while building the effective model for com.automatics.apps:scriptless-automation:jar:1.0.4
[WARNING] 'dependencies.dependency.(groupId: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] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 20763
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 20906
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 21049
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 21192
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 21335
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 21478
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 21621
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 21764
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 21907
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 22050
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 22193
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 22336
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 22479
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 22622
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 22765
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 22908
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 23051
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 23194
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 23337
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 23480
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 23623
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 23766
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 23909
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 24052
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 24195
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 24338
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 24481
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 24624
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 24767
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 24910
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 25053
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 25196
[WARNING] 'dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: com.automatics.providers:rpi-provider-impl:jar -> duplicate declaration of version 0.0.1-SNAPSHOT @ line 12, column 25474
[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] -------------< com.automatics.apps:scriptless-automation >--------------
[INFO] Building scriptless-automation 1.0.4
[INFO] --------------------------------[ jar ]---------------------------------
[INFO] 
[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ scriptless-automation ---
[INFO] Deleting /var/lib/jenkins/workspace/Scriptless1/target
[INFO] 
[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ scriptless-automation ---
[WARNING] Using platform encoding (UTF-8 actually) to copy filtered resources, i.e. build is platform dependent!
[INFO] Copying 5 resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.11.0:compile (default-compile) @ scriptless-automation ---
[INFO] Changes detected - recompiling the module! :source
[WARNING] File encoding has not been set, using platform encoding UTF-8, i.e. build is platform dependent!
[INFO] Compiling 41 source files with javac [debug target 11] to target/classes
[INFO] /var/lib/jenkins/workspace/Scriptless1/src/main/java/com/automatics/scriptless/testutils/TestBeanUtils.java: Some input files use unchecked or unsafe operations.
[INFO] /var/lib/jenkins/workspace/Scriptless1/src/main/java/com/automatics/scriptless/testutils/TestBeanUtils.java: Recompile with -Xlint:unchecked for details.
[INFO] 
[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ scriptless-automation ---
[WARNING] Using platform encoding (UTF-8 actually) to copy filtered resources, i.e. build is platform dependent!
[INFO] skip non existing resourceDirectory /var/lib/jenkins/workspace/Scriptless1/src/test/resources
[INFO] 
[INFO] --- maven-compiler-plugin:3.11.0:testCompile (default-testCompile) @ scriptless-automation ---
[INFO] Changes detected - recompiling the module! :dependency
[WARNING] File encoding has not been set, using platform encoding UTF-8, i.e. build is platform dependent!
[INFO] Compiling 4 source files with javac [debug target 11] to target/test-classes
[INFO] 
[INFO] --- maven-surefire-plugin:2.12.4:test (default-test) @ scriptless-automation ---
[INFO] Tests are skipped.
[INFO] 
[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ scriptless-automation ---
[INFO] Building jar: /var/lib/jenkins/workspace/Scriptless1/target/scriptless-automation-1.0.4.jar
[INFO] 
[INFO] --- maven-install-plugin:2.4:install (default-install) @ scriptless-automation ---
[INFO] Installing /var/lib/jenkins/workspace/Scriptless1/target/scriptless-automation-1.0.4.jar to /root/.m2/repository/com/automatics/apps/scriptless-automation/1.0.4/scriptless-automation-1.0.4.jar
[INFO] Installing /var/lib/jenkins/workspace/Scriptless1/pom.xml to /root/.m2/repository/com/automatics/apps/scriptless-automation/1.0.4/scriptless-automation-1.0.4.pom
[INFO] 
[INFO] --- exec-maven-plugin:3.0.0:java (default-cli) @ scriptless-automation ---
11:07:31,897 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback-test.xml] at [file:/var/lib/jenkins/workspace/Scriptless1/target/classes/logback-test.xml]
11:07:31,897 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs multiple times on the classpath.
11:07:31,897 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs at [jar:file:/root/.m2/repository/com/automatics/apps/automatics-core/2.24.0/automatics-core-2.24.0.jar!/logback-test.xml]
11:07:31,897 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback-test.xml] occurs at [file:/var/lib/jenkins/workspace/Scriptless1/target/classes/logback-test.xml]
11:07:32,019 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
11:07:32,021 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [stdOutAppender]
11:07:32,028 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@24:13 - no applicable action for [onMatch], current ElementPath  is [[configuration][appender][filter][onMatch]]
11:07:32,028 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@25:16 - no applicable action for [onMismatch], current ElementPath  is [[configuration][appender][filter][onMismatch]]
11:07:32,029 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
11:07:32,046 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
11:07:32,048 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [deviceSiftingAppender]
11:07:32,050 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@35:13 - no applicable action for [onMatch], current ElementPath  is [[configuration][appender][filter][onMatch]]
11:07:32,050 |-ERROR in ch.qos.logback.core.joran.spi.Interpreter@36:16 - no applicable action for [onMismatch], current ElementPath  is [[configuration][appender][filter][onMismatch]]
11:07:32,052 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
11:07:32,055 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
11:07:32,055 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [TraceLogger]
11:07:32,056 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
11:07:32,056 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
11:07:32,056 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [SerialTrace]
11:07:32,056 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
11:07:32,057 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.classic.sift.SiftingAppender]
11:07:32,057 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [crash-analysis]
11:07:32,057 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.sift.MDCBasedDiscriminator] for [discriminator] property
11:07:32,058 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [org.apache.http] to false
11:07:32,058 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - org.apache.http level set to INFO
11:07:32,058 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[org.apache.http]
11:07:32,059 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ConnectionTrace] to TRACE
11:07:32,059 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [ConnectionTrace] to false
11:07:32,059 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [TraceLogger] to Logger[ConnectionTrace]
11:07:32,059 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [SerialTrace] to TRACE
11:07:32,059 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [SerialTrace] to false
11:07:32,059 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [SerialTrace] to Logger[SerialTrace]
11:07:32,059 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [crash-analysis] to DEBUG
11:07:32,059 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting additivity of logger [crash-analysis] to false
11:07:32,059 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [crash-analysis] to Logger[crash-analysis]
11:07:32,059 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [stdOutAppender] to Logger[ROOT]
11:07:32,059 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [deviceSiftingAppender] to Logger[ROOT]
11:07:32,059 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
11:07:32,060 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@1f6a886a - Registering current configuration as safe fallback point
[INFO][2024-10-23 11:07:32,062][[com.automatics.executor.Starter.main()] - Starting the execution process for the given test cases.
11:07:32,063 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
11:07:32,065 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-test-suite]
11:07:32,067 |-INFO in ch.qos.logback.core.FileAppender[FILE-test-suite] - File property is set to [target/logs/test-suite.log]
[INFO][2024-10-23 11:07:32,087][[com.automatics.executor.Starter.main()] - Initializing application context partner-applicationContext.xml
ERROR StatusLogger Log4j2 could not find a logging implementation. Please add log4j-core to the classpath. Using SimpleLogger to log to the console...
[INFO][2024-10-23 11:07:32,407][[com.automatics.executor.Starter.main()] - Initializing application context applicationContext.xml
[INFO][2024-10-23 11:07:32,449][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.12:8080/AutomaticsProps/automatics.properties
[INFO][2024-10-23 11:07:32,575][[com.automatics.executor.Starter.main()] - Automatics Response - {"JMD_ID":"2590","settopList":"D8:3A:DD:13:8C:13","aedType":"","disableDHC":"","gateType":"","switchMode":"SET_RESET","BUILD_NAME":"rdkb-generic-broadband-image_TDK_nightly-20241011-kirkstone_20241011030307","Test_Script_Type":"","isWebPA":"","sendReportCustomer":"","isAccountTest":"","includedGroups":"","gr":"scriptless-automation","sendReport":"","testSource":"3DOT0","execSTBType":"","filterTestType":"GROUP_OR_AUTOID","triggerInfo":"","partnerName":"comcast","end_point":"RACK_DEVICE","excludedGroups":"","maxPooledBoxes":"","PERFORMANCE_PARAM":"","filterTestIds":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stbFeature":"null-null","executionMode":"RDKB","SERVICE_NAME":"FUNCTIONAL_VERIFICATION","enableIssueMgntService":"","grb":"rdk-next","updateRdkPortal":"false","is_pytest_testCase":"false"}
[INFO][2024-10-23 11:07:32,579][[com.automatics.executor.Starter.main()] - 

***************** Automatics BUILD PARAMETERS *****************
[INFO][2024-10-23 11:07:32,588][[com.automatics.executor.Starter.main()] -      JMD_ID     |     2590
[INFO][2024-10-23 11:07:32,588][[com.automatics.executor.Starter.main()] -      settopList     |     D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:07:32,588][[com.automatics.executor.Starter.main()] -      aedType     |     
[INFO][2024-10-23 11:07:32,588][[com.automatics.executor.Starter.main()] -      disableDHC     |     
[INFO][2024-10-23 11:07:32,589][[com.automatics.executor.Starter.main()] -      gateType     |     
[INFO][2024-10-23 11:07:32,589][[com.automatics.executor.Starter.main()] -      switchMode     |     SET_RESET
[INFO][2024-10-23 11:07:32,589][[com.automatics.executor.Starter.main()] -      BUILD_NAME     |     rdkb-generic-broadband-image_TDK_nightly-20241011-kirkstone_20241011030307
[INFO][2024-10-23 11:07:32,589][[com.automatics.executor.Starter.main()] -      Test_Script_Type     |     
[INFO][2024-10-23 11:07:32,589][[com.automatics.executor.Starter.main()] -      isWebPA     |     
[INFO][2024-10-23 11:07:32,591][[com.automatics.executor.Starter.main()] -      sendReportCustomer     |     
[INFO][2024-10-23 11:07:32,591][[com.automatics.executor.Starter.main()] -      isAccountTest     |     
[INFO][2024-10-23 11:07:32,591][[com.automatics.executor.Starter.main()] -      includedGroups     |     
[INFO][2024-10-23 11:07:32,591][[com.automatics.executor.Starter.main()] -      gr     |     scriptless-automation
[INFO][2024-10-23 11:07:32,591][[com.automatics.executor.Starter.main()] -      sendReport     |     
[INFO][2024-10-23 11:07:32,592][[com.automatics.executor.Starter.main()] -      testSource     |     3DOT0
[INFO][2024-10-23 11:07:32,592][[com.automatics.executor.Starter.main()] -      execSTBType     |     
[INFO][2024-10-23 11:07:32,592][[com.automatics.executor.Starter.main()] -      filterTestType     |     GROUP_OR_AUTOID
[INFO][2024-10-23 11:07:32,592][[com.automatics.executor.Starter.main()] -      triggerInfo     |     
[INFO][2024-10-23 11:07:32,592][[com.automatics.executor.Starter.main()] -      partnerName     |     comcast
[INFO][2024-10-23 11:07:32,592][[com.automatics.executor.Starter.main()] -      end_point     |     RACK_DEVICE
[INFO][2024-10-23 11:07:32,592][[com.automatics.executor.Starter.main()] -      excludedGroups     |     
[INFO][2024-10-23 11:07:32,593][[com.automatics.executor.Starter.main()] -      maxPooledBoxes     |     
[INFO][2024-10-23 11:07:32,593][[com.automatics.executor.Starter.main()] -      PERFORMANCE_PARAM     |     
[INFO][2024-10-23 11:07:32,593][[com.automatics.executor.Starter.main()] -      filterTestIds     |     3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130
[INFO][2024-10-23 11:07:32,593][[com.automatics.executor.Starter.main()] -      stbFeature     |     null-null
[INFO][2024-10-23 11:07:32,593][[com.automatics.executor.Starter.main()] -      executionMode     |     RDKB
[INFO][2024-10-23 11:07:32,593][[com.automatics.executor.Starter.main()] -      SERVICE_NAME     |     FUNCTIONAL_VERIFICATION
[INFO][2024-10-23 11:07:32,593][[com.automatics.executor.Starter.main()] -      enableIssueMgntService     |     
[INFO][2024-10-23 11:07:32,593][[com.automatics.executor.Starter.main()] -      grb     |     rdk-next
[INFO][2024-10-23 11:07:32,594][[com.automatics.executor.Starter.main()] -      updateRdkPortal     |     false
[INFO][2024-10-23 11:07:32,594][[com.automatics.executor.Starter.main()] -      is_pytest_testCase     |     false
[INFO][2024-10-23 11:07:32,594][[com.automatics.executor.Starter.main()] - Shut Down Hook Attached.
[INFO][2024-10-23 11:07:32,599][[com.automatics.executor.Starter.main()] - Start Execution Test Suite
[INFO][2024-10-23 11:07:32,600][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-10-23 11:07:32,601][[com.automatics.executor.Starter.main()] - Cleaning the trace log directory
[INFO][2024-10-23 11:07:32,623][[com.automatics.executor.Starter.main()] - AutomaticsPropertyUtility: Reading automatics.properties file from http://192.168.2.12:8080/AutomaticsProps/automatics.properties
[INFO][2024-10-23 11:07:32,628][[com.automatics.executor.Starter.main()] - >>>[INIT]: Validating config files
[INFO][2024-10-23 11:07:32,628][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.12:8080/AutomaticsProps/deviceProps.json
[INFO][2024-10-23 11:07:32,822][[com.automatics.executor.Starter.main()] - INIT- Requesting device config from http://192.168.2.12:8080/AutomaticsProps/deviceProps.json
[INFO][2024-10-23 11:07:32,829][[com.automatics.executor.Starter.main()] - frameworkSupportedModels=Rpi-RDKB,Rpi-RDKV,
[INFO][2024-10-23 11:07:32,829][[com.automatics.executor.Starter.main()] - rdkvGWModels=
[INFO][2024-10-23 11:07:32,830][[com.automatics.executor.Starter.main()] - rdkvCLModels=Rpi-RDKV,
[INFO][2024-10-23 11:07:32,830][[com.automatics.executor.Starter.main()] - rdkbModels=Rpi-RDKB,
[INFO][2024-10-23 11:07:32,830][[com.automatics.executor.Starter.main()] - rdkcModels=
[INFO][2024-10-23 11:07:32,844][[com.automatics.executor.Starter.main()] - Reading implementation from core for deviceProvider
[INFO][2024-10-23 11:07:32,859][[com.automatics.executor.Starter.main()] - Is Account based test: false
[INFO][2024-10-23 11:07:32,861][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Get device details
[INFO][2024-10-23 11:07:33,108][[com.automatics.executor.Starter.main()] - Fetching device details for D8:3A:DD:13:8C:13  Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getDeviceDetails
[INFO][2024-10-23 11:07:33,215][[com.automatics.executor.Starter.main()] - Response: {"devices":[{"id":"3845","name":"","hardwareRevision":"","hostMacAddress":"D8:3A:DD:13:8C:13","hostIp4Address":"192.168.2.27","hostIp6Address":null,"clientIpAddress":"192.168.2.27","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.27","headend":"HE","gatewayMac":"D8:3A:DD:13:8C:13","extraProperties":{"deviceIp":"192.168.2.27","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-10-23 11:07:33,251][[com.automatics.executor.Starter.main()] - INIT-D8:3A:DD:13:8C:13 Obtained device details
[INFO][2024-10-23 11:07:33,252][[com.automatics.executor.Starter.main()] - Found matching device object from config for rack model Rpi-RDKB
[INFO][2024-10-23 11:07:33,252][[com.automatics.executor.Starter.main()] - >>>[INIT]: Found device config mapped for rack model Rpi-RDKB
[INFO][2024-10-23 11:07:33,252][[com.automatics.executor.Starter.main()] - >>>[INIT]: Mapping rack model Rpi-RDKB to  automatics model Rpi-RDKB
[INFO][2024-10-23 11:07:33,252][[com.automatics.executor.Starter.main()] - [INIT LOG] : Non RDKV Client device
[INFO][2024-10-23 11:07:33,255][[pool-2-thread-1] - INIT-{} Check if device already lockedD8:3A:DD:13:8C:13
[INFO][2024-10-23 11:07:33,264][[pool-2-thread-1] - Fetching lock status for device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/allocationStatus
[INFO][2024-10-23 11:07:33,271][[pool-2-thread-1] - Response: {"allocationStatus":"AVAILABLE"}
[INFO][2024-10-23 11:07:33,273][[pool-2-thread-1] - DeviceConfig allocation status
[INFO][2024-10-23 11:07:33,274][[pool-2-thread-1] - [INIT LOG] : Locking device D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:07:33,274][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Locking device
[INFO][2024-10-23 11:07:33,280][[pool-2-thread-1] - Locking device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/lock
[INFO][2024-10-23 11:07:33,318][[pool-2-thread-1] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-10-23 11:07:33,321][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Lock success
[INFO][2024-10-23 11:07:33,321][[pool-2-thread-1] - Setting access mechanism for device D8:3A:DD:13:8C:13 SSH
[INFO][2024-10-23 11:07:33,321][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Setting access method SSH
[INFO][2024-10-23 11:07:33,321][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required
[INFO][2024-10-23 11:07:33,322][[pool-2-thread-1] - Setting accessibility check required to false for device D8:3A:DD:13:8C:13 from device config
[INFO][2024-10-23 11:07:33,322][[pool-2-thread-1] - Accessibility check required for device D8:3A:DD:13:8C:13 false 
[INFO][2024-10-23 11:07:33,322][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Checking if accessibility check required is false
[INFO][2024-10-23 11:07:33,322][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Assuming device is accessible
[INFO][2024-10-23 11:07:33,322][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Is device accessible true
[INFO][2024-10-23 11:07:33,323][[pool-2-thread-1] - [INIT LOG] : Wiring device.D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:07:33,323][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 Performing provider wiring
[INFO][2024-10-23 11:07:33,323][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 PowerProvider wiring
[INFO][2024-10-23 11:07:33,323][[pool-2-thread-1] - Reading implementation from core for powerProvider
[INFO][2024-10-23 11:07:33,324][[pool-2-thread-1] - INIT-D8:3A:DD:13:8C:13 DeviceConfig Connection Based TraceProvider wiring
[INFO][2024-10-23 11:07:33,326][[pool-2-thread-1] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-10-23 11:07:33,328][[pool-2-thread-1] - Additional trace support : null
[INFO][2024-10-23 11:07:33,329][[pool-2-thread-1] - settopObj.getModel() = Rpi-RDKB
[INFO][2024-10-23 11:07:33,329][[pool-2-thread-1] - Additional logging requirement if any enabled will be skipped due to configuration issue
[INFO][2024-10-23 11:07:33,332][[pool-2-thread-1] - Trace Log File location /var/lib/jenkins/workspace/Scriptless1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-10-23 11:07:33,332][[pool-2-thread-1] - Crash Analysis not enabled during trace monitoring
[INFO][2024-10-23 11:07:33,333][[pool-2-thread-1] - Serial based trace to be initialized: false
[INFO][2024-10-23 11:07:33,333][[pool-2-thread-1] - [INIT LOG] : Adding dut to locked list
[INFO][2024-10-23 11:07:33,334][[pool-2-thread-1] - DeviceConfig Macs D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:07:33,334][[pool-2-thread-1] - Dut Added to locked list D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:07:36,253][[com.automatics.executor.Starter.main()] - Finished Non-IP Initialization Threads
[INFO][2024-10-23 11:07:36,254][[com.automatics.executor.Starter.main()] - Collecting dut info
[INFO][2024-10-23 11:07:36,255][[com.automatics.executor.Starter.main()] - Requesting data from http://192.168.2.59:8084/api/rack/service/getmasterconfig?configName=TEST_TYPE_MAP
[INFO][2024-10-23 11:07:36,270][[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-10-23 11:07:36,419][[com.automatics.executor.Starter.main()] - AutomaticsTapApi instance not available. Creating new instance
[INFO][2024-10-23 11:07:36,419][[com.automatics.executor.Starter.main()] - Creating new instance for AutomaticsTapApi
[INFO][2024-10-23 11:07:36,420][[com.automatics.executor.Starter.main()] - Reading implementation from partner for deviceConnectionProvider
[INFO][2024-10-23 11:07:36,468][[com.automatics.executor.Starter.main()] - >>>[BEFORE_SUITE]: Perform before suite initialization
[INFO][2024-10-23 11:07:36,468][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-10-23 11:07:36,469][[com.automatics.executor.Starter.main()] - Adding locked devices to dut object in AutomaticsTestBase
[INFO][2024-10-23 11:07:36,469][[com.automatics.executor.Starter.main()] - Locked Settops: 1
[INFO][2024-10-23 11:07:36,470][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Verifying if build loaded in device as expected
[INFO][2024-10-23 11:07:36,471][[pool-6-thread-1] - [BEFORE-SUITE:]Expected build in device is rdkb-generic-broadband-image_TDK_nightly-20241011-kirkstone_20241011030307
[INFO][2024-10-23 11:07:36,471][[pool-6-thread-1] - [BEFORE-SUITE:]Verifying if build changed before test
[INFO][2024-10-23 11:07:36,471][[pool-6-thread-1] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-10-23 11:07:36,471][[pool-6-thread-1] - [BEFORE-SUITE:]Build Change Status: NO_CHANGE
[INFO][2024-10-23 11:07:36,471][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Build in device is as expected
[INFO][2024-10-23 11:07:36,471][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Setting appropritate build appender based on executionMode
[INFO][2024-10-23 11:07:36,476][[pool-6-thread-1] - Skipping setting of execution mode in device as partner specific initialization is not configured.
[INFO][2024-10-23 11:07:36,476][[pool-6-thread-1] - INIT-D8:3A:DD:13:8C:13 Perform before suite initialization
[INFO][2024-10-23 11:07:36,476][[pool-6-thread-1] - Skipping partner specific before suite initialization as it is not configured.
[INFO][2024-10-23 11:07:36,476][[pool-6-thread-1] - >>>[BEFORE_SUITE]: Starting device connection trace
[INFO][2024-10-23 11:07:36,477][[pool-6-thread-1] - Starting trace with command : tail -F -n 0  /rdklogs/logs/*
[INFO][2024-10-23 11:07:36,485][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Starting reading..D83ADD138C13settop_trace.log,
11:07:36,486 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
11:07:36,486 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-D83ADD138C13settop_trace.log]
11:07:36,487 |-INFO in ch.qos.logback.core.FileAppender[FILE-D83ADD138C13settop_trace.log] - File property is set to [target/settoptrace/D83ADD138C13settop_trace.log]
[INFO][2024-10-23 11:07:36,488][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Going to connect device for trace monitoring .....!
[INFO][2024-10-23 11:07:36,488][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP Address : 192.168.2.27
[INFO][2024-10-23 11:07:36,488][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host IP6 Address : null
[INFO][2024-10-23 11:07:36,489][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - XX Host MAC Address : D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:07:36,489][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside Non RDKV client device
[INFO][2024-10-23 11:07:36,499][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Reading server-config.xml from /var/lib/jenkins/workspace/testrepo/server-config.xml
[INFO][2024-10-23 11:07:36,521][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-10-23 11:07:36,879][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - About to create SSH connection to DeviceIP:192.168.2.27
[INFO][2024-10-23 11:07:36,896][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-10-23 11:07:42,144][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:192.168.2.27
[INFO][2024-10-23 11:07:42,151][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connectiongateway iscom.automatics.providers.connection.SshConnection@33c2a76a
[INFO][2024-10-23 11:07:42,152][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - inside null != connectionGateway condition 
[INFO][2024-10-23 11:07:42,152][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - gateWayDeviceInputStream iscom.jcraft.jsch.Channel$MyPipedInputStream@1ecd7bc
[INFO][2024-10-23 11:07:42,154][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Settoptrace debugging:Inside else block
[INFO][2024-10-23 11:07:42,154][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Entered into readOutputFromChannel method
[INFO][2024-10-23 11:07:42,155][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Is RDKV Client trace false
[INFO][2024-10-23 11:07:42,173][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-10-23 11:07:42,404][[PollingThread_26_(D8:3A:DD:13:8C:13)] - sshConnectionPoll established
[INFO][2024-10-23 11:07:42,418][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Default SSH custom port is set
[INFO][2024-10-23 11:07:42,646][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Value of responseString: null
[INFO][2024-10-23 11:07:42,646][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Executing command: echo CONNECTION_IS_ALIVE
[INFO][2024-10-23 11:07:42,649][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:07:42,774][[PollingThread_26_(D8:3A:DD:13:8C:13)] - 
<===========================  RESPONSE =======================> 
CONNECTION_IS_ALIVE

<=============================================================>
[INFO][2024-10-23 11:07:42,774][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Closing SSH connection from DeviceIP:com.automatics.device.Device@25e66cc2
[INFO][2024-10-23 11:07:42,779][[PollingThread_26_(D8:3A:DD:13:8C:13)] - ConnectionPoll disconnected
[INFO][2024-10-23 11:08:06,486][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-10-23 11:08:06,487][[pool-6-thread-1] - Is trace required for connected gateway: false
[INFO][2024-10-23 11:08:09,473][[com.automatics.executor.Starter.main()] - Starting testing on class: org.testng.TestRunner
[INFO][2024-10-23 11:08:09,505][[com.automatics.executor.Starter.main()] - Initializing application context for rdkb test utils: rdkbutils-applicationContext.xml
[INFO][2024-10-23 11:08:09,688][[com.automatics.executor.Starter.main()] - ResponseCode for URL http://192.168.2.59:8084/api/rack/servicehttp://192.168.2.59:8084/api/rack/service/testcase/testInfo/3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130: 200,[{"testCaseName":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","isDynamicTestCase":"Y","testCaseId":10863}]
[INFO][2024-10-23 11:08:09,721][[com.automatics.executor.Starter.main()] - testCaseInfoList size is-1
[INFO][2024-10-23 11:08:09,743][[com.automatics.executor.Starter.main()] - 1.0 - triggerDetails=Trigger Details [triggerId=2590, triggerType=FUNCTIONAL, envType=, buildName=rdkb-generic-broadband-image_TDK_nightly-20241011-kirkstone_20241011030307, gaBuildName=, count=0, startTime=null, endTime=null, timeInterval=null, testCaseDetails=[[ TestCaseInfo testCaseName=3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130, isDynamicTestCase=Y, testCaseId=10863]], runMultipleTcInSeq=false]
[INFO][2024-10-23 11:08:09,759][[com.automatics.executor.Starter.main()] - ResponseCode for URL http://192.168.2.59:8084/api/rack/servicehttp://192.168.2.59:8084/api/rack/service/testConstants/fetchAll: 200,[{"id":"1","constantName":"VERSION","constantValue":"head /version.txt","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:44.000+00:00","updatedUser":"admin","createdDate":"2023-10-17T04:50:44.000+00:00","createdUser":"admin"},{"id":"2","constantName":"UPTIME","constantValue":"uptime","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:44.000+00:00","updatedUser":"admin","createdDate":"2023-10-17T04:50:44.000+00:00","createdUser":"admin"},{"id":"3","constantName":"DEVICE_DETAILS","constantValue":"cat /tmp/.deviceDetails.cache","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:44.000+00:00","updatedUser":"admin","createdDate":"2023-10-17T04:50:44.000+00:00","createdUser":"admin"},{"id":"4","constantName":"ESTB_MAC","constantValue":"cat /tmp/.deviceDetails.cache | grep \"estb_mac\" | cut -d \"=\" -f 2","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:44.000+00:00","updatedUser":"admin","createdDate":"2023-10-17T04:50:44.000+00:00","createdUser":"admin"},{"id":"5","constantName":"MODEL","constantValue":"cat /tmp/.deviceDetails.cache | grep \"model_number\" | cut -d \"=\" -f 2","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:44.000+00:00","updatedUser":"admin","createdDate":"2023-10-17T04:50:44.000+00:00","createdUser":"admin"},{"id":"6","constantName":"VERSION_PATH","constantValue":"cd /opt/logs/","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:44.000+00:00","updatedUser":"admin","createdDate":"2023-10-17T04:50:44.000+00:00","createdUser":"admin"},{"id":"7","constantName":"COMMAND_TO_GET_CERTS","constantValue":"openssl pkcs12 -in /etc/ssl/certs/xpic.p12 -nodes -passin pass:$(/usr/bin/rdkssacli \"{STOR=GET,SRC=evcdkjnofwvy,DST=/dev/stdout}\") ","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:44.000+00:00","updatedUser":"admin","createdDate":"2023-10-17T04:50:44.000+00:00","createdUser":"admin"},{"id":"8","constantName":"CONSTANT_TEST","constantValue":"TEST_VALUE","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:44.000+00:00","updatedUser":"admin","createdDate":"2023-10-17T04:50:44.000+00:00","createdUser":"admin"},{"id":"9","constantName":"TR069_SUPPORT_ENABLE","constantValue":"\tDevice.DeviceInfo.X_RDKCENTRAL-COM_RFC.Feature.TR069support.Enable","isUserEditable":"Y","updatedDate":"2023-10-17T04:50:44.000+00:00","updatedUser":"admin","createdDate":"2023-10-17T04:50:44.000+00:00","createdUser":"admin"}]
[INFO][2024-10-23 11:08:09,801][[com.automatics.executor.Starter.main()] - ResponseCode for URL http://192.168.2.59:8084/api/rack/servicehttp://192.168.2.59:8084/api/rack/service/testcaserepo/fetchrecord/10863: 200,{"testCaseId":10863,"author":"admin","lastUpdatedBy":"admin","lastUpdatedDate":"2024-10-23T05:35:58.000+00:00","testMappingId":0,"testType":"FUNCTIONAL","createdBy":"admin","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","testCaseDescription":"Bootup log verification after CDL","featureType":null,"runOnModels":["Rpi-RDKB"],"environmentType":["RDKB"],"headEnd":["RACK_DEVICE"],"buildType":["STABLE","RELEASE","MASTER"],"testPriority":"P0","userGroups":"ADMIN","testSuiteNames":null,"runbookType":null,"preConditions":null,"postConditions":null,"testStepInfo":[{"stepNumber":"1","description":"Verification of   '  write error ' error message (in bootup logs - with in 5 to 10 mins)","commandType":"Step","utility":{"id":268,"className":"DEVICE_CONNECTION","displayName":"DEVICE_CONNECTION"},"action":{"id":1531,"predefinedClassId":268,"methodName":"com.automatics.tap.AutomaticsScriptlessTapApi.executeCommand","displayName":"EXECUTE_COMMAND","desc":"Execute Command","returnType":"String"},"persistAs":"Should not be any critical 'write error' logs after firmware upgrade.","properties":{"breakOnFailure":"False","raiseDefect":"False","postWait":0,"updateAutomatics":"True","addPolling":"No","pollType":"","pollDurationOrIteration":"","pollInterval":"","disableStep":"No","customErrorMsg":"","saveConnectedClient":"False","updateParentStatus":"False","almId":"","actionDetails":null,"impact":null,"iCollectTc":null},"commandInput":[{"applicableFor":["Rpi-RDKB"],"validationType":null,"expectedOutput":null,"params":[{"paramName":"command","paramValue":"grep -inr \"write error\" /rdklogs/logs/*.* | grep -v \"rdk_shell.log\"","paramDataType":"String","isHidden":false,"order":"1"}],"connectedClientConfig":null,"connectedClient":null,"executeOnConnectedClient":false,"conditions":[{"expected":"NA","actual":null,"validationType":"NULL","operator":"","invertResult":false,"order":"1"}]}],"conditions":null,"iteration":null,"parentId":null,"expectedResult":"Should not be any critical 'write error' logs after firmware upgrade."},{"stepNumber":"2","description":"Verification of   ' syntax error ' error message(in bootup logs - with in 5 to 10 mins)","commandType":"Step","utility":{"id":268,"className":"DEVICE_CONNECTION","displayName":"DEVICE_CONNECTION"},"action":{"id":1531,"predefinedClassId":268,"methodName":"com.automatics.tap.AutomaticsScriptlessTapApi.executeCommand","displayName":"EXECUTE_COMMAND","desc":"Execute Command","returnType":"String"},"persistAs":"Should not be any critical 'syntax error' logs after firmware upgrade.","properties":{"breakOnFailure":"False","raiseDefect":"False","postWait":0,"updateAutomatics":"True","addPolling":"No","pollType":"","pollDurationOrIteration":"","pollInterval":"","disableStep":"No","customErrorMsg":"","saveConnectedClient":"False","updateParentStatus":"False","almId":"","actionDetails":null,"impact":null,"iCollectTc":null},"commandInput":[{"applicableFor":["Rpi-RDKB"],"validationType":null,"expectedOutput":null,"params":[{"paramName":"command","paramValue":"grep -inr \"syntax error\" /rdklogs/logs/*.* | grep -v \"rdk_shell.log\"","paramDataType":"String","isHidden":false,"order":"1"}],"connectedClientConfig":null,"connectedClient":null,"executeOnConnectedClient":false,"conditions":[{"expected":"NA","actual":null,"validationType":"NULL","operator":"","invertResult":false,"order":"1"}]}],"conditions":null,"iteration":null,"parentId":null,"expectedResult":"Should not be any critical 'syntax error' logs after firmware upgrade."},{"stepNumber":"3","description":"Verification of   ' segmentation ' error message(in bootup logs - with in 5 to 10 mins)","commandType":"Step","utility":{"id":268,"className":"DEVICE_CONNECTION","displayName":"DEVICE_CONNECTION"},"action":{"id":1531,"predefinedClassId":268,"methodName":"com.automatics.tap.AutomaticsScriptlessTapApi.executeCommand","displayName":"EXECUTE_COMMAND","desc":"Execute Command","returnType":"String"},"persistAs":"Should not be any critical 'Segmentation' logs after firmware upgrade.","properties":{"breakOnFailure":"False","raiseDefect":"False","postWait":0,"updateAutomatics":"True","addPolling":"No","pollType":"","pollDurationOrIteration":"","pollInterval":"","disableStep":"No","customErrorMsg":"","saveConnectedClient":"False","updateParentStatus":"False","almId":"","actionDetails":null,"impact":null,"iCollectTc":null},"commandInput":[{"applicableFor":["Rpi-RDKB"],"validationType":null,"expectedOutput":null,"params":[{"paramName":"command","paramValue":"grep -inr \"segmentation\" /rdklogs/logs/*.* | grep -v \"rdk_shell.log\"","paramDataType":"String","isHidden":false,"order":"1"}],"connectedClientConfig":null,"connectedClient":null,"executeOnConnectedClient":false,"conditions":[{"expected":"NA","actual":null,"validationType":"NULL","operator":"","invertResult":false,"order":"1"}]}],"conditions":null,"iteration":null,"parentId":null,"expectedResult":"Should not be any critical 'Segmentation' logs after firmware upgrade."},{"stepNumber":"4","description":"Verification of   ' expression ' error message(in bootup logs - with in 5 to 10 mins)","commandType":"Step","utility":{"id":268,"className":"DEVICE_CONNECTION","displayName":"DEVICE_CONNECTION"},"action":{"id":1531,"predefinedClassId":268,"methodName":"com.automatics.tap.AutomaticsScriptlessTapApi.executeCommand","displayName":"EXECUTE_COMMAND","desc":"Execute Command","returnType":"String"},"persistAs":"","properties":{"breakOnFailure":"False","raiseDefect":"False","postWait":0,"updateAutomatics":"True","addPolling":"No","pollType":"","pollDurationOrIteration":"","pollInterval":"","disableStep":"No","customErrorMsg":"","saveConnectedClient":"False","updateParentStatus":"False","almId":"","actionDetails":"","impact":"","iCollectTc":null},"commandInput":[{"applicableFor":["Rpi-RDKB"],"validationType":null,"expectedOutput":null,"params":[{"paramName":"command","paramValue":"grep -inr \"expression\" /rdklogs/logs/*.* | grep -v \"rdk_shell.log\" | grep -v \"integer expression expected\"","paramDataType":"String","isHidden":false,"order":"1"}],"connectedClientConfig":null,"connectedClient":null,"executeOnConnectedClient":false,"conditions":[{"expected":"NA","actual":null,"validationType":"NULL","operator":"","invertResult":false,"order":"1"}]}],"conditions":null,"iteration":null,"parentId":null,"expectedResult":"Should not be any critical 'Expression' logs after firmware upgrade."},{"stepNumber":"5","description":"Verification of ' argument expected ' error message (in bootup logs - with i.n 5 to 10 mins).","commandType":"Step","utility":{"id":268,"className":"DEVICE_CONNECTION","displayName":"DEVICE_CONNECTION"},"action":{"id":1531,"predefinedClassId":268,"methodName":"com.automatics.tap.AutomaticsScriptlessTapApi.executeCommand","displayName":"EXECUTE_COMMAND","desc":"Execute Command","returnType":"String"},"persistAs":"Should not be any critical ' argument expected' logs after firmware upgrade.","properties":{"breakOnFailure":"False","raiseDefect":"False","postWait":0,"updateAutomatics":"True","addPolling":"No","pollType":"","pollDurationOrIteration":"","pollInterval":"","disableStep":"No","customErrorMsg":"","saveConnectedClient":"False","updateParentStatus":"False","almId":"","actionDetails":null,"impact":null,"iCollectTc":null},"commandInput":[{"applicableFor":["Rpi-RDKB"],"validationType":null,"expectedOutput":null,"params":[{"paramName":"command","paramValue":"grep -inr \"argument expected\" /rdklogs/logs/*.* | grep -v \"rdk_shell.log\"","paramDataType":"String","isHidden":false,"order":"1"}],"connectedClientConfig":null,"connectedClient":null,"executeOnConnectedClient":false,"conditions":[{"expected":"NA","actual":null,"validationType":"NULL","operator":"","invertResult":false,"order":"1"}]}],"conditions":null,"iteration":null,"parentId":null,"expectedResult":"Should not be any critical ' argument expected' logs after firmware upgrade."},{"stepNumber":"6","description":"Verification of ' : not found' error message (in bootup logs - with in 5 to 10 mins).","commandType":"Step","utility":{"id":268,"className":"DEVICE_CONNECTION","displayName":"DEVICE_CONNECTION"},"action":{"id":1531,"predefinedClassId":268,"methodName":"com.automatics.tap.AutomaticsScriptlessTapApi.executeCommand","displayName":"EXECUTE_COMMAND","desc":"Execute Command","returnType":"String"},"persistAs":"Should not be any critical ' : not found' logs after firmware upgrade.","properties":{"breakOnFailure":"False","raiseDefect":"False","postWait":0,"updateAutomatics":"True","addPolling":"No","pollType":"","pollDurationOrIteration":"","pollInterval":"","disableStep":"No","customErrorMsg":"","saveConnectedClient":"False","updateParentStatus":"False","almId":"","actionDetails":null,"impact":null,"iCollectTc":null},"commandInput":[{"applicableFor":["Rpi-RDKB"],"validationType":null,"expectedOutput":null,"params":[{"paramName":"command","paramValue":"grep -inr \": not found\" /rdklogs/logs/*.* | grep -v \"rdk_shell.log\"","paramDataType":"String","isHidden":false,"order":"1"}],"connectedClientConfig":null,"connectedClient":null,"executeOnConnectedClient":false,"conditions":[{"expected":"NA","actual":null,"validationType":"NULL","operator":"","invertResult":false,"order":"1"}]}],"conditions":null,"iteration":null,"parentId":null,"expectedResult":"Should not be any critical ' : not found' logs after firmware upgrade."},{"stepNumber":"7","description":"Verification of ' : unexpected' error message (in bootup logs - with in 5 to 10 mins)","commandType":"Step","utility":{"id":268,"className":"DEVICE_CONNECTION","displayName":"DEVICE_CONNECTION"},"action":{"id":1531,"predefinedClassId":268,"methodName":"com.automatics.tap.AutomaticsScriptlessTapApi.executeCommand","displayName":"EXECUTE_COMMAND","desc":"Execute Command","returnType":"String"},"persistAs":"Should not be any critical ' : unexpected '   logs after firmware upgrade.","properties":{"breakOnFailure":"False","raiseDefect":"False","postWait":0,"updateAutomatics":"True","addPolling":"No","pollType":"","pollDurationOrIteration":"","pollInterval":"","disableStep":"No","customErrorMsg":"","saveConnectedClient":"False","updateParentStatus":"False","almId":"","actionDetails":null,"impact":null,"iCollectTc":null},"commandInput":[{"applicableFor":["Rpi-RDKB"],"validationType":null,"expectedOutput":null,"params":[{"paramName":"command","paramValue":"grep -inr \": unexpected\" /rdklogs/logs/*.* | grep -v \"rdk_shell.log\"","paramDataType":"String","isHidden":false,"order":"1"}],"connectedClientConfig":null,"connectedClient":null,"executeOnConnectedClient":false,"conditions":[{"expected":"NA","actual":null,"validationType":"NULL","operator":"","invertResult":false,"order":"1"}]}],"conditions":null,"iteration":null,"parentId":null,"expectedResult":"Should not be any critical ' : unexpected '   logs after firmware upgrade."},{"stepNumber":"8","description":"Verification of 'ambiguous redirect' error message (in bootup logs - with in 5 to 10 mins)","commandType":"Step","utility":{"id":268,"className":"DEVICE_CONNECTION","displayName":"DEVICE_CONNECTION"},"action":{"id":1531,"predefinedClassId":268,"methodName":"com.automatics.tap.AutomaticsScriptlessTapApi.executeCommand","displayName":"EXECUTE_COMMAND","desc":"Execute Command","returnType":"String"},"persistAs":"Should not be any critical ' ambiguous redirect 'logs after firmware upgrade.","properties":{"breakOnFailure":"False","raiseDefect":"False","postWait":0,"updateAutomatics":"True","addPolling":"No","pollType":"","pollDurationOrIteration":"","pollInterval":"","disableStep":"No","customErrorMsg":"","saveConnectedClient":"False","updateParentStatus":"False","almId":"","actionDetails":null,"impact":null,"iCollectTc":null},"commandInput":[{"applicableFor":["Rpi-RDKB"],"validationType":null,"expectedOutput":null,"params":[{"paramName":"command","paramValue":"grep -inr \"ambiguous redirect\" /rdklogs/logs/*.* | grep -v \"rdk_shell.log\"","paramDataType":"String","isHidden":false,"order":"1"}],"connectedClientConfig":null,"connectedClient":null,"executeOnConnectedClient":false,"conditions":[{"expected":"NA","actual":null,"validationType":"NULL","operator":"","invertResult":false,"order":"1"}]}],"conditions":null,"iteration":null,"parentId":null,"expectedResult":"Should not be any critical ' ambiguous redirect 'logs after firmware upgrade."},{"stepNumber":"9","description":"Verification of 'screwed up' error message (in bootup logs - with in 5 to 10 mins).","commandType":"Step","utility":{"id":268,"className":"DEVICE_CONNECTION","displayName":"DEVICE_CONNECTION"},"action":{"id":1531,"predefinedClassId":268,"methodName":"com.automatics.tap.AutomaticsScriptlessTapApi.executeCommand","displayName":"EXECUTE_COMMAND","desc":"Execute Command","returnType":"String"},"persistAs":"Should not be any critical ' screwed up' logs after firmware upgrade.","properties":{"breakOnFailure":"False","raiseDefect":"False","postWait":0,"updateAutomatics":"True","addPolling":"No","pollType":"","pollDurationOrIteration":"","pollInterval":"","disableStep":"No","customErrorMsg":"","saveConnectedClient":"False","updateParentStatus":"False","almId":"","actionDetails":null,"impact":null,"iCollectTc":null},"commandInput":[{"applicableFor":["Rpi-RDKB"],"validationType":null,"expectedOutput":null,"params":[{"paramName":"command","paramValue":"grep -inr \"screwed up\" /rdklogs/logs/*.* | grep -v \"rdk_shell.log\"","paramDataType":"String","isHidden":false,"order":"1"}],"connectedClientConfig":null,"connectedClient":null,"executeOnConnectedClient":false,"conditions":[{"expected":"NA","actual":null,"validationType":"NULL","operator":"","invertResult":false,"order":"1"}]}],"conditions":null,"iteration":null,"parentId":null,"expectedResult":"Should not be any critical ' screwed up' logs after firmware upgrade."}],"isDynamicTestCase":"Y","deletedDate":null,"executionMode":"Standalone","regressionLabel":"112","rdkComponent":null,"featureNumber":null,"functionalGroup":null,"createdDate":"2024-09-25T08:20:39.000+00:00","testCaseHistory":[{"updated_by":"admin","updated_date":"Wed Sep 25 2024 13:56:35 GMT+0530 (India Standard Time)","updated_reason":"aaa"},{"updated_by":"admin","updated_date":"Wed Sep 25 2024 14:12:12 GMT+0530 (India Standard Time)","updated_reason":"AAA\n"},{"updated_by":"admin","updated_date":"Wed Oct 09 2024 16:38:08 GMT+0530 (India Standard Time)","updated_reason":"aaa"},{"updated_by":"admin","updated_date":"Wed Oct 09 2024 16:45:19 GMT+0530 (India Standard Time)","updated_reason":"aaa\n"},{"updated_by":"admin","updated_date":"Wed Oct 09 2024 17:07:31 GMT+0530 (India Standard Time)","updated_reason":"aaa\n"},{"updated_by":"admin","updated_date":"Wed Oct 09 2024 17:28:48 GMT+0530 (India Standard Time)","updated_reason":"aaa\n"}]}
[INFO][2024-10-23 11:08:09,811][[com.automatics.executor.Starter.main()] - Performing the test constants replacement
[INFO][2024-10-23 11:08:09,811][[com.automatics.executor.Starter.main()] - Entering in to replaceTestConstantsWithValues() method
[INFO][2024-10-23 11:08:09,814][[com.automatics.executor.Starter.main()] - Exiting from replaceTestConstantsWithValues() method
[INFO][2024-10-23 11:08:09,847][[com.automatics.executor.Starter.main()] - Doing initialization for D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:08:09,864][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Perform before method initialization D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:08:09,864][[TestNG-PoolService-0] - Setting dattime in patter yyyy-MM-dd HH:mm:ss
[INFO][2024-10-23 11:08:09,867][[TestNG-PoolService-0] - startTime 2024-10-23 05:38:09
[INFO][2024-10-23 11:08:09,867][[TestNG-PoolService-0] - >>>[BEFORE_METHOD]: Sending test exection start time to Automatics D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:08:09,868][[TestNG-PoolService-0] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-10-23 11:08:09,869][[TestNG-PoolService-0] - {"jobId":2590,"automationId":"TC-CUSTOM-1000","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":1729661889868,"endDateTimeEST":0}
[INFO][2024-10-23 11:08:09,983][[TestNG-PoolService-0] - Capture execution time - Response : HTTP/1.1 200 
[INFO][2024-10-23 11:08:09,983][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Skipping partner specific before method initialization as it is not configured.
11:08:09,984 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender]
11:08:09,984 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE-Rpi-RDKB-D83ADD138C13]
11:08:09,985 |-INFO in ch.qos.logback.core.FileAppender[FILE-Rpi-RDKB-D83ADD138C13] - File property is set to [target/logs/Rpi-RDKB-D83ADD138C13.log]
[INFO][2024-10-23 11:08:09,985][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-10-23 11:08:09,985][D8:3A:DD:13:8C:13[TestNG-PoolService-0] -  Get the locked status: false
[INFO][2024-10-23 11:08:09,986][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-10-23 11:08:09,986][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - Is trace required for connected gateway: false
[INFO][2024-10-23 11:08:09,992][D8:3A:DD:13:8C:13[TestNG-PoolService-0] - <a name="com.automatics.scriptless.test.CustomTest.customTestStarter">STARTED - customTestStarter - com.automatics.device.Device@25e66cc2</a>
[INFO][2024-10-23 11:08:09,993][D8:3A:DD:13:8C:13[TC-CUSTOM-1000 : D83ADD138C13] - Getting MDC = D83ADD138C13settop_trace.log
[INFO][2024-10-23 11:08:09,993][D8:3A:DD:13:8C:13[TC-CUSTOM-1000 : D83ADD138C13] - Getting MDC = D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:08:10,001][D8:3A:DD:13:8C:13[TC-CUSTOM-1000 : D83ADD138C13] - Test to be executed 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130
[INFO][2024-10-23 11:08:10,001][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - ###STARTING TEST CASE: 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130
[INFO][2024-10-23 11:08:10,002][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Starting execution in settop -D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:08:10,006][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - # STEP_1            : Verification of   '  write error ' error message (in bootup logs - with in 5 to 10 mins)                                        #
[INFO][2024-10-23 11:08:10,006][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - # STEP_2            : Verification of   ' syntax error ' error message(in bootup logs - with in 5 to 10 mins)                                         #
[INFO][2024-10-23 11:08:10,006][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - # STEP_3            : Verification of   ' segmentation ' error message(in bootup logs - with in 5 to 10 mins)                                         #
[INFO][2024-10-23 11:08:10,006][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - # STEP_4            : Verification of   ' expression ' error message(in bootup logs - with in 5 to 10 mins)                                           #
[INFO][2024-10-23 11:08:10,007][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - # STEP_5            : Verification of ' argument expected ' error message (in bootup logs - with i.n 5 to 10 mins).                                   #
[INFO][2024-10-23 11:08:10,007][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - # STEP_6            : Verification of ' : not found' error message (in bootup logs - with in 5 to 10 mins).                                           #
[INFO][2024-10-23 11:08:10,007][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - # STEP_7            : Verification of ' : unexpected' error message (in bootup logs - with in 5 to 10 mins)                                           #
[INFO][2024-10-23 11:08:10,007][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - # STEP_8            : Verification of 'ambiguous redirect' error message (in bootup logs - with in 5 to 10 mins)                                      #
[INFO][2024-10-23 11:08:10,007][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - # STEP_9            : Verification of 'screwed up' error message (in bootup logs - with in 5 to 10 mins).                                             #
[INFO][2024-10-23 11:08:10,008][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_1] #####################################################
[INFO][2024-10-23 11:08:10,008][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_1] Description : Verification of   '  write error ' error message (in bootup logs - with in 5 to 10 mins)
[INFO][2024-10-23 11:08:10,009][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_1] Expected Result : Should not be any critical 'write error' logs after firmware upgrade.
[INFO][2024-10-23 11:08:10,009][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_1] API Name : EXECUTE_COMMAND
[INFO][2024-10-23 11:08:10,009][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_1] #####################################################
[INFO][2024-10-23 11:08:10,012][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device Model mapped to Automatics model name : Rpi-RDKB
[INFO][2024-10-23 11:08:10,013][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Packages to scan : com.automatics.
[INFO][2024-10-23 11:08:10,225][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Reflections took 188 ms to scan 5 urls, producing 22 keys and 340 values 
[INFO][2024-10-23 11:08:10,318][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Total APIs :: 174
[INFO][2024-10-23 11:08:10,346][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entering executeCommandUsingSsh method
[INFO][2024-10-23 11:08:10,346][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - About to create SSH connection to DutIP:192.168.2.27
[INFO][2024-10-23 11:08:10,364][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Default SSH custom port is set
[INFO][2024-10-23 11:08:10,567][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Executing command: grep -inr "write error" /rdklogs/logs/*.* | grep -v "rdk_shell.log"
[INFO][2024-10-23 11:08:10,567][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:08:10,689][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-10-23 11:08:10,690][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exiting executeCommandUsingSsh method
[INFO][2024-10-23 11:08:10,692][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> ACTUAL OUTPUT: 

[INFO][2024-10-23 11:08:10,692][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> EXPECTED OUTPUT: NA
[INFO][2024-10-23 11:08:10,692][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION PERFORMED: NULL
[INFO][2024-10-23 11:08:10,692][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION STATUS: true
[INFO][2024-10-23 11:08:10,693][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> INVERT RESULT: false
[INFO][2024-10-23 11:08:10,697][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_1] #####################################################
[INFO][2024-10-23 11:08:10,698][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_1]-[API Name: EXECUTE_COMMAND]-[Execution Status: PASS]
[INFO][2024-10-23 11:08:10,698][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_1] #####################################################
[INFO][2024-10-23 11:08:10,704][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] [step Number : S1][ Execution status : PASS] [Error Message : ]
[INFO][2024-10-23 11:08:10,705][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entered into updateExecutionStatus method
[INFO][2024-10-23 11:08:10,706][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Fetching device property: FIRMWARE_VERSION
[INFO][2024-10-23 11:08:10,713][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Fetching device props for D8:3A:DD:13:8C:13 for props [FIRMWARE_VERSION] Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/getDeviceProps
[INFO][2024-10-23 11:08:10,724][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 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-10-23 11:08:10,724][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Getting value for property: FIRMWARE_VERSION
[INFO][2024-10-23 11:08:10,724][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Value: RPI-RDKB
[INFO][2024-10-23 11:08:10,724][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device property name: FIRMWARE_VERSION value obtained: RPI-RDKB
[INFO][2024-10-23 11:08:10,729][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Result Details ->{"JMD_ID":"2590","manualId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stepNumber":"S1","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"comcast","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130"}
[INFO][2024-10-23 11:08:10,790][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution result updated successfully
[INFO][2024-10-23 11:08:10,790][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exited from updateExecutionStatus method
[INFO][2024-10-23 11:08:10,790][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_2] #####################################################
[INFO][2024-10-23 11:08:10,791][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_2] Description : Verification of   ' syntax error ' error message(in bootup logs - with in 5 to 10 mins)
[INFO][2024-10-23 11:08:10,791][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_2] Expected Result : Should not be any critical 'syntax error' logs after firmware upgrade.
[INFO][2024-10-23 11:08:10,791][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_2] API Name : EXECUTE_COMMAND
[INFO][2024-10-23 11:08:10,791][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_2] #####################################################
[INFO][2024-10-23 11:08:10,791][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device Model mapped to Automatics model name : Rpi-RDKB
[INFO][2024-10-23 11:08:10,814][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entering executeCommandUsingSsh method
[INFO][2024-10-23 11:08:10,814][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - About to create SSH connection to DutIP:192.168.2.27
[INFO][2024-10-23 11:08:10,827][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Default SSH custom port is set
[INFO][2024-10-23 11:08:11,035][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Executing command: grep -inr "syntax error" /rdklogs/logs/*.* | grep -v "rdk_shell.log"
[INFO][2024-10-23 11:08:11,035][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:08:11,157][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-10-23 11:08:11,158][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exiting executeCommandUsingSsh method
[INFO][2024-10-23 11:08:11,158][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> ACTUAL OUTPUT: 

[INFO][2024-10-23 11:08:11,159][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> EXPECTED OUTPUT: NA
[INFO][2024-10-23 11:08:11,159][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION PERFORMED: NULL
[INFO][2024-10-23 11:08:11,159][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION STATUS: true
[INFO][2024-10-23 11:08:11,159][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> INVERT RESULT: false
[INFO][2024-10-23 11:08:11,160][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_2] #####################################################
[INFO][2024-10-23 11:08:11,160][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_2]-[API Name: EXECUTE_COMMAND]-[Execution Status: PASS]
[INFO][2024-10-23 11:08:11,160][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_2] #####################################################
[INFO][2024-10-23 11:08:11,162][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] [step Number : S2][ Execution status : PASS] [Error Message : ]
[INFO][2024-10-23 11:08:11,163][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entered into updateExecutionStatus method
[INFO][2024-10-23 11:08:11,164][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Result Details ->{"JMD_ID":"2590","manualId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stepNumber":"S2","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"comcast","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130"}
[INFO][2024-10-23 11:08:11,198][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution result updated successfully
[INFO][2024-10-23 11:08:11,198][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exited from updateExecutionStatus method
[INFO][2024-10-23 11:08:11,199][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_3] #####################################################
[INFO][2024-10-23 11:08:11,199][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_3] Description : Verification of   ' segmentation ' error message(in bootup logs - with in 5 to 10 mins)
[INFO][2024-10-23 11:08:11,199][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_3] Expected Result : Should not be any critical 'Segmentation' logs after firmware upgrade.
[INFO][2024-10-23 11:08:11,199][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_3] API Name : EXECUTE_COMMAND
[INFO][2024-10-23 11:08:11,199][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_3] #####################################################
[INFO][2024-10-23 11:08:11,199][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device Model mapped to Automatics model name : Rpi-RDKB
[INFO][2024-10-23 11:08:11,227][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entering executeCommandUsingSsh method
[INFO][2024-10-23 11:08:11,227][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - About to create SSH connection to DutIP:192.168.2.27
[INFO][2024-10-23 11:08:11,243][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Default SSH custom port is set
[INFO][2024-10-23 11:08:11,440][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Executing command: grep -inr "segmentation" /rdklogs/logs/*.* | grep -v "rdk_shell.log"
[INFO][2024-10-23 11:08:11,440][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:08:11,563][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-10-23 11:08:11,563][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exiting executeCommandUsingSsh method
[INFO][2024-10-23 11:08:11,563][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> ACTUAL OUTPUT: 

[INFO][2024-10-23 11:08:11,564][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> EXPECTED OUTPUT: NA
[INFO][2024-10-23 11:08:11,564][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION PERFORMED: NULL
[INFO][2024-10-23 11:08:11,564][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION STATUS: true
[INFO][2024-10-23 11:08:11,564][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> INVERT RESULT: false
[INFO][2024-10-23 11:08:11,564][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_3] #####################################################
[INFO][2024-10-23 11:08:11,565][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_3]-[API Name: EXECUTE_COMMAND]-[Execution Status: PASS]
[INFO][2024-10-23 11:08:11,565][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_3] #####################################################
[INFO][2024-10-23 11:08:11,567][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] [step Number : S3][ Execution status : PASS] [Error Message : ]
[INFO][2024-10-23 11:08:11,568][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entered into updateExecutionStatus method
[INFO][2024-10-23 11:08:11,569][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Result Details ->{"JMD_ID":"2590","manualId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stepNumber":"S3","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"comcast","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130"}
[INFO][2024-10-23 11:08:11,606][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution result updated successfully
[INFO][2024-10-23 11:08:11,606][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exited from updateExecutionStatus method
[INFO][2024-10-23 11:08:11,607][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_4] #####################################################
[INFO][2024-10-23 11:08:11,607][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_4] Description : Verification of   ' expression ' error message(in bootup logs - with in 5 to 10 mins)
[INFO][2024-10-23 11:08:11,607][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_4] Expected Result : Should not be any critical 'Expression' logs after firmware upgrade.
[INFO][2024-10-23 11:08:11,607][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_4] API Name : EXECUTE_COMMAND
[INFO][2024-10-23 11:08:11,607][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_4] #####################################################
[INFO][2024-10-23 11:08:11,608][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device Model mapped to Automatics model name : Rpi-RDKB
[INFO][2024-10-23 11:08:11,637][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entering executeCommandUsingSsh method
[INFO][2024-10-23 11:08:11,637][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - About to create SSH connection to DutIP:192.168.2.27
[INFO][2024-10-23 11:08:11,651][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Default SSH custom port is set
[INFO][2024-10-23 11:08:11,852][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Executing command: grep -inr "expression" /rdklogs/logs/*.* | grep -v "rdk_shell.log" | grep -v "integer expression expected"
[INFO][2024-10-23 11:08:11,852][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:08:11,975][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-10-23 11:08:11,975][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exiting executeCommandUsingSsh method
[INFO][2024-10-23 11:08:11,976][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> ACTUAL OUTPUT: 

[INFO][2024-10-23 11:08:11,976][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> EXPECTED OUTPUT: NA
[INFO][2024-10-23 11:08:11,976][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION PERFORMED: NULL
[INFO][2024-10-23 11:08:11,976][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION STATUS: true
[INFO][2024-10-23 11:08:11,976][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> INVERT RESULT: false
[INFO][2024-10-23 11:08:11,977][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_4] #####################################################
[INFO][2024-10-23 11:08:11,977][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_4]-[API Name: EXECUTE_COMMAND]-[Execution Status: PASS]
[INFO][2024-10-23 11:08:11,977][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_4] #####################################################
[INFO][2024-10-23 11:08:11,979][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] [step Number : S4][ Execution status : PASS] [Error Message : ]
[INFO][2024-10-23 11:08:11,979][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entered into updateExecutionStatus method
[INFO][2024-10-23 11:08:11,980][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Result Details ->{"JMD_ID":"2590","manualId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stepNumber":"S4","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"comcast","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130"}
[INFO][2024-10-23 11:08:12,023][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution result updated successfully
[INFO][2024-10-23 11:08:12,023][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exited from updateExecutionStatus method
[INFO][2024-10-23 11:08:12,024][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_5] #####################################################
[INFO][2024-10-23 11:08:12,024][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_5] Description : Verification of ' argument expected ' error message (in bootup logs - with i.n 5 to 10 mins).
[INFO][2024-10-23 11:08:12,024][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_5] Expected Result : Should not be any critical ' argument expected' logs after firmware upgrade.
[INFO][2024-10-23 11:08:12,024][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_5] API Name : EXECUTE_COMMAND
[INFO][2024-10-23 11:08:12,024][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_5] #####################################################
[INFO][2024-10-23 11:08:12,024][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device Model mapped to Automatics model name : Rpi-RDKB
[INFO][2024-10-23 11:08:12,048][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entering executeCommandUsingSsh method
[INFO][2024-10-23 11:08:12,048][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - About to create SSH connection to DutIP:192.168.2.27
[INFO][2024-10-23 11:08:12,062][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Default SSH custom port is set
[INFO][2024-10-23 11:08:12,264][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Executing command: grep -inr "argument expected" /rdklogs/logs/*.* | grep -v "rdk_shell.log"
[INFO][2024-10-23 11:08:12,265][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:08:12,387][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-10-23 11:08:12,387][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exiting executeCommandUsingSsh method
[INFO][2024-10-23 11:08:12,387][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> ACTUAL OUTPUT: 

[INFO][2024-10-23 11:08:12,388][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> EXPECTED OUTPUT: NA
[INFO][2024-10-23 11:08:12,388][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION PERFORMED: NULL
[INFO][2024-10-23 11:08:12,388][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION STATUS: true
[INFO][2024-10-23 11:08:12,388][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> INVERT RESULT: false
[INFO][2024-10-23 11:08:12,389][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_5] #####################################################
[INFO][2024-10-23 11:08:12,389][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_5]-[API Name: EXECUTE_COMMAND]-[Execution Status: PASS]
[INFO][2024-10-23 11:08:12,389][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_5] #####################################################
[INFO][2024-10-23 11:08:12,391][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] [step Number : S5][ Execution status : PASS] [Error Message : ]
[INFO][2024-10-23 11:08:12,391][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entered into updateExecutionStatus method
[INFO][2024-10-23 11:08:12,392][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Result Details ->{"JMD_ID":"2590","manualId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stepNumber":"S5","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"comcast","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130"}
[INFO][2024-10-23 11:08:12,431][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution result updated successfully
[INFO][2024-10-23 11:08:12,432][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exited from updateExecutionStatus method
[INFO][2024-10-23 11:08:12,432][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_6] #####################################################
[INFO][2024-10-23 11:08:12,432][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_6] Description : Verification of ' : not found' error message (in bootup logs - with in 5 to 10 mins).
[INFO][2024-10-23 11:08:12,432][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_6] Expected Result : Should not be any critical ' : not found' logs after firmware upgrade.
[INFO][2024-10-23 11:08:12,432][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_6] API Name : EXECUTE_COMMAND
[INFO][2024-10-23 11:08:12,432][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_6] #####################################################
[INFO][2024-10-23 11:08:12,433][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device Model mapped to Automatics model name : Rpi-RDKB
[INFO][2024-10-23 11:08:12,459][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entering executeCommandUsingSsh method
[INFO][2024-10-23 11:08:12,459][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - About to create SSH connection to DutIP:192.168.2.27
[INFO][2024-10-23 11:08:12,472][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Default SSH custom port is set
[INFO][2024-10-23 11:08:12,655][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Executing command: grep -inr ": not found" /rdklogs/logs/*.* | grep -v "rdk_shell.log"
[INFO][2024-10-23 11:08:12,656][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:08:12,778][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-10-23 11:08:12,778][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exiting executeCommandUsingSsh method
[INFO][2024-10-23 11:08:12,778][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> ACTUAL OUTPUT: 

[INFO][2024-10-23 11:08:12,779][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> EXPECTED OUTPUT: NA
[INFO][2024-10-23 11:08:12,779][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION PERFORMED: NULL
[INFO][2024-10-23 11:08:12,779][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION STATUS: true
[INFO][2024-10-23 11:08:12,779][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> INVERT RESULT: false
[INFO][2024-10-23 11:08:12,779][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_6] #####################################################
[INFO][2024-10-23 11:08:12,780][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_6]-[API Name: EXECUTE_COMMAND]-[Execution Status: PASS]
[INFO][2024-10-23 11:08:12,780][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_6] #####################################################
[INFO][2024-10-23 11:08:12,781][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] [step Number : S6][ Execution status : PASS] [Error Message : ]
[INFO][2024-10-23 11:08:12,782][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entered into updateExecutionStatus method
[INFO][2024-10-23 11:08:12,783][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Result Details ->{"JMD_ID":"2590","manualId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stepNumber":"S6","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"comcast","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130"}
[INFO][2024-10-23 11:08:12,823][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution result updated successfully
[INFO][2024-10-23 11:08:12,823][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exited from updateExecutionStatus method
[INFO][2024-10-23 11:08:12,823][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_7] #####################################################
[INFO][2024-10-23 11:08:12,823][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_7] Description : Verification of ' : unexpected' error message (in bootup logs - with in 5 to 10 mins)
[INFO][2024-10-23 11:08:12,823][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_7] Expected Result : Should not be any critical ' : unexpected '   logs after firmware upgrade.
[INFO][2024-10-23 11:08:12,824][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_7] API Name : EXECUTE_COMMAND
[INFO][2024-10-23 11:08:12,824][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_7] #####################################################
[INFO][2024-10-23 11:08:12,824][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device Model mapped to Automatics model name : Rpi-RDKB
[INFO][2024-10-23 11:08:12,844][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entering executeCommandUsingSsh method
[INFO][2024-10-23 11:08:12,844][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - About to create SSH connection to DutIP:192.168.2.27
[INFO][2024-10-23 11:08:12,858][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Default SSH custom port is set
[INFO][2024-10-23 11:08:13,066][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Executing command: grep -inr ": unexpected" /rdklogs/logs/*.* | grep -v "rdk_shell.log"
[INFO][2024-10-23 11:08:13,066][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:08:13,188][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-10-23 11:08:13,189][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exiting executeCommandUsingSsh method
[INFO][2024-10-23 11:08:13,189][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> ACTUAL OUTPUT: 

[INFO][2024-10-23 11:08:13,189][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> EXPECTED OUTPUT: NA
[INFO][2024-10-23 11:08:13,189][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION PERFORMED: NULL
[INFO][2024-10-23 11:08:13,190][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION STATUS: true
[INFO][2024-10-23 11:08:13,190][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> INVERT RESULT: false
[INFO][2024-10-23 11:08:13,190][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_7] #####################################################
[INFO][2024-10-23 11:08:13,190][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_7]-[API Name: EXECUTE_COMMAND]-[Execution Status: PASS]
[INFO][2024-10-23 11:08:13,190][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_7] #####################################################
[INFO][2024-10-23 11:08:13,193][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] [step Number : S7][ Execution status : PASS] [Error Message : ]
[INFO][2024-10-23 11:08:13,193][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entered into updateExecutionStatus method
[INFO][2024-10-23 11:08:13,194][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Result Details ->{"JMD_ID":"2590","manualId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stepNumber":"S7","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"comcast","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130"}
[INFO][2024-10-23 11:08:13,231][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution result updated successfully
[INFO][2024-10-23 11:08:13,232][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exited from updateExecutionStatus method
[INFO][2024-10-23 11:08:13,232][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_8] #####################################################
[INFO][2024-10-23 11:08:13,232][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_8] Description : Verification of 'ambiguous redirect' error message (in bootup logs - with in 5 to 10 mins)
[INFO][2024-10-23 11:08:13,232][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_8] Expected Result : Should not be any critical ' ambiguous redirect 'logs after firmware upgrade.
[INFO][2024-10-23 11:08:13,232][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_8] API Name : EXECUTE_COMMAND
[INFO][2024-10-23 11:08:13,232][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_8] #####################################################
[INFO][2024-10-23 11:08:13,233][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device Model mapped to Automatics model name : Rpi-RDKB
[INFO][2024-10-23 11:08:13,254][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entering executeCommandUsingSsh method
[INFO][2024-10-23 11:08:13,255][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - About to create SSH connection to DutIP:192.168.2.27
[INFO][2024-10-23 11:08:13,271][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Default SSH custom port is set
[INFO][2024-10-23 11:08:13,462][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Executing command: grep -inr "ambiguous redirect" /rdklogs/logs/*.* | grep -v "rdk_shell.log"
[INFO][2024-10-23 11:08:13,462][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:08:13,584][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-10-23 11:08:13,584][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exiting executeCommandUsingSsh method
[INFO][2024-10-23 11:08:13,584][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> ACTUAL OUTPUT: 

[INFO][2024-10-23 11:08:13,585][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> EXPECTED OUTPUT: NA
[INFO][2024-10-23 11:08:13,585][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION PERFORMED: NULL
[INFO][2024-10-23 11:08:13,585][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION STATUS: true
[INFO][2024-10-23 11:08:13,585][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> INVERT RESULT: false
[INFO][2024-10-23 11:08:13,585][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_8] #####################################################
[INFO][2024-10-23 11:08:13,586][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_8]-[API Name: EXECUTE_COMMAND]-[Execution Status: PASS]
[INFO][2024-10-23 11:08:13,586][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_8] #####################################################
[INFO][2024-10-23 11:08:13,587][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] [step Number : S8][ Execution status : PASS] [Error Message : ]
[INFO][2024-10-23 11:08:13,587][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entered into updateExecutionStatus method
[INFO][2024-10-23 11:08:13,588][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Result Details ->{"JMD_ID":"2590","manualId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stepNumber":"S8","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"comcast","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130"}
[INFO][2024-10-23 11:08:13,632][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution result updated successfully
[INFO][2024-10-23 11:08:13,632][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exited from updateExecutionStatus method
[INFO][2024-10-23 11:08:13,632][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_9] #####################################################
[INFO][2024-10-23 11:08:13,632][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_9] Description : Verification of 'screwed up' error message (in bootup logs - with in 5 to 10 mins).
[INFO][2024-10-23 11:08:13,632][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_9] Expected Result : Should not be any critical ' screwed up' logs after firmware upgrade.
[INFO][2024-10-23 11:08:13,633][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_9] API Name : EXECUTE_COMMAND
[INFO][2024-10-23 11:08:13,633][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_9] #####################################################
[INFO][2024-10-23 11:08:13,633][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Device Model mapped to Automatics model name : Rpi-RDKB
[INFO][2024-10-23 11:08:13,659][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entering executeCommandUsingSsh method
[INFO][2024-10-23 11:08:13,659][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - About to create SSH connection to DutIP:192.168.2.27
[INFO][2024-10-23 11:08:13,673][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Default SSH custom port is set
[INFO][2024-10-23 11:08:13,872][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Executing command: grep -inr "screwed up" /rdklogs/logs/*.* | grep -v "rdk_shell.log"
[INFO][2024-10-23 11:08:13,872][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Conn is not null sendRecive: 
[INFO][2024-10-23 11:08:13,994][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - 
<===========================  RESPONSE =======================> 

<=============================================================>
[INFO][2024-10-23 11:08:13,995][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exiting executeCommandUsingSsh method
[INFO][2024-10-23 11:08:13,995][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> ACTUAL OUTPUT: 

[INFO][2024-10-23 11:08:13,995][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> EXPECTED OUTPUT: NA
[INFO][2024-10-23 11:08:13,995][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION PERFORMED: NULL
[INFO][2024-10-23 11:08:13,995][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> VALIDATION STATUS: true
[INFO][2024-10-23 11:08:13,996][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>>>> INVERT RESULT: false
[INFO][2024-10-23 11:08:13,996][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_9] #####################################################
[INFO][2024-10-23 11:08:13,996][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_9]-[API Name: EXECUTE_COMMAND]-[Execution Status: PASS]
[INFO][2024-10-23 11:08:13,996][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STEP_9] #####################################################
[INFO][2024-10-23 11:08:13,998][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [STB MAC : D8:3A:DD:13:8C:13][ Manual test ID : 3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] [step Number : S9][ Execution status : PASS] [Error Message : ]
[INFO][2024-10-23 11:08:13,998][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Entered into updateExecutionStatus method
[INFO][2024-10-23 11:08:13,999][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Result Details ->{"JMD_ID":"2590","manualId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130","stepNumber":"S9","testType":"GROUP_OR_AUTOID","buildName":"RPI-RDKB","macAddress":"D8:3A:DD:13:8C:13","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"comcast","automationId":"3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130"}
[INFO][2024-10-23 11:08:14,040][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution result updated successfully
[INFO][2024-10-23 11:08:14,040][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Exited from updateExecutionStatus method
[INFO][2024-10-23 11:08:14,041][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Execution Completed in settop -D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:08:14,048][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Mac:: D8:3A:DD:13:8C:13 Status:: true Total Count:: 0
[ERROR][2024-10-23 11:08:14,055][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [ HTML LOG PARSER ] : Exception --> null
java.lang.NullPointerException
	at com.automatics.scriptless.utils.HtmlLogGenerator.initializeParser(HtmlLogGenerator.java:333)
	at com.automatics.scriptless.utils.HtmlLogGenerator.parseAndGenerateHTMLLog(HtmlLogGenerator.java:361)
	at com.automatics.scriptless.test.RackTestBase.generateHtmlLog(RackTestBase.java:160)
	at com.automatics.scriptless.test.CustomTest.customTestStarter(CustomTest.java:64)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:134)
	at org.testng.internal.TestInvoker.invokeMethod(TestInvoker.java:597)
	at org.testng.internal.TestInvoker.invokeTestMethod(TestInvoker.java:173)
	at org.testng.internal.TestMethodWithDataProviderMethodWorker.call(TestMethodWithDataProviderMethodWorker.java:77)
	at org.testng.internal.TestMethodWithDataProviderMethodWorker.call(TestMethodWithDataProviderMethodWorker.java:15)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at java.base/java.lang.Thread.run(Thread.java:829)
[INFO][2024-10-23 11:08:14,059][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>[AFTER_METHOD]: Clear device trace buffer for D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:08:14,066][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - NewLocation for log saving logs  /var/lib/jenkins/workspace/Scriptless1/target/TC-CUSTOM-1000/D83ADD138C13/settoptrace/
[INFO][2024-10-23 11:08:14,067][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - created a new directory true
[INFO][2024-10-23 11:08:14,067][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - New trace location from the strings /var/lib/jenkins/workspace/Scriptless1/target/settoptrace/D83ADD138C13settop_trace.log
[INFO][2024-10-23 11:08:14,068][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Writing stated from line
[INFO][2024-10-23 11:08:14,118][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [ HTML LOG PARSER ] : End of log parser Wed Oct 23 11:08:14 IST 2024
[INFO][2024-10-23 11:08:14,118][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - NewLocation for log saving logs  /var/lib/jenkins/workspace/Scriptless1/target/TC-CUSTOM-1000/D83ADD138C13/logs/
[INFO][2024-10-23 11:08:14,119][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - created a new directory true
[INFO][2024-10-23 11:08:14,119][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Destination Summary file doesn't exist. Creating one!
[INFO][2024-10-23 11:08:14,119][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Writing stated from line
[INFO][2024-10-23 11:08:14,215][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - [ HTML LOG PARSER ] : End of log parser Wed Oct 23 11:08:14 IST 2024
[INFO][2024-10-23 11:08:14,215][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>[AFTER_METHOD]: Sending test execution completion time for D8:3A:DD:13:8C:13
[INFO][2024-10-23 11:08:14,216][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - http://192.168.2.59:8080/Automatics/captureTestTriggerTime.htm
[INFO][2024-10-23 11:08:14,216][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - {"jobId":2590,"automationId":"TC-CUSTOM-1000","macAddress":"D8:3A:DD:13:8C:13","startDateTimeEST":0,"endDateTimeEST":1729661894215}
[INFO][2024-10-23 11:08:14,259][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Capture execution time - Response : HTTP/1.1 200 
[INFO][2024-10-23 11:08:14,259][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Restarting Trace in after method
[INFO][2024-10-23 11:08:14,260][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Is trace required for connected gateway: false
[INFO][2024-10-23 11:08:14,260][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Is trace required for connected gateway: false
[INFO][2024-10-23 11:08:14,260][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Going for if build changed : Test Type : GROUP_OR_AUTOID
[INFO][2024-10-23 11:08:14,260][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-10-23 11:08:14,260][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - >>>[AFTER_METHOD]: Build Change Status: NO_CHANGE
[INFO][2024-10-23 11:08:14,260][D8:3A:DD:13:8C:13[3D0T0-TC-RDKB_BOOTUP_LOG_VERFIY_TC130] - Skipping parter specific after method clean up as it is not configured.
[INFO][2024-10-23 11:08:14,262][[com.automatics.executor.Starter.main()] - Closing test utils application context
===== Invoked methods
  AutomaticsTestBase.performBeforeSuiteInit(org.testng.ITestContext)[pri:0, instance:com.automatics.scriptless.test.CustomTest@55788777]org.testng.TestRunner@7bc09fa  1433962359
  RackTestBase.rackBeforeTest()[pri:0, instance:com.automatics.scriptless.test.CustomTest@55788777] 1433962359
  AutomaticsTestBase.performBeforeMethodInit([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.scriptless.test.CustomTest@55788777][Ljava.lang.Object;@388aea1f [TestResult name={null} status=CREATED method=CustomTest.customTestStarter(com.automatics.device.Dut)[pri:0, instance:com.automatics.scriptless.test.CustomTest@55788777] output={null}]  1433962359
    CustomTest.customTestStarter(com.automatics.device.Dut)[pri:0, instance:com.automatics.scriptless.test.CustomTest@55788777]com.automatics.device.Device@25e66cc2  1433962359
  AutomaticsTestBase.performAfterMethodCleanup([Ljava.lang.Object;, org.testng.ITestResult)[pri:0, instance:com.automatics.scriptless.test.CustomTest@55788777][Ljava.lang.Object;@388aea1f [TestResult name=customTestStarter status=SUCCESS method=CustomTest.customTestStarter(com.automatics.device.Dut)[pri:0, instance:com.automatics.scriptless.test.CustomTest@55788777] output={null}]  1433962359
  RackTestBase.rackAfterTest()[pri:0, instance:com.automatics.scriptless.test.CustomTest@55788777] 1433962359
=====
PASSED: customTestStarter(com.automatics.device.Device@25e66cc2)

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

[INFO][2024-10-23 11:08:14,502][[com.automatics.executor.Starter.main()] - Finished testing class: org.testng.TestRunner
[INFO][2024-10-23 11:08:14,503][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Performing after suite cleanup
[INFO][2024-10-23 11:08:14,503][[com.automatics.executor.Starter.main()] - Bean testInitializer is not configured.
[INFO][2024-10-23 11:08:14,503][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Locked devices after suite execution 1
[INFO][2024-10-23 11:08:14,504][[com.automatics.executor.Starter.main()] - Value of intial testType : QUICK
[INFO][2024-10-23 11:08:14,504][[com.automatics.executor.Starter.main()] - Value of TestType provided by job : GROUP_OR_AUTOID
[INFO][2024-10-23 11:08:14,504][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Verifying if build changed after test
[INFO][2024-10-23 11:08:14,504][[com.automatics.executor.Starter.main()] - Skipping build change verification as partner specific initialization is not configured.
[INFO][2024-10-23 11:08:14,504][[com.automatics.executor.Starter.main()] - >>>[AFTER-SUITE]: Build Change Status: NO_CHANGE
[INFO][2024-10-23 11:08:14,504][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[ERROR][2024-10-23 11:08:14,505][[PollingThread_26_(D8:3A:DD:13:8C:13)] - Sleep interrupted sleep interrupted
[ERROR][2024-10-23 11:08:14,507][[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-10-23 11:08:14,508][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-10-23 11:08:14,508][[com.automatics.executor.Starter.main()] - Connection instance is Null
[INFO][2024-10-23 11:08:14,508][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-10-23 11:08:14,508][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Connection instance is Null
[INFO][2024-10-23 11:08:14,508][[ConnectionThread_21 (D8:3A:DD:13:8C:13)] - Exited from readOutputFromChannel method
[INFO][2024-10-23 11:08:14,508][[com.automatics.executor.Starter.main()] - Is trace required for connected gateway: false
[INFO][2024-10-23 11:08:14,508][[com.automatics.executor.Starter.main()] - Skipping parter specific after suite clean up as it is not configured.
[INFO][2024-10-23 11:08:14,514][[com.automatics.executor.Starter.main()] - Releasing device D8:3A:DD:13:8C:13 Url Path: http://192.168.2.12:8080/DeviceManager/deviceManagement/device/release
[INFO][2024-10-23 11:08:14,568][[com.automatics.executor.Starter.main()] - Response: {"status":"SUCCESS","mac":"D8:3A:DD:13:8C:13"}
[INFO][2024-10-23 11:08:14,569][[com.automatics.executor.Starter.main()] - Successfully unlocked RDKB (D8:3A:DD:13:8C:13) mapped components - 
[INFO][2024-10-23 11:08:14,570][[com.automatics.executor.Starter.main()] - SETTOP - D8:3A:DD:13:8C:13 RELEASED.
[INFO][2024-10-23 11:08:14,570][[com.automatics.executor.Starter.main()] - [AFTER-SUITE:]JSON message to Automatics: {"status":"COMPLETED","service":"FUNCTIONAL_VERIFICATION","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1729661856469,"completionTime":1729661894570,"JMD_ID":2590,"updateRdkPortal":false,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID","tests":[]}}
[INFO][2024-10-23 11:08:14,570][[com.automatics.executor.Starter.main()] - Disabling HTML report generation from automatics-core

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

[INFO][2024-10-23 11:08:14,610][[com.automatics.executor.Starter.main()] - Updating final execution status to Automatics
[INFO][2024-10-23 11:08:14,610][[Thread-40] - ============= MESSAGE SEND TO AUTOMATICS ==============
[INFO][2024-10-23 11:08:14,611][[Thread-40] - Tested build name : RPI-RDKB
[INFO][2024-10-23 11:08:14,611][[Thread-40] - Final execution status : COMPLETED
[INFO][2024-10-23 11:08:14,611][[Thread-40] - Final successful device list : ["D8:3A:DD:13:8C:13"]
[INFO][2024-10-23 11:08:14,611][[Thread-40] - Job Id : 2590
[INFO][2024-10-23 11:08:14,611][[Thread-40] - ==============================================================

[INFO][2024-10-23 11:08:14,611][[Thread-40] - >>>[INIT]: Automatics JSON ->
{"status":"COMPLETED","service":"FUNCTIONAL_VERIFICATION","buildImageName":"RPI-RDKB","settopList":["D8:3A:DD:13:8C:13"],"startTime":1729661856469,"completionTime":1729661894570,"JMD_ID":2590,"updateRdkPortal":false,"result":{"build_name":"RPI-RDKB","type":"GROUP_OR_AUTOID","tests":[]}}
[INFO][2024-10-23 11:08:14,611][[Thread-40] - http://192.168.2.59:8080/Automatics/executionResponse.htm
[INFO][2024-10-23 11:08:14,693][[Thread-40] - HTTP STATUS LINE : HTTP/1.1 200 
[INFO][2024-10-23 11:08:14,693][[Thread-40] - HTTP STATUS CODE : 200
[INFO][2024-10-23 11:08:14,693][[com.automatics.executor.Starter.main()] - ********  EXECUTION COMPLETED *********
[WARNING] thread Thread[ForkJoinPool.commonPool-worker-3,5,com.automatics.executor.Starter] was interrupted but is still alive after waiting at least 15000msecs
[WARNING] thread Thread[ForkJoinPool.commonPool-worker-3,5,com.automatics.executor.Starter] will linger despite being asked to die via interruption
[WARNING] thread Thread[ForkJoinPool.commonPool-worker-5,5,com.automatics.executor.Starter] will linger despite being asked to die via interruption
[WARNING] thread Thread[ForkJoinPool.commonPool-worker-7,5,com.automatics.executor.Starter] will linger despite being asked to die via interruption
[WARNING] NOTE: 3 thread(s) did not finish despite being asked to  via interruption. This is not a problem with exec:java, it is a problem with the running code. Although not serious, it should be remedied.
[WARNING] Couldn't destroy threadgroup org.codehaus.mojo.exec.ExecJavaMojo$IsolatedThreadGroup[name=com.automatics.executor.Starter,maxpri=10]
java.lang.IllegalThreadStateException
    at java.lang.ThreadGroup.destroy (ThreadGroup.java:776)
    at org.codehaus.mojo.exec.ExecJavaMojo.execute (ExecJavaMojo.java:293)
    at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo (DefaultBuildPluginManager.java:137)
    at org.apache.maven.lifecycle.internal.MojoExecutor.doExecute2 (MojoExecutor.java:370)
    at org.apache.maven.lifecycle.internal.MojoExecutor.doExecute (MojoExecutor.java:351)
    at org.apache.maven.lifecycle.internal.MojoExecutor.execute (MojoExecutor.java:215)
    at org.apache.maven.lifecycle.internal.MojoExecutor.execute (MojoExecutor.java:171)
    at org.apache.maven.lifecycle.internal.MojoExecutor.execute (MojoExecutor.java:163)
    at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject (LifecycleModuleBuilder.java:117)
    at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject (LifecycleModuleBuilder.java:81)
    at org.apache.maven.lifecycle.internal.builder.singlethreaded.SingleThreadedBuilder.build (SingleThreadedBuilder.java:56)
    at org.apache.maven.lifecycle.internal.LifecycleStarter.execute (LifecycleStarter.java:128)
    at org.apache.maven.DefaultMaven.doExecute (DefaultMaven.java:294)
    at org.apache.maven.DefaultMaven.doExecute (DefaultMaven.java:192)
    at org.apache.maven.DefaultMaven.execute (DefaultMaven.java:105)
    at org.apache.maven.cli.MavenCli.execute (MavenCli.java:960)
    at org.apache.maven.cli.MavenCli.doMain (MavenCli.java:293)
    at org.apache.maven.cli.MavenCli.main (MavenCli.java:196)
    at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0 (Native Method)
    at jdk.internal.reflect.NativeMethodAccessorImpl.invoke (NativeMethodAccessorImpl.java:62)
    at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke (DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke (Method.java:566)
    at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced (Launcher.java:282)
    at org.codehaus.plexus.classworlds.launcher.Launcher.launch (Launcher.java:225)
    at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode (Launcher.java:406)
    at org.codehaus.plexus.classworlds.launcher.Launcher.main (Launcher.java:347)
[INFO] ------------------------------------------------------------------------
[INFO] BUILD SUCCESS
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  01:02 min
[INFO] Finished at: 2024-10-23T11:08:29+05:30
[INFO] ------------------------------------------------------------------------
Inside Add Shutdown Hook
[INFO][2024-10-23 11:08:29,705][[Thread-1] - Bean testInitializer is not configured.
[INFO][2024-10-23 11:08:29,705][[Thread-1] - Closing partner application context
[INFO][2024-10-23 11:08:29,705][[Thread-1] - Closing core application context
[Scriptless1] $ /bin/sh -xe /tmp/jenkins1947597328765841946.sh
+ /var/lib/jenkins/pre-test-config.sh -g com.automatics.providers -a rpi-provider-impl -v 0.0.1-SNAPSHOT
Mode: RDKB
Dependency already exist.
Dependency already exist.
Adding dependency <groupId>com.automatics.providers</groupId><artifactId>rpi-provider-impl</artifactId><version>0.0.1-SNAPSHOT</version>
Finished updating pom.xml
Archiving artifacts
Finished: SUCCESS