[2024-06-12 13:28:40,494] : ###STARTING TEST CASE: 3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000
[2024-06-12 13:28:40,498] : Starting execution in settop -E4:5F:01:F4:EF:A6
[2024-06-12 13:28:40,501] : # STEP_1 : Get ecmmac of the device #
[2024-06-12 13:28:40,501] : # STEP_2 : Disabling DMZ through webconfig #
[2024-06-12 13:28:40,501] : # STEP_3 : set forcesync to root #
[2024-06-12 13:28:40,501] : # STEP_4 : Check the state of DMZ #
[2024-06-12 13:28:40,502] : # STEP_5 : Check Success Log line in WebConfig log #
[2024-06-12 13:28:40,502] : # STEP_6 : Check Success Log line in PAM logs #

[2024-06-12 13:28:40,516] : Device Model mapped to Automatics model name : Rpi-RDKB
[2024-06-12 13:28:40,517] : Packages to scan : com.automatics.,com.automatics.core.,com.automatics.scriptless.
[2024-06-12 13:28:41,579] : Total APIs :: 174
[2024-06-12 13:28:41,617] : AutomaticsScriptlessTapApi instance not available. Creating new instance
[2024-06-12 13:28:41,621] : TR181 Access Method: WEBPA
[2024-06-12 13:28:41,623] : getWebPaParamValue: Device.DeviceInfo.X_COMCAST-COM_CM_MAC
[2024-06-12 13:28:41,624] : Reading webpaProvider from partner partner-applicationContext.xml
[2024-06-12 13:28:41,624] : Since webpaProvider not configured in partner, reading default implementation in Automatics.
[2024-06-12 13:28:41,625] : COMPLETE URL BUILD FOR "GET" IS : http://52.88.248.43:9003/api/v2/device/mac:E45F01F4EFA6/config?names=Device.DeviceInfo.X_COMCAST-COM_CM_MAC
[2024-06-12 13:28:41,625] : Reading webpaProvider from partner partner-applicationContext.xml
[2024-06-12 13:28:41,625] : Since webpaProvider not configured in partner, reading default implementation in Automatics.
[2024-06-12 13:28:41,629] : REST CLIENT - Processing request
[2024-06-12 13:28:42,064] : REST CLIENT - Response from - http://52.88.248.43:9003/api/v2/device/mac:E45F01F4EFA6/config?names=Device.DeviceInfo.X_COMCAST-COM_CM_MAC is - 200
[2024-06-12 13:28:42,065] : RESPONSE RECIEVED FOR WEBPA GET REQUEST: 200
[2024-06-12 13:28:42,065] : WEBPA RESPONSE : {"parameters":[{"name":"Device.DeviceInfo.X_COMCAST-COM_CM_MAC","value":"E4:5F:01:F4:EF:A6","dataType":0,"parameterCount":1,"message":"Success"}],"statusCode":200}
[2024-06-12 13:28:42,068] : WebPa data type: STRING
[2024-06-12 13:28:42,068] : TR181 parametrs status code added 0
[2024-06-12 13:28:42,069] : TR181 Response : [tableName=null, index=null, name=Device.DeviceInfo.X_COMCAST-COM_CM_MAC, protocolSpecificParamName=null, value=E4:5F:01:F4:EF:A6, datatype=STRING, statusCode=0]
[2024-06-12 13:28:42,069] : >>>>>> ACTUAL OUTPUT: E4:5F:01:F4:EF:A6
[2024-06-12 13:28:42,070] : >>>>>> EXPECTED OUTPUT: NA
[2024-06-12 13:28:42,070] : >>>>>> VALIDATION PERFORMED: NOT_NULL
[2024-06-12 13:28:42,070] : >>>>>> VALIDATION STATUS: true
[2024-06-12 13:28:42,070] : >>>>>> INVERT RESULT: false

