Closed gruyaume closed 1 month ago
@gruyaume, given that we (Aether-OnRamp) use the upf-adapter
by default, the patch does not work as is. So, changes need to be made to the upf-adapter
to be able to test your patch with OnRamp. Is it ok to add commits (related to the upf-adapter
) to your PR?
@gruyaume, given that we (Aether-OnRamp) use the
upf-adapter
by default, the patch does not work as is. So, changes need to be made to theupf-adapter
to be able to test your patch with OnRamp. Is it ok to add commits (related to theupf-adapter
) to your PR?
Can you provide more information as to how the patch does not work as is? The change here is not supposed to break anything with upf-adapter
. And yes, I can make the changes to upf-adapter
as well.
Can you provide more information as to how the patch does not work as is? The change here is not supposed to break anything with
upf-adapter
. And yes, I can make the changes toupf-adapter
as well.
Hi @gruyaume, here is the log (first, a summary and then, in the collapsable box, the complete log) in the upf-adapter
...
2024-07-29T16:12:58Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{<nil> {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler)
2024-07-29T16:12:58Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler)
...
+ cd /free5gc + cat config/config.yaml info: description: UPF Adapter initial local configuration version: 1.0.0 logger: level: info mongodb: name: free5gc url: mongodb://mongodb-arbiter-headless+ GOTRACEBACK=crash ./bin/upf-adapter config/config.yaml 2024-07-29T16:12:01Z[37m [DEBU][UADP][App] [0mListen on 10.42.0.79:8805 (/go/src/smf/upfadapter/pfcp/udp/udp.go:49 upf-adapter/pfcp/udp.Run) 2024-07-29T16:12:58Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{{0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:12:58Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:13:08Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:13:08Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:13:18Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:13:18Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:13:28Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:13:28Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:13:38Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:13:38Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:13:48Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:13:48Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:13:58Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:13:58Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:14:09Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:14:09Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:14:19Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:14:19Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:14:29Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:14:29Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) 2024-07-29T16:14:39Z[37m [DEBU][UADP][App] [0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{ {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:14:39Z[31m [ERRO][UADP][App] [0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler)
And, as you can see here, only the Registration
test passes because there is no UPF (upf-adapter
) involvement
time="2024-07-29T16:13:47Z" level=info msg="Profile Name: profile1 , Profile Type: register" time="2024-07-29T16:13:47Z" level=info msg="Profile Status: PASS" time="2024-07-29T16:13:50Z" level=info msg="Profile Name: profile2 , Profile Type: pdusessest" time="2024-07-29T16:13:50Z" level=info msg="Profile Errors:" time="2024-07-29T16:13:50Z" level=error msg="imsi:imsi-208930100007511, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:50Z" level=error msg="imsi:imsi-208930100007512, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:50Z" level=error msg="imsi:imsi-208930100007510, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:50Z" level=error msg="imsi:imsi-208930100007513, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:50Z" level=error msg="imsi:imsi-208930100007514, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:50Z" level=info msg="Profile Status: FAIL" time="2024-07-29T16:13:52Z" level=info msg="Profile Name: profile3 , Profile Type: anrelease" time="2024-07-29T16:13:52Z" level=info msg="Profile Errors:" time="2024-07-29T16:13:52Z" level=error msg="imsi:imsi-208930100007521, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:52Z" level=error msg="imsi:imsi-208930100007522, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:52Z" level=error msg="imsi:imsi-208930100007524, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:52Z" level=error msg="imsi:imsi-208930100007520, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:52Z" level=error msg="imsi:imsi-208930100007523, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:13:52Z" level=info msg="Profile Status: FAIL" time="2024-07-29T16:14:03Z" level=info msg="Profile Name: profile4 , Profile Type: uetriggservicereq" time="2024-07-29T16:14:03Z" level=info msg="Profile Errors:" time="2024-07-29T16:14:03Z" level=error msg="imsi:imsi-208930100007530, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:03Z" level=error msg="imsi:imsi-208930100007531, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:03Z" level=error msg="imsi:imsi-208930100007532, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:03Z" level=error msg="imsi:imsi-208930100007533, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:03Z" level=error msg="imsi:imsi-208930100007534, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:03Z" level=info msg="Profile Status: FAIL" time="2024-07-29T16:14:13Z" level=info msg="Profile Name: profile5 , Profile Type: deregister" time="2024-07-29T16:14:13Z" level=info msg="Profile Errors:" time="2024-07-29T16:14:13Z" level=error msg="imsi:imsi-208930100007540, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:13Z" level=error msg="imsi:imsi-208930100007541, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:13Z" level=error msg="imsi:imsi-208930100007542, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:13Z" level=error msg="imsi:imsi-208930100007543, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:13Z" level=error msg="imsi:imsi-208930100007544, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:13Z" level=info msg="Profile Status: FAIL" time="2024-07-29T16:14:16Z" level=info msg="Profile Name: profile7 , Profile Type: uereqpdusessrelease" time="2024-07-29T16:14:16Z" level=info msg="Profile Errors:" time="2024-07-29T16:14:16Z" level=error msg="imsi:imsi-208930100007560, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:16Z" level=error msg="imsi:imsi-208930100007562, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:16Z" level=error msg="imsi:imsi-208930100007564, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:16Z" level=error msg="imsi:imsi-208930100007561, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:16Z" level=error msg="imsi:imsi-208930100007563, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T16:14:16Z" level=info msg="Profile Status: FAIL"
Can you provide more information as to how the patch does not work as is? The change here is not supposed to break anything with
upf-adapter
. And yes, I can make the changes toupf-adapter
as well.Hi @gruyaume, here is the log (first, a summary and then, in the collapsable box, the complete log) in the
upf-adapter
... 2024-07-29T16:12:58Z�[37m [DEBU][UADP][App] �[0mreceived msg type [0], upf nodeId [upf], smfIp [10.42.0.80], msg [{<nil> {0 0 0 0 0 0 0 0}}] (/go/src/smf/upfadapter/main.go:30 main.handler) 2024-07-29T16:12:58Z�[31m [ERRO][UADP][App] �[0mError HttpLib received pfcp Rsp (/go/src/smf/upfadapter/main.go:35 main.handler) ...
UPF-ADAPTER log (complete) And, as you can see here, only the
Registration
test passes because there is no UPF (upf-adapter
) involvementAehter OnRamp Output
I went ahead and refactored upfadapter to also use the go-pfcp library. My confidence in this part of the change is quite low though as there is barely any test in this part of the code.
I went ahead and refactored upfadapter to also use the go-pfcp library. My confidence in this part of the change is quite low though as there is barely any test in this part of the code.
Thanks... Let me take a look at it and run a couple of tests with the upf-adapter
I went ahead and refactored upfadapter to also use the go-pfcp library. My confidence in this part of the change is quite low though as there is barely any test in this part of the code.
Test still fails. However, there is more progress in the code execution and as per the upf-adapter
logs, the error is related to pfcpMsg, ok := upf.LastAssoRsp.(*message.AssociationSetupResponse)
func BuildPfcpAssociationResponse(nodeId *types.NodeID, seqNo uint32) (*message.AssociationSetupResponse, error) {
logger.AppLog.Debugf("building pfcp association response for upf [%v], seqNo [%v]", nodeId, seqNo)
if upf := config.GetUpfFromNodeId(nodeId); upf != nil {
pfcpMsg, ok := upf.LastAssoRsp.(*message.AssociationSetupResponse)
if !ok {
logger.AppLog.Errorf("invalid association setup response")
return nil, fmt.Errorf("invalid association setup response")
}
...
$ k -n omec logs upf-adapter-6b675cdc9d-zg49k + cd /free5gc + cat config/config.yaml info: description: UPF Adapter initial local configuration version: 1.0.0 logger: level: info mongodb: name: free5gc url: mongodb://mongodb-arbiter-headless+ GOTRACEBACK=crash ./bin/upf-adapter config/config.yaml 2024-07-29T19:10:24Z [INFO][UADP][Pfcp] Listen on 10.42.0.87:8805 (/go/src/smf/upfadapter/pfcp/udp/udp.go:220 upf-adapter/pfcp/udp.Run) 2024-07-29T19:11:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 1 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:11:20Z [DEBU][UADP][Config] associated upfs: [ map[]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:11:20Z [DEBU][UADP][Config] upf:[upf] not configured yet (/go/src/smf/upfadapter/config/config.go:117 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:11:20Z [WARN][UADP][App] host [upf] not found in smf dns cache (/go/src/smf/upfadapter/types/nodeid.go:53 upf-adapter/types.(*NodeID).ResolveNodeIdToIp) 2024-07-29T19:11:20Z [INFO][UADP][App] host [upf] dns resolved, updating smf dns cache (/go/src/smf/upfadapter/types/nodeid.go:58 upf-adapter/types.(*NodeID).ResolveNodeIdToIp) 2024-07-29T19:11:20Z [INFO][UADP][Config] inserting upf node [upf] (/go/src/smf/upfadapter/config/config.go:160 upf-adapter/config.InsertUpfNode) 2024-07-29T19:11:20Z [DEBU][UADP][App] association request, existing node id replaced by [10.42.0.87] (/go/src/smf/upfadapter/pfcp/util.go:51 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:11:20Z [DEBU][UADP][Config] inserting transaction with sequence number [1] (/go/src/smf/upfadapter/config/config.go:187 upf-adapter/config.InsertUpfPfcpTxn) 2024-07-29T19:11:20Z [DEBU][UADP][Pfcp] send pfcp association request to upfNodeId [{[117 112 102] 2}], pfcpMsg [{Version: 1, Flags: {FO: false, MP: false, S: false}, Type: 5, Length: 26, SEID: 0x0000000000000000, SequenceNumber: 0x1, MessagePriority: 0, Payload: 0x003c0005000a2a006700600004ea5267d70059000100}] (/go/src/smf/upfadapter/pfcp/message/send.go:20 upf-adapter/pfcp/message.SendPfcpAssociationSetupRequest) 2024-07-29T19:11:20Z [DEBU][UADP][App] host [upf] found in smf dns cache (/go/src/smf/upfadapter/types/nodeid.go:63 upf-adapter/types.(*NodeID).ResolveNodeIdToIp) 2024-07-29T19:11:20Z [DEBU][UADP][Pfcp] send pfcp msg addr [10.42.180.52:8805], pfcpMsg [{Version: 1, Flags: {FO: false, MP: false, S: false}, Type: 5, Length: 26, SEID: 0x0000000000000000, SequenceNumber: 0x1, MessagePriority: 0, Payload: 0x003c0005000a2a006700600004ea5267d70059000100}] (/go/src/smf/upfadapter/pfcp/message/send.go:26 upf-adapter/pfcp/message.SendPfcpAssociationSetupRequest) 2024-07-29T19:11:20Z [DEBU][UADP][Pfcp] HandlePfcpAssociationSetupResponse, recovery timestamp [2024-07-29 19:11:20 +0000 UTC] (/go/src/smf/upfadapter/pfcp/handler/handler.go:106 upf-adapter/pfcp/handler.HandlePfcpAssociationSetupResponse) 2024-07-29T19:11:20Z [DEBU][UADP][Pfcp] node id from pfcp association response [10.42.180.52] (/go/src/smf/upfadapter/pfcp/handler/handler.go:123 upf-adapter/pfcp/handler.HandlePfcpAssociationSetupResponse) 2024-07-29T19:11:20Z [INFO][UADP][Config] activating upf node [&{[10 42 180 52] 0}] (/go/src/smf/upfadapter/config/config.go:165 upf-adapter/config.ActivateUpfNode) 2024-07-29T19:11:20Z [DEBU][UADP][Pfcp] Remove Request Transaction [1] (/go/src/smf/upfadapter/pfcp/udp/udp.go:250 upf-adapter/pfcp/udp.removeTransaction) 2024-07-29T19:11:20Z [DEBU][UADP][Config] getting upf from node id [&{[10 42 180 52] 0}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:20Z [DEBU][UADP][Config] getting upf from node id, ip-addr [&{[10 42 180 52] 0}, 10.42.180.52] successful (/go/src/smf/upfadapter/config/config.go:131 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:20Z [DEBU][UADP][Config] storing pfcp association response for upf [&{upf {[117 112 102] 2} 10.42.180.52 2{{0 0} 0 0 {{} 0} {{} 0}}}] (/go/src/smf/upfadapter/config/config.go:209 upf-adapter/config.(*UPNode).PreservePfcpAssociationRsp) 2024-07-29T19:11:20Z [DEBU][UADP][Config] fetch transaction with sequence number [1] successful (/go/src/smf/upfadapter/config/config.go:199 upf-adapter/config.GetUpfPfcpTxn) 2024-07-29T19:11:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:11:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:11:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 2 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:11:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:11:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:11:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:11:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [2] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:11:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:11:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:11:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 3 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:11:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:11:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:11:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:11:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [3] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:11:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:11:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:11:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 4 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:11:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:11:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:11:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:11:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [4] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:11:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:11:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:11:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:12:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 5 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:12:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:12:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [5] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:12:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 6 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:12:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:12:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [6] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:12:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 7 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:12:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:12:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [7] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:12:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:12:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 8 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:12:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:12:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [8] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:12:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 9 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:12:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:12:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [9] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:12:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 10 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:12:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:12:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:12:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [10] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:12:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:12:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:13:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 11 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:13:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:13:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [11] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:13:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 12 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:13:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:13:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [12] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:13:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 13 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:13:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:13:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [13] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:13:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:13:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 14 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:13:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:13:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [14] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:13:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 15 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:13:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:13:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [15] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:13:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 16 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:13:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:13:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:13:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [16] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:13:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:13:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:14:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 17 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:14:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:14:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [17] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:14:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 18 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:14:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:14:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [18] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:14:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 19 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:14:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:14:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [19] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:14:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:14:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 20 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:14:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:14:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [20] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:14:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 21 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:14:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:14:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [21] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:14:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 22 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:14:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:14:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:14:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [22] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:14:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:14:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:15:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 23 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:15:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:15:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [23] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:15:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 24 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:15:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:15:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [24] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:15:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 25 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:15:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:15:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [25] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:15:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:15:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 26 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:15:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:15:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [26] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:15:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 27 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:15:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:15:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [27] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:15:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 28 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:15:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:15:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:15:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [28] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:15:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:15:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:16:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 29 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:16:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:16:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [29] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:16:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 30 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:16:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:16:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [30] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:16:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 31 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:16:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:16:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [31] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:16:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:16:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 32 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:16:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:16:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [32] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:16:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 33 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:16:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:16:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [33] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:16:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 34 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:16:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:16:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:16:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [34] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:16:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:16:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:17:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 35 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:17:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:17:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [35] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:17:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 36 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:17:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:17:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [36] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:17:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 37 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:17:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:17:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [37] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:17:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:17:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 38 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:17:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:17:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [38] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:17:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 39 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:17:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:17:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [39] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:17:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 40 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:17:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:17:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:17:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [40] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:17:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:17:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:18:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 41 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:18:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:18:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [41] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:18:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 42 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:18:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:18:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [42] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:18:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 43 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:18:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:18:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [43] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:18:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:18:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 44 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:18:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:18:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [44] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:18:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 45 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:18:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:18:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [45] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:18:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 46 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:18:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:18:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:18:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [46] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:18:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:18:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:19:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 47 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:19:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:19:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [47] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:19:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 48 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:19:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:19:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [48] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:19:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 49 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:19:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:19:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [49] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:19:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:19:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 50 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:19:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:19:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [50] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:19:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 51 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:19:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:19:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [51] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:19:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 52 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:19:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:19:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:19:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [52] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:19:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:19:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:20:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 53 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:20:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:20:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [53] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:20:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 54 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:20:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:20:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [54] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:20:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 55 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:20:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:20:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [55] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:20:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:20:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 56 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:20:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:20:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [56] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:20:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 57 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:20:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:20:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [57] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:20:50Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 58 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:20:50Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:50Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:20:50Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:20:50Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [58] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:50Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:50Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:50Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:20:50Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:20:50Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:21:00Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 59 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:21:00Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:00Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:00Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:21:00Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [59] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:00Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:00Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:00Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:00Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:00Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:21:10Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 60 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:21:10Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:10Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:10Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:21:10Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [60] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:10Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:10Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:10Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:10Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:10Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:21:20Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 61 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:21:20Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:20Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:20Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:21:20Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [61] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:20Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:20Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:20Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:20Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:20Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:21:24Z [DEBU][UADP][App] refreshing DNS for host [upf] (/go/src/smf/upfadapter/types/nodeid.go:85 upf-adapter/types.RefreshDnsHostIpCache) 2024-07-29T19:21:30Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 62 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:21:30Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:30Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:30Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:21:30Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [62] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:30Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:30Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:30Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:30Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:30Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler) 2024-07-29T19:21:40Z [DEBU][UADP][App] received msg type [5], upf nodeId [upf], smfIp [10.42.0.103], msg [{[32 5 0 26 0 0 63 0 0 60 0 5 0 10 42 0 103 0 96 0 4 234 82 103 215 0 89 0 1 0]}] (/go/src/smf/upfadapter/main.go:39 main.handler) 2024-07-29T19:21:40Z [DEBU][UADP][Config] associated upfs: [ map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:106 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:40Z [DEBU][UADP][Config] upf:[upf] associated (/go/src/smf/upfadapter/config/config.go:110 upf-adapter/config.IsUpfAssociated) 2024-07-29T19:21:40Z [DEBU][UADP][App] upf[{[117 112 102] 2}] already associated (/go/src/smf/upfadapter/pfcp/util.go:31 upf-adapter/pfcp.ForwardPfcpMsgToUpf) 2024-07-29T19:21:40Z [DEBU][UADP][App] building pfcp association response for upf [&{[117 112 102] 2}], seqNo [63] (/go/src/smf/upfadapter/pfcp/handler/handler.go:192 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:40Z [DEBU][UADP][Config] getting upf from node id [&{[117 112 102] 2}] (/go/src/smf/upfadapter/config/config.go:125 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:40Z [DEBU][UADP][Config] content of upf config [map[upf:0xc0000c0630]] (/go/src/smf/upfadapter/config/config.go:126 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:40Z [DEBU][UADP][Config] getting upf from node id, fqdn [&{[117 112 102] 2}, [117 112 102]] successful (/go/src/smf/upfadapter/config/config.go:137 upf-adapter/config.GetUpfFromNodeId) 2024-07-29T19:21:40Z [ERRO][UADP][App] invalid association setup response (/go/src/smf/upfadapter/pfcp/handler/handler.go:196 upf-adapter/pfcp/handler.BuildPfcpAssociationResponse) 2024-07-29T19:21:40Z [DEBU][UADP][App] response sent for 5 (/go/src/smf/upfadapter/main.go:51 main.handler)
I went ahead and refactored upfadapter to also use the go-pfcp library. My confidence in this part of the change is quite low though as there is barely any test in this part of the code.
Test still fails. However, there is more progress in the code execution and as per the
upf-adapter
logs, the error is related topfcpMsg, ok := upf.LastAssoRsp.(*message.AssociationSetupResponse)
func BuildPfcpAssociationResponse(nodeId *types.NodeID, seqNo uint32) (*message.AssociationSetupResponse, error) { logger.AppLog.Debugf("building pfcp association response for upf [%v], seqNo [%v]", nodeId, seqNo) if upf := config.GetUpfFromNodeId(nodeId); upf != nil { pfcpMsg, ok := upf.LastAssoRsp.(*message.AssociationSetupResponse) if !ok { logger.AppLog.Errorf("invalid association setup response") return nil, fmt.Errorf("invalid association setup response") } ...
UPF-ADAPTER log
I believe I addressed the issue, let me know if/where the code fails now.
(btw we should add some automated e2e test to avoid this back and forth)
I believe I addressed the issue, let me know if/where the code fails now.
The smf
is restarting due to a nil
pointer. As you can see below, 3 E2E test profiles successfully complete but the remaining profiles fail due to the restart of the smf
time="2024-07-29T22:30:15Z" level=info msg="Profile Name: profile1 , Profile Type: register" time="2024-07-29T22:30:15Z" level=info msg="Profile Status: PASS" time="2024-07-29T22:30:22Z" level=info msg="Profile Name: profile2 , Profile Type: pdusessest" time="2024-07-29T22:30:22Z" level=info msg="Profile Status: PASS" time="2024-07-29T22:30:28Z" level=info msg="Profile Name: profile3 , Profile Type: anrelease" time="2024-07-29T22:30:28Z" level=info msg="Profile Status: PASS" time="2024-07-29T22:32:18Z" level=info msg="Profile Name: profile4 , Profile Type: uetriggservicereq" time="2024-07-29T22:32:18Z" level=info msg="Profile Errors:" time="2024-07-29T22:32:18Z" level=error msg="imsi:imsi-208930100007530, profile timeout" time="2024-07-29T22:32:18Z" level=error msg="imsi:imsi-208930100007531, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:18Z" level=error msg="imsi:imsi-208930100007532, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:18Z" level=error msg="imsi:imsi-208930100007533, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:18Z" level=error msg="imsi:imsi-208930100007534, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:18Z" level=info msg="Profile Status: FAIL" time="2024-07-29T22:32:29Z" level=info msg="Profile Name: profile5 , Profile Type: deregister" time="2024-07-29T22:32:29Z" level=info msg="Profile Errors:" time="2024-07-29T22:32:29Z" level=error msg="imsi:imsi-208930100007540, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:29Z" level=error msg="imsi:imsi-208930100007541, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:29Z" level=error msg="imsi:imsi-208930100007542, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:29Z" level=error msg="imsi:imsi-208930100007543, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:29Z" level=error msg="imsi:imsi-208930100007544, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:29Z" level=info msg="Profile Status: FAIL" time="2024-07-29T22:32:31Z" level=info msg="Profile Name: profile7 , Profile Type: uereqpdusessrelease" time="2024-07-29T22:32:31Z" level=info msg="Profile Errors:" time="2024-07-29T22:32:31Z" level=error msg="imsi:imsi-208930100007561, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:31Z" level=error msg="imsi:imsi-208930100007562, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:31Z" level=error msg="imsi:imsi-208930100007560, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:31Z" level=error msg="imsi:imsi-208930100007563, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:31Z" level=error msg="imsi:imsi-208930100007564, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" time="2024-07-29T22:32:31Z" level=info msg="Profile Status: FAIL"
Here is the smf
log showing the reason for the restart: smf-p.log. Also, I am attaching the upf-adapter
log in case you need it: upf-adapter.log
I additionally ran a single E2E test for the UE Initiated Service Request
profile (profile 4) for a single UE and the issue is exactly the same as the one shown in the attached logs.
(btw we should add some automated e2e test to avoid this back and forth)
I certainly agree with you. This is something I still have pending in my to-do list
I believe I addressed the issue, let me know if/where the code fails now.
The
smf
is restarting due to anil
pointer. As you can see below, 3 E2E test profiles successfully complete but the remaining profiles fail due to the restart of thesmf
E2E tests (Aether OnRamp) Here is the
smf
log showing the reason for the restart: smf-p.log. Also, I am attaching theupf-adapter
log in case you need it: upf-adapter.logI additionally ran a single E2E test for the
UE Initiated Service Request
profile (profile 4) for a single UE and the issue is exactly the same as the one shown in the attached logs.(btw we should add some automated e2e test to avoid this back and forth)
I certainly agree with you. This is something I still have pending in my to-do list
Ok thanks, I just fixed this issue in this commit:
Ok thanks, I just fixed this issue in this commit:
FYI, as you can see below, OnRamp
E2E test successfully pass. I am going to review the PR as soon as I can.
time="2024-07-30T15:02:45Z" level=info msg="Profile Name: profile1 , Profile Type: register"
time="2024-07-30T15:02:45Z" level=info msg="Profile Status: PASS"
time="2024-07-30T15:02:51Z" level=info msg="Profile Name: profile2 , Profile Type: pdusessest"
time="2024-07-30T15:02:51Z" level=info msg="Profile Status: PASS"
time="2024-07-30T15:02:58Z" level=info msg="Profile Name: profile3 , Profile Type: anrelease"
time="2024-07-30T15:02:58Z" level=info msg="Profile Status: PASS"
time="2024-07-30T15:03:32Z" level=info msg="Profile Name: profile4 , Profile Type: uetriggservicereq"
time="2024-07-30T15:03:32Z" level=info msg="Profile Status: PASS"
time="2024-07-30T15:04:03Z" level=info msg="Profile Name: profile5 , Profile Type: deregister"
time="2024-07-30T15:04:03Z" level=info msg="Profile Status: PASS"
time="2024-07-30T15:04:10Z" level=info msg="Profile Name: profile7 , Profile Type: uereqpdusessrelease"
time="2024-07-30T15:04:10Z" level=info msg="Profile Status: PASS"
HI @gruyaume, thanks for your contribution. I added some comments. The most relevant are the ones about using files from the
smf
into theupfadapter
. Those comments are mainly to know what your thoughts are about it. However, I see in theupfadapter/go.mod
that it does NOT depend on thesmf
(nosmf
files are imported in theupfadapter
)
I started addressing some of your comments, including the main one around re-using code in upfadapter
. I will continue reviewing your comments later in the day.
HI @gruyaume, thanks for your contribution. I added some comments. The most relevant are the ones about using files from the
smf
into theupfadapter
. Those comments are mainly to know what your thoughts are about it. However, I see in theupfadapter/go.mod
that it does NOT depend on thesmf
(nosmf
files are imported in theupfadapter
)I started addressing some of your comments, including the main one around re-using code in
upfadapter
. I will continue reviewing your comments later in the day.
@gab-arrobo I believe I addressed all your comments. I implemented most of the changes you recommended. I did push back on some of them and left those conversations open, I'll let you close them if you see fit.
@gab-arrobo I believe I addressed all your comments. I implemented most of the changes you recommended. I did push back on some of them and left those conversations open, I'll let you close them if you see fit.
I closed most of the conversations you left open and I left only a few conversations open with my comments. Please let me know what you think. Thnaks!
@gab-arrobo I believe I addressed all your comments. I implemented most of the changes you recommended. I did push back on some of them and left those conversations open, I'll let you close them if you see fit.
I closed most of the conversations you left open and I left only a few conversations open with my comments. Please let me know what you think. Thnaks!
They're all addressed!
@gruyaume, would you like me to create a new (release patch or minor or major) tag?
@gruyaume, would you like me to create a new (release patch or minor or major) tag?
@gab-arrobo this would probably count as a minor tag bump (no user facing change).
@gruyaume, would you like me to create a new (release patch or minor or major) tag?
@gab-arrobo this would probably count as a minor tag bump (no user facing change).
@gruyaume, what about enabling some linters (https://github.com/omec-project/smf/blob/master/.golangci.yml#L238) before we create the minor release?
@gruyaume, would you like me to create a new (release patch or minor or major) tag?
@gab-arrobo this would probably count as a minor tag bump (no user facing change).
@gruyaume, what about enabling some linters (https://github.com/omec-project/smf/blob/master/.golangci.yml#L238) before we create the minor release?
Here's the first of such PRs:
@gruyaume, would you like me to create a new (release patch or minor or major) tag?
@gab-arrobo this would probably count as a minor tag bump (no user facing change).
@gruyaume, what about enabling some linters (https://github.com/omec-project/smf/blob/master/.golangci.yml#L238) before we create the minor release?
Here's the first of such PRs:
Thanks. I am currently reviewing it.
BTW, when you are ready for the creation of a new tag (e.g. minor release, as we discussed before), you can open a PR with the following change:
diff --git a/VERSION b/VERSION
index f9f73cc..bc80560 100644
--- a/VERSION
+++ b/VERSION
@@ -1 +1 @@
-1.4.4-dev
+1.5.0
Description
Replace the omec PFCP package with go-pfcp allowing for the deprecation of the following projects:
Doing so, we also move all pfcp specific code to the
pfcp/
directory. In other words, you won't see import ofgo-pfcp
anywhere else than in/pfcp
. Imports ofomec-project/pfcp
used to be present all over the code base, and this is why replacing the package requires so large of a code change.Rationale
Ease of life for developers
We are currently using two different projects for PFCP communication between the SMF and the UPF making it hard to understand and contribute changes involving PFCP communication. The UPF uses
wmnsk/go-pfcp
and the SMF usesomec-project/pfcp
, both with the same purpose. By depending on the same project for PFCP communication we make it simpler to execute code changes.Security and reliability
go-pfcp
is well maintained, tracks changes to 3GPP releases, has 53 dependents (making it more likely to receive bug fixes and security patches), and has only 1 dependency (go-cmp).Maintainability
Archiving omec-project/pfcp repository would reduce by 2 the total number of repositories maintained for Aether, making the project easier to support with a limited amount of developers.
We did not refactor the
upf-adapter
sub-projectAt Canonical, we do not use
upf-adapter
and the suite of tests there is too small to be confident that I did not break anything, therefore I decided not to touch this part of the project. I would encourage someone with familiarity with this component to take care of the change as a follow-up change.Better unit test coverage
The SMF lacked proper unit test coverage and through this change I added a bunch, moving the coverage from 12.5% to 18.1%.
No change was made to the PFCP UDP server logic
While the logic to create and parse PFCP messages is in the
go-pfcp
library, the code for the PFCP server here (under thepfcp/udp
directory) was was directly taken from the omec-project/pfcp project. There are many ways this server code could be improved, but I did not want to combine the use PFCP messages from the new library with changes made to the PFCP server.After this PR is merged
upf-adapter
sub-projectFollow up quality improvements
Working on this change, I identified changes that would improve the quality of the projects. The PR was large enough and I did not want to bundle those with the rest of the refactoring.
The main example of this is that structs used to represent concepts like NodeID, SourceInterface, etc. are more complex than they really need to be.
Reference: