search

omec-project / pfcpsim

PFCP client simulator used for UPF testing
18 stars 18 forks source link

PFCP Simulator Not Responding To Heartbeat #61

Closed infinitydon closed 1 year ago

infinitydon commented 1 year ago

Hi,

I am trying to use pfcpsim with a UPF, the association is formed as shown below:

[ec2-user@ip-172-31-18-255 pfpcp-sim]$ kubectl logs deploy/oai-pfcpsim
time="2022-09-25T00:55:11Z" level=info msg="Server listening on port 54321"
time="2022-09-25T00:56:38Z" level=info msg="Server is configured. Remote peer address: 10.0.4.19, N3 interface address: 10.0.3.184 "
time="2022-09-25T00:56:38Z" level=info msg="Association established"

But after a short time the UPF complains that the pfcpsim is not responding to heartbeat:

09/25 00:56:38.920: [upf] INFO: PFCP associated (../src/upf/pfcp-sm.c:173)
09/25 00:56:39.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:39.921: [pfcp] DEBUG: [2] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:39.921: [pfcp] DEBUG: [2] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:39.921: [pfcp] DEBUG: [2] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:39.921: [pfcp] DEBUG: [2] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:39.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:39.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:39.921: [pfcp] DEBUG: [2] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:39.921: [pfcp] DEBUG: [2] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:40.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:40.921: [pfcp] DEBUG: [3] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:40.921: [pfcp] DEBUG: [3] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:40.921: [pfcp] DEBUG: [3] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:40.921: [pfcp] DEBUG: [3] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:40.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:40.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:40.921: [pfcp] DEBUG: [3] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:40.921: [pfcp] DEBUG: [3] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:41.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:41.921: [pfcp] DEBUG: [4] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:41.921: [pfcp] DEBUG: [4] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:41.921: [pfcp] DEBUG: [4] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:41.921: [pfcp] DEBUG: [4] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:41.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:41.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:41.921: [pfcp] DEBUG: [4] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:41.921: [pfcp] DEBUG: [4] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:42.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:42.921: [pfcp] DEBUG: [5] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:42.921: [pfcp] DEBUG: [5] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:42.921: [pfcp] DEBUG: [5] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:42.921: [pfcp] DEBUG: [5] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:42.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:42.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:42.921: [pfcp] DEBUG: [5] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:42.921: [pfcp] DEBUG: [5] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:43.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:43.921: [pfcp] DEBUG: [6] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:43.921: [pfcp] DEBUG: [6] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:43.921: [pfcp] DEBUG: [6] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:43.921: [pfcp] DEBUG: [6] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:43.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:43.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:43.921: [pfcp] DEBUG: [6] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:43.921: [pfcp] DEBUG: [6] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:44.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:44.921: [pfcp] DEBUG: [7] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:44.921: [pfcp] DEBUG: [7] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:44.921: [pfcp] DEBUG: [7] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:44.922: [pfcp] DEBUG: [7] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:44.922: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:44.922: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:44.922: [pfcp] DEBUG: [7] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:44.922: [pfcp] DEBUG: [7] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:45.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:45.921: [pfcp] DEBUG: [8] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:45.921: [pfcp] DEBUG: [8] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:45.921: [pfcp] DEBUG: [8] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:45.921: [pfcp] DEBUG: [8] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:45.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:45.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:45.921: [pfcp] DEBUG: [8] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:45.921: [pfcp] DEBUG: [8] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:46.421: [pfcp] DEBUG: [1] REMOTE Holding Timeout for step 2 type 6 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:46.421: [pfcp] DEBUG: [1] REMOTE Delete Transaction for step 2 type 6 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:46.421: [pfcp] DEBUG: [1] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:46.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:46.921: [pfcp] DEBUG: [9] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:46.921: [pfcp] DEBUG: [9] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:46.921: [pfcp] DEBUG: [9] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:46.921: [pfcp] DEBUG: [9] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:46.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:46.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:46.921: [pfcp] DEBUG: [9] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:46.921: [pfcp] DEBUG: [9] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:47.423: [pfcp] DEBUG: [2] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:47.423: [pfcp] DEBUG: [2] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:47.423: [pfcp] DEBUG: [2] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:47.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:47.921: [pfcp] DEBUG: [10] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:47.921: [pfcp] DEBUG: [10] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:47.921: [pfcp] DEBUG: [10] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:47.921: [pfcp] DEBUG: [10] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:47.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:47.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:47.921: [pfcp] DEBUG: [10] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:47.921: [pfcp] DEBUG: [10] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:48.423: [pfcp] DEBUG: [3] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:48.423: [pfcp] DEBUG: [3] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:48.423: [pfcp] DEBUG: [3] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:48.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:48.921: [pfcp] DEBUG: [11] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:48.921: [pfcp] DEBUG: [11] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:48.921: [pfcp] DEBUG: [11] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:48.921: [pfcp] DEBUG: [11] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:48.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:48.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:48.921: [pfcp] DEBUG: [11] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:48.921: [pfcp] DEBUG: [11] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:49.423: [pfcp] DEBUG: [4] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:49.423: [pfcp] DEBUG: [4] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:49.423: [pfcp] DEBUG: [4] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:49.920: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_TIMER (../src/upf/upf-sm.c:51)
09/25 00:56:49.920: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_TIMER (../src/upf/pfcp-sm.c:164)
09/25 00:56:49.920: [pfcp] DEBUG: [5] LOCAL  Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:112)
09/25 00:56:49.920: [pfcp] DEBUG: Heartbeat Request (../lib/pfcp/build.c:27)
09/25 00:56:49.921: [pfcp] DEBUG: [5] LOCAL  UPD TX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:49.921: [pfcp] DEBUG: [5] LOCAL  Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:49.921: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51)
09/25 00:56:49.921: [pfcp] DEBUG: [12] Cannot find xact type 1 from PFCP peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:795)
09/25 00:56:49.921: [pfcp] DEBUG: [12] REMOTE Create  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:155)
09/25 00:56:49.921: [pfcp] DEBUG: [12] REMOTE Receive peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:700)
09/25 00:56:49.921: [pfcp] DEBUG: [12] REMOTE UPD RX-1  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:293)
09/25 00:56:49.921: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:164)
09/25 00:56:49.921: [pfcp] DEBUG: Heartbeat Response (../lib/pfcp/build.c:44)
09/25 00:56:49.921: [pfcp] DEBUG: [12] REMOTE UPD TX-2  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:192)
09/25 00:56:49.921: [pfcp] DEBUG: [12] REMOTE Commit  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:471)
09/25 00:56:50.422: [pfcp] DEBUG: [5] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:50.422: [pfcp] DEBUG: [5] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:50.422: [pfcp] DEBUG: [5] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:51.422: [pfcp] DEBUG: [6] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:51.422: [pfcp] DEBUG: [6] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:51.422: [pfcp] DEBUG: [6] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:52.423: [pfcp] DEBUG: [5] LOCAL  Response Timeout for step 1 type 1 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:590)
09/25 00:56:52.423: [pfcp] DEBUG: [7] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:52.423: [pfcp] DEBUG: [7] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:52.423: [pfcp] DEBUG: [7] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:53.423: [pfcp] DEBUG: [8] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:53.423: [pfcp] DEBUG: [8] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:53.423: [pfcp] DEBUG: [8] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:54.423: [pfcp] DEBUG: [9] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:54.423: [pfcp] DEBUG: [9] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:54.423: [pfcp] DEBUG: [9] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:54.924: [pfcp] DEBUG: [5] LOCAL  Response Timeout for step 1 type 1 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:590)
09/25 00:56:55.423: [pfcp] DEBUG: [10] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:55.423: [pfcp] DEBUG: [10] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:55.423: [pfcp] DEBUG: [10] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:56.423: [pfcp] DEBUG: [11] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:56.423: [pfcp] DEBUG: [11] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:56.423: [pfcp] DEBUG: [11] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:57.423: [pfcp] DEBUG: [12] REMOTE Holding Timeout for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:641)
09/25 00:56:57.423: [pfcp] DEBUG: [12] REMOTE Delete Transaction for step 2 type 2 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:654)
09/25 00:56:57.423: [pfcp] DEBUG: [12] REMOTE Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:57.424: [pfcp] DEBUG: [5] LOCAL  Response Timeout for step 1 type 1 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:590)
09/25 00:56:57.424: [pfcp] WARNING: [5] LOCAL  No Reponse. Give up! for step 1 type 1 peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:613)
09/25 00:56:57.424: [pfcp] DEBUG: [5] LOCAL  Delete  peer [10.0.4.20]:38173 (../lib/pfcp/xact.c:809)
09/25 00:56:57.424: [upf] DEBUG: upf_state_operational(): UPF_EVT_N4_NO_HEARTBEAT (../src/upf/upf-sm.c:51)
09/25 00:56:57.424: [upf] DEBUG: upf_pfcp_state_associated(): UPF_EVT_N4_NO_HEARTBEAT (../src/upf/pfcp-sm.c:164)
09/25 00:56:57.424: [upf] WARNING: No Heartbeat from SMF [10.0.4.20]:38173 (../src/upf/pfcp-sm.c:256)
09/25 00:56:57.424: [upf] DEBUG: upf_pfcp_state_associated(): EXIT (../src/upf/pfcp-sm.c:164)
09/25 00:56:57.424: [upf] INFO: PFCP de-associated (../src/upf/pfcp-sm.c:178)

I have also attached the PCAP below:

pod-pfcp-sim-upf.zip

Any help assistance will be appreciated.

osinstom commented 1 year ago

Hi @infinitydon,

pfcpsim does not currently support UPF-initiated heartbeats. As you can see in the attached PCAP file heartbeats exchange is successful when initiated by pfcpsim.

If you need UPF-initiated heartbeats feel free to open a PR. The implementation should go into https://github.com/omec-project/pfcpsim/blob/main/pkg/pfcpsim/pfcpsim.go.

infinitydon commented 1 year ago

Thanks @osinstom - I will open the PR once am able to add this feature