[2024-06-12 13:28:42,077] : [STB MAC : E4:5F:01:F4:EF:A6][ Manual test ID : 3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000] [step Number : S1][ Execution status : PASS] [Error Message : ]
[2024-06-12 13:28:42,078] : Fetching device property: FIRMWARE_VERSION
[2024-06-12 13:28:42,083] : Fetching device props for E4:5F:01:F4:EF:A6 for props [FIRMWARE_VERSION] Url Path: https://automatics-apps.rdkcentral.com/DeviceManager/deviceManagement/getDeviceProps
[2024-06-12 13:28:42,194] : Response: {"mac":"E4:5F:01:F4:EF:A6","HEAD_END":null,"FIRMWARE_VERSION":"rdkb-generic","ECM_IP_ADDRESS":null,"ESTB_IP_ADDRESS":null}
[2024-06-12 13:28:42,195] : Getting value for property: FIRMWARE_VERSION
[2024-06-12 13:28:42,195] : Value: rdkb-generic
[2024-06-12 13:28:42,195] : Device property name: FIRMWARE_VERSION value obtained: rdkb-generic
[2024-06-12 13:28:42,197] : Result Details ->{"JMD_ID":"2904","manualId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000","stepNumber":"S1","testType":"GROUP_OR_AUTOID","buildName":"rdkb-generic-broadband-image_rdkb-2024q1-kirkstone_20240417053101","macAddress":"E4:5F:01:F4:EF:A6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"RDKM","automationId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000"}
[2024-06-12 13:28:42,231] : Execution result updated successfully

[2024-06-12 13:28:42,233] : Device Model mapped to Automatics model name : Rpi-RDKB
[2024-06-12 13:28:42,233] : [BEFORE]: curl -s -i "http://webconfig.rdkcentral.com:9008/api/v1/device/{{ecmmac}}/document/wan?param_name=Device.NAT.X_CISCO_COM_DMZ.Data" -H 'Content-type: application/json' --data '{ "Enable":false,"InternalIP":"10.0.0.17"}' -X POST
[2024-06-12 13:28:42,233] : [AFTER]: curl -s -i "http://webconfig.rdkcentral.com:9008/api/v1/device/E4:5F:01:F4:EF:A6/document/wan?param_name=Device.NAT.X_CISCO_COM_DMZ.Data" -H 'Content-type: application/json' --data '{ "Enable":false,"InternalIP":"10.0.0.17"}' -X POST
[2024-06-12 13:28:42,274] : Entering executeCommandUsingSsh method
[2024-06-12 13:28:42,358] : Default SSH custom port is set
<=========================== RESPONSE =======================>
HTTP/1.1 200 OK

Server: Werkzeug/2.3.4 Python/3.10.12

Date: Wed, 12 Jun 2024 13:28:44 GMT

Content-Type: application/json

Content-Length: 21

Connection: close

{"status":"success"}

<=============================================================>

Server: Werkzeug/2.3.4 Python/3.10.12

Date: Wed, 12 Jun 2024 13:28:44 GMT

Content-Type: application/json

Content-Length: 21

Connection: close

{"status":"success"}


[2024-06-12 13:28:45,297] : Exiting executeCommandUsingSsh method
[2024-06-12 13:28:45,298] : Presence of parameter 200 in the command output : true
[2024-06-12 13:28:45,298] : >>>>>> ACTUAL OUTPUT: HTTP/1.1 200 OK

Server: Werkzeug/2.3.4 Python/3.10.12

Date: Wed, 12 Jun 2024 13:28:44 GMT

Content-Type: application/json

Content-Length: 21

Connection: close

{"status":"success"}


[2024-06-12 13:28:45,298] : >>>>>> EXPECTED OUTPUT: 200
[2024-06-12 13:28:45,298] : >>>>>> VALIDATION PERFORMED: CONTAINS
[2024-06-12 13:28:45,298] : >>>>>> VALIDATION STATUS: true
[2024-06-12 13:28:45,298] : >>>>>> INVERT RESULT: false

[2024-06-12 13:28:45,301] : [STB MAC : E4:5F:01:F4:EF:A6][ Manual test ID : 3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000] [step Number : S2][ Execution status : PASS] [Error Message : ]
[2024-06-12 13:28:45,301] : Result Details ->{"JMD_ID":"2904","manualId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000","stepNumber":"S2","testType":"GROUP_OR_AUTOID","buildName":"rdkb-generic-broadband-image_rdkb-2024q1-kirkstone_20240417053101","macAddress":"E4:5F:01:F4:EF:A6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"RDKM","automationId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000"}
[2024-06-12 13:28:45,331] : Execution result updated successfully

[2024-06-12 13:28:45,332] : Device Model mapped to Automatics model name : Rpi-RDKB
[2024-06-12 13:28:45,348] : Entering executeCommandUsingSsh method
[2024-06-12 13:28:45,443] : Default SSH custom port is set
<=========================== RESPONSE =======================>
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(webconfig): Device.X_RDK_WebConfig.ForceSync
Execution succeed.

<=============================================================>
subsystem_prefix eRT.
setv from/to component(webconfig): Device.X_RDK_WebConfig.ForceSync
Execution succeed.


[2024-06-12 13:28:47,669] : Exiting executeCommandUsingSsh method
[2024-06-12 13:28:47,669] : Presence of parameter Execution succeed. in the command output : true
[2024-06-12 13:28:47,670] : >>>>>> ACTUAL OUTPUT: CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
setv from/to component(webconfig): Device.X_RDK_WebConfig.ForceSync
Execution succeed.


[2024-06-12 13:28:47,670] : >>>>>> EXPECTED OUTPUT: Execution succeed.
[2024-06-12 13:28:47,670] : >>>>>> VALIDATION PERFORMED: CONTAINS
[2024-06-12 13:28:47,670] : >>>>>> VALIDATION STATUS: true
[2024-06-12 13:28:47,670] : >>>>>> INVERT RESULT: false

[2024-06-12 13:28:47,671] : [STB MAC : E4:5F:01:F4:EF:A6][ Manual test ID : 3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000] [step Number : S3][ Execution status : PASS] [Error Message : ]
[2024-06-12 13:28:47,674] : Result Details ->{"JMD_ID":"2904","manualId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000","stepNumber":"S3","testType":"GROUP_OR_AUTOID","buildName":"rdkb-generic-broadband-image_rdkb-2024q1-kirkstone_20240417053101","macAddress":"E4:5F:01:F4:EF:A6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"RDKM","automationId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000"}
[2024-06-12 13:28:47,692] : Execution result updated successfully

[2024-06-12 13:28:47,693] : Device Model mapped to Automatics model name : Rpi-RDKB
[2024-06-12 13:28:47,738] : Entering executeCommandUsingSsh method
[2024-06-12 13:28:47,816] : Default SSH custom port is set
<=========================== RESPONSE =======================>
false

<=============================================================>


[2024-06-12 13:28:50,036] : Exiting executeCommandUsingSsh method
[2024-06-12 13:28:50,036] : Presence of parameter false in the command output : true
[2024-06-12 13:28:50,036] : >>>>>> ACTUAL OUTPUT: false


[2024-06-12 13:28:50,036] : >>>>>> EXPECTED OUTPUT: false
[2024-06-12 13:28:50,036] : >>>>>> VALIDATION PERFORMED: CONTAINS
[2024-06-12 13:28:50,036] : >>>>>> VALIDATION STATUS: true
[2024-06-12 13:28:50,047] : >>>>>> INVERT RESULT: false

[2024-06-12 13:28:50,049] : [STB MAC : E4:5F:01:F4:EF:A6][ Manual test ID : 3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000] [step Number : S4][ Execution status : PASS] [Error Message : ]
[2024-06-12 13:28:50,050] : Result Details ->{"JMD_ID":"2904","manualId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000","stepNumber":"S4","testType":"GROUP_OR_AUTOID","buildName":"rdkb-generic-broadband-image_rdkb-2024q1-kirkstone_20240417053101","macAddress":"E4:5F:01:F4:EF:A6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"RDKM","automationId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000"}
[2024-06-12 13:28:50,085] : Execution result updated successfully

[2024-06-12 13:28:50,086] : Device Model mapped to Automatics model name : Rpi-RDKB
[2024-06-12 13:28:50,098] : COMMAND TO BE EXECUTED: grep -i "setValues success. ccspStatus : 100" /rdklogs/logs/WEBCONFIGlog.txt.0
[2024-06-12 13:29:50,098] : Entering executeCommandUsingSsh method
[2024-06-12 13:29:50,181] : Default SSH custom port is set
<=========================== RESPONSE =======================>
240612-13:27:23.860105 [mod=WEBCONFIG, lvl=INFO] [tid=43378] WEBCONFIG: setValues success. ccspStatus : 100
240612-13:27:23.873492 [mod=WEBCONFIG, lvl=INFO] [tid=43378] WEBCONFIG: setValues success. ccspStatus : 100

<=============================================================>
240612-13:27:23.873492 [mod=WEBCONFIG, lvl=INFO] [tid=43378] WEBCONFIG: setValues success. ccspStatus : 100


[2024-06-12 13:29:52,412] : Exiting executeCommandUsingSsh method
[2024-06-12 13:29:52,413] : SEARCH RESPONSE FOR - setValues success. ccspStatus : 100 IN THE LOG FILE - /rdklogs/logs/WEBCONFIGlog.txt.0 IS : 240612-13:27:23.860105 [mod=WEBCONFIG, lvl=INFO] [tid=43378] WEBCONFIG: setValues success. ccspStatus : 100
240612-13:27:23.873492 [mod=WEBCONFIG, lvl=INFO] [tid=43378] WEBCONFIG: setValues success. ccspStatus : 100
[2024-06-12 13:29:52,413] : >>>>>> ACTUAL OUTPUT: 240612-13:27:23.860105 [mod=WEBCONFIG, lvl=INFO] [tid=43378] WEBCONFIG: setValues success. ccspStatus : 100
240612-13:27:23.873492 [mod=WEBCONFIG, lvl=INFO] [tid=43378] WEBCONFIG: setValues success. ccspStatus : 100
[2024-06-12 13:29:52,413] : >>>>>> EXPECTED OUTPUT: NA
[2024-06-12 13:29:52,413] : >>>>>> VALIDATION PERFORMED: NOT_NULL
[2024-06-12 13:29:52,413] : >>>>>> VALIDATION STATUS: true
[2024-06-12 13:29:52,413] : >>>>>> INVERT RESULT: false

[2024-06-12 13:29:52,415] : [STB MAC : E4:5F:01:F4:EF:A6][ Manual test ID : 3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000] [step Number : S5][ Execution status : PASS] [Error Message : ]
[2024-06-12 13:29:52,415] : Result Details ->{"JMD_ID":"2904","manualId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000","stepNumber":"S5","testType":"GROUP_OR_AUTOID","buildName":"rdkb-generic-broadband-image_rdkb-2024q1-kirkstone_20240417053101","macAddress":"E4:5F:01:F4:EF:A6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"RDKM","automationId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000"}
[2024-06-12 13:29:52,435] : Execution result updated successfully

[2024-06-12 13:29:52,436] : Device Model mapped to Automatics model name : Rpi-RDKB
[2024-06-12 13:29:52,444] : COMMAND TO BE EXECUTED: grep -i "messageQueueProcessing : Execution success , sending completed ACK" /rdklogs/logs/PAMlog.txt.0
[2024-06-12 13:30:52,447] : Entering executeCommandUsingSsh method
[2024-06-12 13:30:52,563] : Default SSH custom port is set
<=========================== RESPONSE =======================>
240612-13:27:23.905020 [mod=PAM, lvl=INFO] [tid=2009] messageQueueProcessing : Execution success , sending completed ACK

<=============================================================>


[2024-06-12 13:30:54,718] : Exiting executeCommandUsingSsh method
[2024-06-12 13:30:54,718] : SEARCH RESPONSE FOR - messageQueueProcessing : Execution success , sending completed ACK IN THE LOG FILE - /rdklogs/logs/PAMlog.txt.0 IS : 240612-13:27:23.905020 [mod=PAM, lvl=INFO] [tid=2009] messageQueueProcessing : Execution success , sending completed ACK
[2024-06-12 13:30:54,718] : >>>>>> ACTUAL OUTPUT: 240612-13:27:23.905020 [mod=PAM, lvl=INFO] [tid=2009] messageQueueProcessing : Execution success , sending completed ACK
[2024-06-12 13:30:54,718] : >>>>>> EXPECTED OUTPUT: NA
[2024-06-12 13:30:54,718] : >>>>>> VALIDATION PERFORMED: NOT_NULL
[2024-06-12 13:30:54,718] : >>>>>> VALIDATION STATUS: true
[2024-06-12 13:30:54,718] : >>>>>> INVERT RESULT: false

[2024-06-12 13:30:54,720] : [STB MAC : E4:5F:01:F4:EF:A6][ Manual test ID : 3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000] [step Number : S6][ Execution status : PASS] [Error Message : ]
[2024-06-12 13:30:54,720] : Result Details ->{"JMD_ID":"2904","manualId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000","stepNumber":"S6","testType":"GROUP_OR_AUTOID","buildName":"rdkb-generic-broadband-image_rdkb-2024q1-kirkstone_20240417053101","macAddress":"E4:5F:01:F4:EF:A6","remarks":"","executionStatus":"PASS","skipRemaining":false,"partnerName":"RDKM","automationId":"3DOT0-TC-RDKB-DMZ_WEBCONFIG-1000"}
[2024-06-12 13:30:54,753] : Execution result updated successfully
[2024-06-12 13:30:54,754] : Execution Completed in settop -E4:5F:01:F4:EF:A6
[2024-06-12 13:30:54,754] : Mac:: E4:5F:01:F4:EF:A6 Status:: true Total Count:: 0