Changes:
------------------------------------------ [...truncated 298.08 KB...] + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-pcap-server ● osmo-pcap-server.service - PCAP Server for the PCAP aggregation Loaded: loaded (/usr/lib/systemd/system/osmo-pcap-server.service; disabled; vendor preset: disabled) Active: active (running) since Sat 2023-02-11 05:05:58 EST; 2s ago Main PID: 20806 (osmo-pcap-serve) Tasks: 1 (limit: 4908) Memory: 1.9M CGroup: /system.slice/osmo-pcap-server.service └─20806 /usr/bin/osmo-pcap-server -c /etc/osmocom/osmo-pcap-server.cfg
Feb 11 05:05:58 localhost.localdomain systemd[1]: Started PCAP Server for the PCAP aggregation. Feb 11 05:05:58 localhost.localdomain osmo-pcap-server[20806]: <0005> telnet_interface.c:88 Available via telnet 127.0.0.1 4228 Feb 11 05:05:58 localhost.localdomain osmo-pcap-server[20806]: <0004> osmo_tls.c:44 Going to create DH params for 3072 bits Feb 11 05:06:00 localhost.localdomain osmo-pcap-server[20806]: <0002> osmo_server_network.c:573 New connection from zecke + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-pcu ● osmo-pcu.service - Osmocom osmo-pcu Loaded: loaded (/usr/lib/systemd/system/osmo-pcu.service; disabled; vendor preset: disabled) Active: active (running) since Sat 2023-02-11 05:05:58 EST; 2s ago Main PID: 20808 (osmo-pcu) Tasks: 1 (limit: 4908) Memory: 2.4M CGroup: /system.slice/osmo-pcu.service └─20808 /usr/bin/osmo-pcu -c /etc/osmocom/osmo-pcu.cfg
Feb 11 05:05:58 localhost.localdomain systemd[1]: Started Osmocom osmo-pcu. Feb 11 05:05:58 localhost.localdomain osmo-pcu[20808]: <000f> telnet_interface.c:88 Available via telnet 127.0.0.1 4240 Feb 11 05:05:58 localhost.localdomain osmo-pcu[20808]: <0001> osmobts_sock.c:239 osmo-bts PCU socket /tmp/pcu_bts has been connected Feb 11 05:05:58 localhost.localdomain osmo-pcu[20808]: <0001> pcu_l1_if.cpp:1119 Received message for new BTS0 Feb 11 05:05:58 localhost.localdomain osmo-pcu[20808]: <0001> pcu_l1_if.cpp:732 BTS not available + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-remsim-bankd ● osmo-remsim-bankd.service - Osmocom Remote SIM BankD Loaded: loaded (/usr/lib/systemd/system/osmo-remsim-bankd.service; disabled; vendor preset: disabled) Active: active (running) since Sat 2023-02-11 05:05:58 EST; 2s ago Main PID: 20836 (osmo-remsim-ban) Tasks: 6 (limit: 4908) Memory: 1.4M CGroup: /system.slice/osmo-remsim-bankd.service └─20836 /usr/bin/osmo-remsim-bankd -i 127.0.0.1 -n 5
Feb 11 05:05:58 localhost.localdomain systemd[1]: Started Osmocom Remote SIM BankD. Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/0 -> regex 'ACS ACR33 ICC Reader 00 00' Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/1 -> regex 'ACS ACR33 ICC Reader 00 01' Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/2 -> regex 'ACS ACR33 ICC Reader 00 02' Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/3 -> regex 'ACS ACR33 ICC Reader 00 03' Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/4 -> regex 'ACS ACR33 ICC Reader 00 04' Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/23 -> regex 'Alcor Micro AU9560 00 00' Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [000 B0:0 INIT] Changing state to INIT Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [000 B65535:65535 INIT] Changing state to ACCEPTING Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [001 B0:0 INIT] Changing state to INIT Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [002 B0:0 INIT] Changing state to INIT Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [001 B65535:65535 INIT] Changing state to ACCEPTING Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [003 B0:0 INIT] Changing state to INIT Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [002 B65535:65535 INIT] Changing state to ACCEPTING Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [004 B0:0 INIT] Changing state to INIT Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [003 B65535:65535 INIT] Changing state to ACCEPTING Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DBANKDW INFO bankd_main.c:492 [004 B65535:65535 INIT] Changing state to ACCEPTING Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DRSPRO INFO ../rspro_client_fsm.c:389 RSPRO_CLIENT(server){REESTABLISH}: Creating TCP connection to server at 127.0.0.1:9998 Feb 11 05:05:58 localhost.localdomain osmo-remsim-bankd[20836]: 20836 DRSPRO NOTICE ../rspro_client_fsm.c:147 RSPRO_CLIENT(server){REESTABLISH}: RSPRO link to 127.0.0.1:9998 DOWN + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-remsim-server ● osmo-remsim-server.service - Osmocom Remote SIM Server Loaded: loaded (/usr/lib/systemd/system/osmo-remsim-server.service; disabled; vendor preset: disabled) Active: active (running) since Sat 2023-02-11 05:05:58 EST; 2s ago Main PID: 20853 (osmo-remsim-ser) Tasks: 2 (limit: 4908) Memory: 1.8M CGroup: /system.slice/osmo-remsim-server.service └─20853 /usr/bin/osmo-remsim-server
Feb 11 05:05:58 localhost.localdomain systemd[1]: Started Osmocom Remote SIM Server. + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-sgsn ● osmo-sgsn.service - Osmocom SGSN (Serving GPRS Support Node) Loaded: loaded (/usr/lib/systemd/system/osmo-sgsn.service; disabled; vendor preset: disabled) Active: active (running) since Sat 2023-02-11 05:05:58 EST; 2s ago Main PID: 20854 (osmo-sgsn) Tasks: 1 (limit: 4908) Memory: 3.0M CGroup: /system.slice/osmo-sgsn.service └─20854 /usr/bin/osmo-sgsn -c /etc/osmocom/osmo-sgsn.cfg
Feb 11 05:05:58 localhost.localdomain systemd[1]: Started Osmocom SGSN (Serving GPRS Support Node). Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: rate_ctr.c:87 validating counter group 0x55bc4e5b15c0(sgsn) with 23 counters Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0013> telnet_interface.c:88 Available via telnet 127.0.0.1 4245 Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <001b> gsn.c:431 GTP: gtp_newgsn() started at 127.0.0.1 Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <001b> gsn.c:388 State information file (.//gsn_restart) not found. Creating new file. Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0004> sgsn_libgtp.c:886 Created GTP on 127.0.0.1 Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0004> sgsn_main.c:457 libGTP v1.10.0.1.3506.202302110009 initialized Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <001a> control_if.c:1014 CTRL at 127.0.0.1 4251 Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <001d> gsup_client.c:75 GSUP connecting to 127.0.0.1:4222 Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0020> sccp_user.c:522 OsmoSGSN: Creating SS7 instance Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0020> sccp_user.c:549 OsmoSGSN: Using SS7 instance 0, pc:0.23.4 Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0020> sccp_user.c:556 OsmoSGSN: Creating AS instance Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0020> sccp_user.c:567 OsmoSGSN: Using AS instance as-clnt-OsmoSGSN Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0020> sccp_user.c:572 OsmoSGSN: Creating default route Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0020> sccp_user.c:612 OsmoSGSN: No unassociated ASP for m3ua, creating new ASP asp-clnt-OsmoSGSN Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <0020> sccp_user.c:647 OsmoSGSN: Using ASP instance asp-clnt-OsmoSGSN Feb 11 05:05:58 localhost.localdomain osmo-sgsn[20854]: <001f> osmo_ss7.c:458 0: Creating SCCP instance Feb 11 05:05:59 localhost.localdomain osmo-sgsn[20854]: <001d> gsup_client.c:75 GSUP connecting to 127.0.0.1:4222 Feb 11 05:05:59 localhost.localdomain osmo-sgsn[20854]: <0015> input/ipa.c:141 127.0.0.1:4222 connection done Feb 11 05:05:59 localhost.localdomain osmo-sgsn[20854]: <0015> input/ipaccess.c:920 received ID_GET for unit ID 0/0/0 + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-sip-connector ● osmo-sip-connector.service - Osmo SIP Connector Loaded: loaded (/usr/lib/systemd/system/osmo-sip-connector.service; disabled; vendor preset: disabled) Active: active (running) since Sat 2023-02-11 05:05:58 EST; 2s ago Main PID: 20858 (osmo-sip-connec) Tasks: 1 (limit: 4908) Memory: 2.1M CGroup: /system.slice/osmo-sip-connector.service └─20858 /usr/bin/osmo-sip-connector -c /etc/osmocom/osmo-sip-connector.cfg
Feb 11 05:05:58 localhost.localdomain systemd[1]: Started Osmo SIP Connector. Feb 11 05:05:58 localhost.localdomain osmo-sip-connector[20858]: <0004> telnet_interface.c:88 Available via telnet 127.0.0.1 4256 Feb 11 05:05:58 localhost.localdomain osmo-sip-connector[20858]: <0001> mncc.c:1083 Scheduling MNCC connect Feb 11 05:05:58 localhost.localdomain osmo-sip-connector[20858]: <0000> sip.c:724 su_source_port_create() returns 0x55e25f8154a0 Feb 11 05:05:58 localhost.localdomain osmo-sip-connector[20858]: <0001> mncc.c:975 Failed to connect(/tmp/msc_mncc). Retrying + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-smlc ● osmo-smlc.service - Osmocom Serving Mobile Location Center (SMLC) Loaded: loaded (/usr/lib/systemd/system/osmo-smlc.service; disabled; vendor preset: disabled) Active: active (running) since Sat 2023-02-11 05:05:58 EST; 2s ago Main PID: 20861 (osmo-smlc) Tasks: 1 (limit: 4908) Memory: 1.9M CGroup: /system.slice/osmo-smlc.service └─20861 /usr/bin/osmo-smlc -c /etc/osmocom/osmo-smlc.cfg
Feb 11 05:05:58 localhost.localdomain systemd[1]: Started Osmocom Serving Mobile Location Center (SMLC). Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <0004> telnet_interface.c:88 Available via telnet 127.0.0.1 4271 Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <000b> control_if.c:1014 CTRL at 127.0.0.1 4272 Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <0011> sccp_user.c:522 Lb: Creating SS7 instance Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <0011> sccp_user.c:549 Lb: Using SS7 instance 0, pc:0.23.6 Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <0011> sccp_user.c:556 Lb: Creating AS instance Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <0011> sccp_user.c:567 Lb: Using AS instance as-clnt-Lb Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <0011> sccp_user.c:572 Lb: Creating default route Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <0011> sccp_user.c:612 Lb: No unassociated ASP for m3ua, creating new ASP asp-clnt-Lb Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <0011> sccp_user.c:647 Lb: Using ASP instance asp-clnt-Lb Feb 11 05:05:58 localhost.localdomain osmo-smlc[20861]: <0010> osmo_ss7.c:458 0: Creating SCCP instance + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-stp ● osmo-stp.service - Osmocom STP (Signal Transfer Point) Loaded: loaded (/usr/lib/systemd/system/osmo-stp.service; disabled; vendor preset: disabled) Active: active (running) since Sat 2023-02-11 05:05:58 EST; 2s ago Docs: https://osmocom.org/projects/osmo-stp/wiki Main PID: 20870 (osmo-stp) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-stp.service └─20870 /usr/bin/osmo-stp -c /etc/osmocom/osmo-stp.cfg
Feb 11 05:05:58 localhost.localdomain systemd[1]: Started Osmocom STP (Signal Transfer Point). Feb 11 05:05:58 localhost.localdomain osmo-stp[20870]: DLGLOBAL <0000> telnet_interface.c:88 Available via telnet 127.0.0.1 4239 Feb 11 05:05:58 localhost.localdomain osmo-stp[20870]: DLSS7 <000c> osmo_ss7.c:1645 0: asp-asp-dyn-0: ASP Restart for server not implemented yet! + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-bsc-nat ● osmo-bsc-nat.service - Osmocom BSC NAT Loaded: loaded (/usr/lib/systemd/system/osmo-bsc-nat.service; disabled; vendor preset: disabled) Active: active (running) since Sat 2023-02-11 05:05:58 EST; 2s ago Main PID: 20874 (osmo-bsc-nat) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-bsc-nat.service └─20874 /usr/bin/osmo-bsc-nat -c /etc/osmocom/osmo-bsc-nat.cfg
Feb 11 05:05:58 localhost.localdomain systemd[1]: Started Osmocom BSC NAT. Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000d> osmo_ss7.c:1645 0: asp-asp-clnt-OsmoBSCNAT-CN: ASP Restart for server not implemented yet! Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000d> osmo_ss7.c:1645 1: asp-asp-clnt-OsmoBSCNAT-RAN: ASP Restart for server not implemented yet! Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <0001> telnet_interface.c:88 Available via telnet 127.0.0.1 4244 Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:549 OsmoBSCNAT-CN: Using SS7 instance 0, pc:0.23.3 Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:556 OsmoBSCNAT-CN: Creating AS instance Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:567 OsmoBSCNAT-CN: Using AS instance as-clnt-OsmoBSCNAT-CN Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:572 OsmoBSCNAT-CN: Creating default route Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:606 OsmoBSCNAT-CN: ASP asp-clnt-OsmoBSCNAT-CN for m3ua is not associated with any AS, using it Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:647 OsmoBSCNAT-CN: Using ASP instance asp-clnt-OsmoBSCNAT-CN Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000d> osmo_ss7.c:458 0: Creating SCCP instance Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:549 OsmoBSCNAT-RAN: Using SS7 instance 1, pc:0.23.1 Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:556 OsmoBSCNAT-RAN: Creating AS instance Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:567 OsmoBSCNAT-RAN: Using AS instance as-clnt-OsmoBSCNAT-RAN Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:572 OsmoBSCNAT-RAN: Creating default route Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:606 OsmoBSCNAT-RAN: ASP asp-clnt-OsmoBSCNAT-RAN for m3ua is not associated with any AS, using it Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000e> sccp_user.c:647 OsmoBSCNAT-RAN: Using ASP instance asp-clnt-OsmoBSCNAT-RAN Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000d> osmo_ss7.c:458 1: Creating SCCP instance Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <0011> mgcp_client.c:789 MGW(bscnat) MGCP client: using endpoint domain '@bscnat' Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <0000> bsc_nat_fsm.c:351 MGW pool with 1 pool member(s) initialized Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <0000> bssap.c:34 Tx RESET to PC=0.23.1 in CN Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <000d> m3ua.c:508 XUA_AS(as-clnt-OsmoBSCNAT-CN)[0x563fcb5b7be0]{AS_DOWN}: Event AS-TRANSFER.req not permitted Feb 11 05:05:58 localhost.localdomain osmo-bsc-nat[20874]: <0000> msc_fsm.c:67 Could not send RESET to MSC (SCCP not up yet?) Feb 11 05:06:00 localhost.localdomain osmo-bsc-nat[20874]: <0010> m3ua.c:625 0: asp-asp-clnt-OsmoBSCNAT-CN: Received NOTIFY Type State Change:AS Inactive () Feb 11 05:06:00 localhost.localdomain osmo-bsc-nat[20874]: <000d> xua_default_lm_fsm.c:361 xua_default_lm(asp-clnt-OsmoBSCNAT-CN)[0x563fcb5b7db0]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm Feb 11 05:06:00 localhost.localdomain osmo-bsc-nat[20874]: <0010> m3ua.c:625 0: asp-asp-clnt-OsmoBSCNAT-CN: Received NOTIFY Type State Change:AS Active () + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-upf ● osmo-upf.service - Osmocom User Plane Function (UPF) Loaded: loaded (/usr/lib/systemd/system/osmo-upf.service; disabled; vendor preset: disabled) Active: activating (auto-restart) (Result: exit-code) since Sat 2023-02-11 05:06:00 EST; 443ms ago Process: 22593 ExecStart=/usr/bin/osmo-upf -c /etc/osmocom/osmo-upf.cfg (code=exited, status=255) Main PID: 22593 (code=exited, status=255) + failed=' osmo-upf' + journalctl -u osmo-upf -n 200 -- Logs begin at Sat 2023-02-11 05:04:11 EST, end at Sat 2023-02-11 05:06:00 EST. -- Feb 11 05:05:58 localhost.localdomain systemd[1]: Started Osmocom User Plane Function (UPF). Feb 11 05:05:58 localhost.localdomain osmo-upf[20880]: 20230211050558876 DLGLOBAL NOTICE Available via telnet 127.0.0.1 4275 (telnet_interface.c:88) Feb 11 05:05:58 localhost.localdomain osmo-upf[20880]: 20230211050558876 DLCTRL NOTICE CTRL at 127.0.0.1 4276 (control_if.c:1014) Feb 11 05:05:58 localhost.localdomain osmo-upf[20880]: Error: Could not process rule: Invalid argument Feb 11 05:05:58 localhost.localdomain osmo-upf[20880]: add table inet osmo-upf { flags owner; }; Feb 11 05:05:58 localhost.localdomain osmo-upf[20880]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 11 05:05:58 localhost.localdomain osmo-upf[20880]: 20230211050558900 DNFT ERROR error running nft ruleset: rc=-1 ruleset="add table inet osmo-upf { flags owner; };\n" (upf_nft.c:80) Feb 11 05:05:58 localhost.localdomain osmo-upf[20880]: 20230211050558900 DNFT ERROR Failed to create nft table "osmo-upf" (upf_nft.c:112) Feb 11 05:05:58 localhost.localdomain osmo-upf[20880]: Feb 11 05:05:58 localhost.localdomain systemd[1]: osmo-upf.service: Main process exited, code=exited, status=255/n/a Feb 11 05:05:58 localhost.localdomain systemd[1]: osmo-upf.service: Failed with result 'exit-code'. Feb 11 05:06:00 localhost.localdomain systemd[1]: osmo-upf.service: Service RestartSec=2s expired, scheduling restart. Feb 11 05:06:00 localhost.localdomain systemd[1]: osmo-upf.service: Scheduled restart job, restart counter is at 1. Feb 11 05:06:00 localhost.localdomain systemd[1]: Stopped Osmocom User Plane Function (UPF). Feb 11 05:06:00 localhost.localdomain systemd[1]: Started Osmocom User Plane Function (UPF). Feb 11 05:06:00 localhost.localdomain osmo-upf[22593]: 20230211050600919 DLGLOBAL NOTICE Available via telnet 127.0.0.1 4275 (telnet_interface.c:88) Feb 11 05:06:00 localhost.localdomain osmo-upf[22593]: 20230211050600919 DLCTRL NOTICE CTRL at 127.0.0.1 4276 (control_if.c:1014) Feb 11 05:06:00 localhost.localdomain osmo-upf[22593]: Error: Could not process rule: Invalid argument Feb 11 05:06:00 localhost.localdomain osmo-upf[22593]: add table inet osmo-upf { flags owner; }; Feb 11 05:06:00 localhost.localdomain osmo-upf[22593]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 11 05:06:00 localhost.localdomain osmo-upf[22593]: 20230211050600931 DNFT ERROR error running nft ruleset: rc=-1 ruleset="add table inet osmo-upf { flags owner; };\n" (upf_nft.c:80) Feb 11 05:06:00 localhost.localdomain osmo-upf[22593]: 20230211050600931 DNFT ERROR Failed to create nft table "osmo-upf" (upf_nft.c:112) Feb 11 05:06:00 localhost.localdomain osmo-upf[22593]: Feb 11 05:06:00 localhost.localdomain systemd[1]: osmo-upf.service: Main process exited, code=exited, status=255/n/a Feb 11 05:06:00 localhost.localdomain systemd[1]: osmo-upf.service: Failed with result 'exit-code'. + systemctl stop osmo-bsc osmo-bts-virtual osmo-cbc osmo-gbproxy osmo-gtphub osmo-hlr osmo-hnbgw osmo-hnodeb osmo-mgw osmo-msc osmo-pcap-client osmo-pcap-server osmo-pcu osmo-remsim-bankd osmo-remsim-server osmo-sgsn osmo-sip-connector osmo-smlc osmo-stp osmo-bsc-nat osmo-upf + '[' -n ' osmo-upf' ']' + set +x
ERROR: services failed to start: osmo-upf
+ clean_up_trap + [ -n ] + set +x
### Clean up ###
+ trap - EXIT INT TERM 0 + clean_up + [ -n ] + [ -e _repo_install_test_data/qemu.pid ] + cat _repo_install_test_data/qemu.pid + kill 767363 + rm -f _repo_install_test_data/temp.qcow2 Build step 'Execute shell' marked build as failure
Changes:
------------------------------------------ [...truncated 298.16 KB...] + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-pcap-server ● osmo-pcap-server.service - PCAP Server for the PCAP aggregation Loaded: loaded (/usr/lib/systemd/system/osmo-pcap-server.service; disabled; vendor preset: disabled) Active: active (running) since Sun 2023-02-12 05:06:06 EST; 2s ago Main PID: 20923 (osmo-pcap-serve) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-pcap-server.service └─20923 /usr/bin/osmo-pcap-server -c /etc/osmocom/osmo-pcap-server.cfg
Feb 12 05:06:06 localhost.localdomain systemd[1]: Started PCAP Server for the PCAP aggregation. Feb 12 05:06:06 localhost.localdomain osmo-pcap-server[20923]: <0005> telnet_interface.c:88 Available via telnet 127.0.0.1 4228 Feb 12 05:06:06 localhost.localdomain osmo-pcap-server[20923]: <0004> osmo_tls.c:44 Going to create DH params for 3072 bits Feb 12 05:06:08 localhost.localdomain osmo-pcap-server[20923]: <0002> osmo_server_network.c:573 New connection from zecke + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-pcu ● osmo-pcu.service - Osmocom osmo-pcu Loaded: loaded (/usr/lib/systemd/system/osmo-pcu.service; disabled; vendor preset: disabled) Active: active (running) since Sun 2023-02-12 05:06:06 EST; 2s ago Main PID: 20924 (osmo-pcu) Tasks: 1 (limit: 4908) Memory: 2.4M CGroup: /system.slice/osmo-pcu.service └─20924 /usr/bin/osmo-pcu -c /etc/osmocom/osmo-pcu.cfg
Feb 12 05:06:06 localhost.localdomain systemd[1]: Started Osmocom osmo-pcu. Feb 12 05:06:06 localhost.localdomain osmo-pcu[20924]: <000f> telnet_interface.c:88 Available via telnet 127.0.0.1 4240 Feb 12 05:06:06 localhost.localdomain osmo-pcu[20924]: <0001> osmobts_sock.c:239 osmo-bts PCU socket /tmp/pcu_bts has been connected Feb 12 05:06:06 localhost.localdomain osmo-pcu[20924]: <0001> pcu_l1_if.cpp:1119 Received message for new BTS0 Feb 12 05:06:06 localhost.localdomain osmo-pcu[20924]: <0001> pcu_l1_if.cpp:732 BTS not available + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-remsim-bankd ● osmo-remsim-bankd.service - Osmocom Remote SIM BankD Loaded: loaded (/usr/lib/systemd/system/osmo-remsim-bankd.service; disabled; vendor preset: disabled) Active: active (running) since Sun 2023-02-12 05:06:06 EST; 2s ago Main PID: 20936 (osmo-remsim-ban) Tasks: 6 (limit: 4908) Memory: 1.4M CGroup: /system.slice/osmo-remsim-bankd.service └─20936 /usr/bin/osmo-remsim-bankd -i 127.0.0.1 -n 5
Feb 12 05:06:06 localhost.localdomain systemd[1]: Started Osmocom Remote SIM BankD. Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/0 -> regex 'ACS ACR33 ICC Reader 00 00' Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/1 -> regex 'ACS ACR33 ICC Reader 00 01' Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/2 -> regex 'ACS ACR33 ICC Reader 00 02' Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/3 -> regex 'ACS ACR33 ICC Reader 00 03' Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/4 -> regex 'ACS ACR33 ICC Reader 00 04' Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/23 -> regex 'Alcor Micro AU9560 00 00' Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [000 B0:0 INIT] Changing state to INIT Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [000 B65535:65535 INIT] Changing state to ACCEPTING Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [001 B0:0 INIT] Changing state to INIT Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [002 B0:0 INIT] Changing state to INIT Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [001 B65535:65535 INIT] Changing state to ACCEPTING Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [002 B65535:65535 INIT] Changing state to ACCEPTING Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [003 B0:0 INIT] Changing state to INIT Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [004 B0:0 INIT] Changing state to INIT Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [004 B65535:65535 INIT] Changing state to ACCEPTING Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DBANKDW INFO bankd_main.c:492 [003 B65535:65535 INIT] Changing state to ACCEPTING Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DRSPRO INFO ../rspro_client_fsm.c:389 RSPRO_CLIENT(server){REESTABLISH}: Creating TCP connection to server at 127.0.0.1:9998 Feb 12 05:06:06 localhost.localdomain osmo-remsim-bankd[20936]: 20936 DRSPRO NOTICE ../rspro_client_fsm.c:147 RSPRO_CLIENT(server){REESTABLISH}: RSPRO link to 127.0.0.1:9998 DOWN + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-remsim-server ● osmo-remsim-server.service - Osmocom Remote SIM Server Loaded: loaded (/usr/lib/systemd/system/osmo-remsim-server.service; disabled; vendor preset: disabled) Active: active (running) since Sun 2023-02-12 05:06:06 EST; 2s ago Main PID: 20963 (osmo-remsim-ser) Tasks: 2 (limit: 4908) Memory: 1.8M CGroup: /system.slice/osmo-remsim-server.service └─20963 /usr/bin/osmo-remsim-server
Feb 12 05:06:06 localhost.localdomain systemd[1]: Started Osmocom Remote SIM Server. + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-sgsn ● osmo-sgsn.service - Osmocom SGSN (Serving GPRS Support Node) Loaded: loaded (/usr/lib/systemd/system/osmo-sgsn.service; disabled; vendor preset: disabled) Active: active (running) since Sun 2023-02-12 05:06:06 EST; 2s ago Main PID: 20964 (osmo-sgsn) Tasks: 1 (limit: 4908) Memory: 3.0M CGroup: /system.slice/osmo-sgsn.service └─20964 /usr/bin/osmo-sgsn -c /etc/osmocom/osmo-sgsn.cfg
Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: rate_ctr.c:87 validating counter group 0x55f2b6c7f5c0(sgsn) with 23 counters Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0013> telnet_interface.c:88 Available via telnet 127.0.0.1 4245 Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <001b> gsn.c:431 GTP: gtp_newgsn() started at 127.0.0.1 Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <001b> gsn.c:388 State information file (.//gsn_restart) not found. Creating new file. Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0004> sgsn_libgtp.c:886 Created GTP on 127.0.0.1 Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0004> sgsn_main.c:457 libGTP v1.10.0.1.3506.202302120006 initialized Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <001a> control_if.c:1014 CTRL at 127.0.0.1 4251 Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <001d> gsup_client.c:75 GSUP connecting to 127.0.0.1:4222 Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0020> sccp_user.c:522 OsmoSGSN: Creating SS7 instance Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0020> sccp_user.c:549 OsmoSGSN: Using SS7 instance 0, pc:0.23.4 Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0020> sccp_user.c:556 OsmoSGSN: Creating AS instance Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0020> sccp_user.c:567 OsmoSGSN: Using AS instance as-clnt-OsmoSGSN Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0020> sccp_user.c:572 OsmoSGSN: Creating default route Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0020> sccp_user.c:612 OsmoSGSN: No unassociated ASP for m3ua, creating new ASP asp-clnt-OsmoSGSN Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <0020> sccp_user.c:647 OsmoSGSN: Using ASP instance asp-clnt-OsmoSGSN Feb 12 05:06:06 localhost.localdomain osmo-sgsn[20964]: <001f> osmo_ss7.c:458 0: Creating SCCP instance Feb 12 05:06:06 localhost.localdomain systemd[1]: Started Osmocom SGSN (Serving GPRS Support Node). Feb 12 05:06:07 localhost.localdomain osmo-sgsn[20964]: <001d> gsup_client.c:75 GSUP connecting to 127.0.0.1:4222 Feb 12 05:06:07 localhost.localdomain osmo-sgsn[20964]: <0015> input/ipa.c:141 127.0.0.1:4222 connection done Feb 12 05:06:07 localhost.localdomain osmo-sgsn[20964]: <0015> input/ipaccess.c:920 received ID_GET for unit ID 0/0/0 + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-sip-connector ● osmo-sip-connector.service - Osmo SIP Connector Loaded: loaded (/usr/lib/systemd/system/osmo-sip-connector.service; disabled; vendor preset: disabled) Active: active (running) since Sun 2023-02-12 05:06:06 EST; 2s ago Main PID: 20970 (osmo-sip-connec) Tasks: 1 (limit: 4908) Memory: 2.1M CGroup: /system.slice/osmo-sip-connector.service └─20970 /usr/bin/osmo-sip-connector -c /etc/osmocom/osmo-sip-connector.cfg
Feb 12 05:06:06 localhost.localdomain osmo-sip-connector[20970]: <0004> telnet_interface.c:88 Available via telnet 127.0.0.1 4256 Feb 12 05:06:06 localhost.localdomain osmo-sip-connector[20970]: <0001> mncc.c:1083 Scheduling MNCC connect Feb 12 05:06:06 localhost.localdomain osmo-sip-connector[20970]: <0000> sip.c:724 su_source_port_create() returns 0x560fd01ef4a0 Feb 12 05:06:06 localhost.localdomain osmo-sip-connector[20970]: <0001> mncc.c:975 Failed to connect(/tmp/msc_mncc). Retrying Feb 12 05:06:06 localhost.localdomain systemd[1]: Started Osmo SIP Connector. + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-smlc ● osmo-smlc.service - Osmocom Serving Mobile Location Center (SMLC) Loaded: loaded (/usr/lib/systemd/system/osmo-smlc.service; disabled; vendor preset: disabled) Active: active (running) since Sun 2023-02-12 05:06:06 EST; 2s ago Main PID: 20975 (osmo-smlc) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-smlc.service └─20975 /usr/bin/osmo-smlc -c /etc/osmocom/osmo-smlc.cfg
Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <0004> telnet_interface.c:88 Available via telnet 127.0.0.1 4271 Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <000b> control_if.c:1014 CTRL at 127.0.0.1 4272 Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <0011> sccp_user.c:522 Lb: Creating SS7 instance Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <0011> sccp_user.c:549 Lb: Using SS7 instance 0, pc:0.23.6 Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <0011> sccp_user.c:556 Lb: Creating AS instance Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <0011> sccp_user.c:567 Lb: Using AS instance as-clnt-Lb Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <0011> sccp_user.c:572 Lb: Creating default route Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <0011> sccp_user.c:612 Lb: No unassociated ASP for m3ua, creating new ASP asp-clnt-Lb Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <0011> sccp_user.c:647 Lb: Using ASP instance asp-clnt-Lb Feb 12 05:06:06 localhost.localdomain osmo-smlc[20975]: <0010> osmo_ss7.c:458 0: Creating SCCP instance Feb 12 05:06:06 localhost.localdomain systemd[1]: Started Osmocom Serving Mobile Location Center (SMLC). + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-stp ● osmo-stp.service - Osmocom STP (Signal Transfer Point) Loaded: loaded (/usr/lib/systemd/system/osmo-stp.service; disabled; vendor preset: disabled) Active: active (running) since Sun 2023-02-12 05:06:06 EST; 2s ago Docs: https://osmocom.org/projects/osmo-stp/wiki Main PID: 20976 (osmo-stp) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-stp.service └─20976 /usr/bin/osmo-stp -c /etc/osmocom/osmo-stp.cfg
Feb 12 05:06:06 localhost.localdomain osmo-stp[20976]: DLGLOBAL <0000> telnet_interface.c:88 Available via telnet 127.0.0.1 4239 Feb 12 05:06:06 localhost.localdomain osmo-stp[20976]: DLSS7 <000c> osmo_ss7.c:1645 0: asp-asp-dyn-0: ASP Restart for server not implemented yet! Feb 12 05:06:06 localhost.localdomain systemd[1]: Started Osmocom STP (Signal Transfer Point). + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-bsc-nat ● osmo-bsc-nat.service - Osmocom BSC NAT Loaded: loaded (/usr/lib/systemd/system/osmo-bsc-nat.service; disabled; vendor preset: disabled) Active: active (running) since Sun 2023-02-12 05:06:06 EST; 2s ago Main PID: 20981 (osmo-bsc-nat) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-bsc-nat.service └─20981 /usr/bin/osmo-bsc-nat -c /etc/osmocom/osmo-bsc-nat.cfg
Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000d> osmo_ss7.c:1645 0: asp-asp-clnt-OsmoBSCNAT-CN: ASP Restart for server not implemented yet! Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000d> osmo_ss7.c:1645 1: asp-asp-clnt-OsmoBSCNAT-RAN: ASP Restart for server not implemented yet! Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <0001> telnet_interface.c:88 Available via telnet 127.0.0.1 4244 Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:549 OsmoBSCNAT-CN: Using SS7 instance 0, pc:0.23.3 Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:556 OsmoBSCNAT-CN: Creating AS instance Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:567 OsmoBSCNAT-CN: Using AS instance as-clnt-OsmoBSCNAT-CN Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:572 OsmoBSCNAT-CN: Creating default route Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:606 OsmoBSCNAT-CN: ASP asp-clnt-OsmoBSCNAT-CN for m3ua is not associated with any AS, using it Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:647 OsmoBSCNAT-CN: Using ASP instance asp-clnt-OsmoBSCNAT-CN Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000d> osmo_ss7.c:458 0: Creating SCCP instance Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:549 OsmoBSCNAT-RAN: Using SS7 instance 1, pc:0.23.1 Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:556 OsmoBSCNAT-RAN: Creating AS instance Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:567 OsmoBSCNAT-RAN: Using AS instance as-clnt-OsmoBSCNAT-RAN Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:572 OsmoBSCNAT-RAN: Creating default route Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:606 OsmoBSCNAT-RAN: ASP asp-clnt-OsmoBSCNAT-RAN for m3ua is not associated with any AS, using it Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000e> sccp_user.c:647 OsmoBSCNAT-RAN: Using ASP instance asp-clnt-OsmoBSCNAT-RAN Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000d> osmo_ss7.c:458 1: Creating SCCP instance Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <0011> mgcp_client.c:789 MGW(bscnat) MGCP client: using endpoint domain '@bscnat' Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <0000> bsc_nat_fsm.c:351 MGW pool with 1 pool member(s) initialized Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <0000> bssap.c:34 Tx RESET to PC=0.23.1 in CN Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <000d> m3ua.c:508 XUA_AS(as-clnt-OsmoBSCNAT-CN)[0x5590af7aebe0]{AS_DOWN}: Event AS-TRANSFER.req not permitted Feb 12 05:06:06 localhost.localdomain osmo-bsc-nat[20981]: <0000> msc_fsm.c:67 Could not send RESET to MSC (SCCP not up yet?) Feb 12 05:06:06 localhost.localdomain systemd[1]: Started Osmocom BSC NAT. Feb 12 05:06:08 localhost.localdomain osmo-bsc-nat[20981]: <0010> m3ua.c:625 0: asp-asp-clnt-OsmoBSCNAT-CN: Received NOTIFY Type State Change:AS Inactive () Feb 12 05:06:08 localhost.localdomain osmo-bsc-nat[20981]: <000d> xua_default_lm_fsm.c:361 xua_default_lm(asp-clnt-OsmoBSCNAT-CN)[0x5590af7aedb0]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm Feb 12 05:06:08 localhost.localdomain osmo-bsc-nat[20981]: <0010> m3ua.c:625 0: asp-asp-clnt-OsmoBSCNAT-CN: Received NOTIFY Type State Change:AS Active () + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-upf ● osmo-upf.service - Osmocom User Plane Function (UPF) Loaded: loaded (/usr/lib/systemd/system/osmo-upf.service; disabled; vendor preset: disabled) Active: activating (auto-restart) (Result: exit-code) since Sun 2023-02-12 05:06:08 EST; 170ms ago Process: 22585 ExecStart=/usr/bin/osmo-upf -c /etc/osmocom/osmo-upf.cfg (code=exited, status=255) Main PID: 22585 (code=exited, status=255) + failed=' osmo-upf' + journalctl -u osmo-upf -n 200 -- Logs begin at Sun 2023-02-12 05:04:11 EST, end at Sun 2023-02-12 05:06:08 EST. -- Feb 12 05:06:06 localhost.localdomain osmo-upf[20993]: 20230212050606835 DLGLOBAL NOTICE Available via telnet 127.0.0.1 4275 (telnet_interface.c:88) Feb 12 05:06:06 localhost.localdomain osmo-upf[20993]: 20230212050606835 DLCTRL NOTICE CTRL at 127.0.0.1 4276 (control_if.c:1014) Feb 12 05:06:06 localhost.localdomain osmo-upf[20993]: Error: Could not process rule: Invalid argument Feb 12 05:06:06 localhost.localdomain osmo-upf[20993]: add table inet osmo-upf { flags owner; }; Feb 12 05:06:06 localhost.localdomain osmo-upf[20993]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 12 05:06:06 localhost.localdomain osmo-upf[20993]: 20230212050606851 DNFT ERROR error running nft ruleset: rc=-1 ruleset="add table inet osmo-upf { flags owner; };\n" (upf_nft.c:80) Feb 12 05:06:06 localhost.localdomain osmo-upf[20993]: 20230212050606851 DNFT ERROR Failed to create nft table "osmo-upf" (upf_nft.c:112) Feb 12 05:06:06 localhost.localdomain systemd[1]: Started Osmocom User Plane Function (UPF). Feb 12 05:06:06 localhost.localdomain osmo-upf[20993]: Feb 12 05:06:06 localhost.localdomain systemd[1]: osmo-upf.service: Main process exited, code=exited, status=255/n/a Feb 12 05:06:06 localhost.localdomain systemd[1]: osmo-upf.service: Failed with result 'exit-code'. Feb 12 05:06:08 localhost.localdomain systemd[1]: osmo-upf.service: Service RestartSec=2s expired, scheduling restart. Feb 12 05:06:08 localhost.localdomain systemd[1]: osmo-upf.service: Scheduled restart job, restart counter is at 1. Feb 12 05:06:08 localhost.localdomain systemd[1]: Stopped Osmocom User Plane Function (UPF). Feb 12 05:06:08 localhost.localdomain systemd[1]: Started Osmocom User Plane Function (UPF). Feb 12 05:06:08 localhost.localdomain osmo-upf[22585]: 20230212050608874 DLGLOBAL NOTICE Available via telnet 127.0.0.1 4275 (telnet_interface.c:88) Feb 12 05:06:08 localhost.localdomain osmo-upf[22585]: 20230212050608874 DLCTRL NOTICE CTRL at 127.0.0.1 4276 (control_if.c:1014) Feb 12 05:06:08 localhost.localdomain osmo-upf[22585]: Error: Could not process rule: Invalid argument Feb 12 05:06:08 localhost.localdomain osmo-upf[22585]: add table inet osmo-upf { flags owner; }; Feb 12 05:06:08 localhost.localdomain osmo-upf[22585]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 12 05:06:08 localhost.localdomain osmo-upf[22585]: 20230212050608887 DNFT ERROR error running nft ruleset: rc=-1 ruleset="add table inet osmo-upf { flags owner; };\n" (upf_nft.c:80) Feb 12 05:06:08 localhost.localdomain osmo-upf[22585]: 20230212050608887 DNFT ERROR Failed to create nft table "osmo-upf" (upf_nft.c:112) Feb 12 05:06:08 localhost.localdomain osmo-upf[22585]: Feb 12 05:06:08 localhost.localdomain systemd[1]: osmo-upf.service: Main process exited, code=exited, status=255/n/a Feb 12 05:06:08 localhost.localdomain systemd[1]: osmo-upf.service: Failed with result 'exit-code'. + systemctl stop osmo-bsc osmo-bts-virtual osmo-cbc osmo-gbproxy osmo-gtphub osmo-hlr osmo-hnbgw osmo-hnodeb osmo-mgw osmo-msc osmo-pcap-client osmo-pcap-server osmo-pcu osmo-remsim-bankd osmo-remsim-server osmo-sgsn osmo-sip-connector osmo-smlc osmo-stp osmo-bsc-nat osmo-upf + '[' -n ' osmo-upf' ']' + set +x
ERROR: services failed to start: osmo-upf
+ clean_up_trap + [ -n ] + set +x
### Clean up ###
+ trap - EXIT INT TERM 0 + clean_up + [ -n ] + [ -e _repo_install_test_data/qemu.pid ] + cat _repo_install_test_data/qemu.pid + kill 3103967 + rm -f _repo_install_test_data/temp.qcow2 Build step 'Execute shell' marked build as failure
Changes:
------------------------------------------ [...truncated 298.39 KB...] Feb 13 05:05:58 localhost.localdomain systemd[1]: Started PCAP Client for the PCAP aggregation. Feb 13 05:05:58 localhost.localdomain osmo-pcap-client[20738]: <0005> telnet_interface.c:88 Available via telnet 127.0.0.1 4227 Feb 13 05:05:58 localhost.localdomain osmo-pcap-client[20738]: <0001> osmo_client_network.c:79 Lost connection on read conn=default + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-pcap-server ● osmo-pcap-server.service - PCAP Server for the PCAP aggregation Loaded: loaded (/usr/lib/systemd/system/osmo-pcap-server.service; disabled; vendor preset: disabled) Active: active (running) since Mon 2023-02-13 05:05:58 EST; 2s ago Main PID: 20745 (osmo-pcap-serve) Tasks: 1 (limit: 4908) Memory: 1.9M CGroup: /system.slice/osmo-pcap-server.service └─20745 /usr/bin/osmo-pcap-server -c /etc/osmocom/osmo-pcap-server.cfg
Feb 13 05:05:58 localhost.localdomain systemd[1]: Started PCAP Server for the PCAP aggregation. Feb 13 05:05:58 localhost.localdomain osmo-pcap-server[20745]: <0005> telnet_interface.c:88 Available via telnet 127.0.0.1 4228 Feb 13 05:05:58 localhost.localdomain osmo-pcap-server[20745]: <0004> osmo_tls.c:44 Going to create DH params for 3072 bits Feb 13 05:06:00 localhost.localdomain osmo-pcap-server[20745]: <0002> osmo_server_network.c:573 New connection from zecke + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-pcu ● osmo-pcu.service - Osmocom osmo-pcu Loaded: loaded (/usr/lib/systemd/system/osmo-pcu.service; disabled; vendor preset: disabled) Active: active (running) since Mon 2023-02-13 05:05:58 EST; 2s ago Main PID: 20766 (osmo-pcu) Tasks: 1 (limit: 4908) Memory: 2.4M CGroup: /system.slice/osmo-pcu.service └─20766 /usr/bin/osmo-pcu -c /etc/osmocom/osmo-pcu.cfg
Feb 13 05:05:58 localhost.localdomain systemd[1]: Started Osmocom osmo-pcu. Feb 13 05:05:58 localhost.localdomain osmo-pcu[20766]: <000f> telnet_interface.c:88 Available via telnet 127.0.0.1 4240 Feb 13 05:05:58 localhost.localdomain osmo-pcu[20766]: <0001> osmobts_sock.c:239 osmo-bts PCU socket /tmp/pcu_bts has been connected Feb 13 05:05:58 localhost.localdomain osmo-pcu[20766]: <0001> pcu_l1_if.cpp:1119 Received message for new BTS0 Feb 13 05:05:58 localhost.localdomain osmo-pcu[20766]: <0001> pcu_l1_if.cpp:732 BTS not available + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-remsim-bankd ● osmo-remsim-bankd.service - Osmocom Remote SIM BankD Loaded: loaded (/usr/lib/systemd/system/osmo-remsim-bankd.service; disabled; vendor preset: disabled) Active: active (running) since Mon 2023-02-13 05:05:58 EST; 2s ago Main PID: 20778 (osmo-remsim-ban) Tasks: 6 (limit: 4908) Memory: 1.4M CGroup: /system.slice/osmo-remsim-bankd.service └─20778 /usr/bin/osmo-remsim-bankd -i 127.0.0.1 -n 5
Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/0 -> regex 'ACS ACR33 ICC Reader 00 00' Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/1 -> regex 'ACS ACR33 ICC Reader 00 01' Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/2 -> regex 'ACS ACR33 ICC Reader 00 02' Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/3 -> regex 'ACS ACR33 ICC Reader 00 03' Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/4 -> regex 'ACS ACR33 ICC Reader 00 04' Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/23 -> regex 'Alcor Micro AU9560 00 00' Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [000 B0:0 INIT] Changing state to INIT Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [001 B0:0 INIT] Changing state to INIT Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [000 B65535:65535 INIT] Changing state to ACCEPTING Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [002 B0:0 INIT] Changing state to INIT Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [001 B65535:65535 INIT] Changing state to ACCEPTING Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [002 B65535:65535 INIT] Changing state to ACCEPTING Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [003 B0:0 INIT] Changing state to INIT Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [004 B0:0 INIT] Changing state to INIT Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [004 B65535:65535 INIT] Changing state to ACCEPTING Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DBANKDW INFO bankd_main.c:492 [003 B65535:65535 INIT] Changing state to ACCEPTING Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DRSPRO INFO ../rspro_client_fsm.c:389 RSPRO_CLIENT(server){REESTABLISH}: Creating TCP connection to server at 127.0.0.1:9998 Feb 13 05:05:58 localhost.localdomain systemd[1]: Started Osmocom Remote SIM BankD. Feb 13 05:05:58 localhost.localdomain osmo-remsim-bankd[20778]: 20778 DRSPRO NOTICE ../rspro_client_fsm.c:147 RSPRO_CLIENT(server){REESTABLISH}: RSPRO link to 127.0.0.1:9998 DOWN + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-remsim-server ● osmo-remsim-server.service - Osmocom Remote SIM Server Loaded: loaded (/usr/lib/systemd/system/osmo-remsim-server.service; disabled; vendor preset: disabled) Active: active (running) since Mon 2023-02-13 05:05:58 EST; 2s ago Main PID: 20790 (osmo-remsim-ser) Tasks: 2 (limit: 4908) Memory: 1.8M CGroup: /system.slice/osmo-remsim-server.service └─20790 /usr/bin/osmo-remsim-server
Feb 13 05:05:58 localhost.localdomain systemd[1]: Started Osmocom Remote SIM Server. + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-sgsn ● osmo-sgsn.service - Osmocom SGSN (Serving GPRS Support Node) Loaded: loaded (/usr/lib/systemd/system/osmo-sgsn.service; disabled; vendor preset: disabled) Active: active (running) since Mon 2023-02-13 05:05:58 EST; 2s ago Main PID: 20795 (osmo-sgsn) Tasks: 1 (limit: 4908) Memory: 3.0M CGroup: /system.slice/osmo-sgsn.service └─20795 /usr/bin/osmo-sgsn -c /etc/osmocom/osmo-sgsn.cfg
Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: rate_ctr.c:87 validating counter group 0x55bd80d435c0(sgsn) with 23 counters Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0013> telnet_interface.c:88 Available via telnet 127.0.0.1 4245 Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <001b> gsn.c:431 GTP: gtp_newgsn() started at 127.0.0.1 Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <001b> gsn.c:388 State information file (.//gsn_restart) not found. Creating new file. Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0004> sgsn_libgtp.c:886 Created GTP on 127.0.0.1 Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0004> sgsn_main.c:457 libGTP v1.10.0.1.3506.202302130006 initialized Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <001a> control_if.c:1014 CTRL at 127.0.0.1 4251 Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <001d> gsup_client.c:75 GSUP connecting to 127.0.0.1:4222 Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0020> sccp_user.c:522 OsmoSGSN: Creating SS7 instance Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0020> sccp_user.c:549 OsmoSGSN: Using SS7 instance 0, pc:0.23.4 Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0020> sccp_user.c:556 OsmoSGSN: Creating AS instance Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0020> sccp_user.c:567 OsmoSGSN: Using AS instance as-clnt-OsmoSGSN Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0020> sccp_user.c:572 OsmoSGSN: Creating default route Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0020> sccp_user.c:612 OsmoSGSN: No unassociated ASP for m3ua, creating new ASP asp-clnt-OsmoSGSN Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <0020> sccp_user.c:647 OsmoSGSN: Using ASP instance asp-clnt-OsmoSGSN Feb 13 05:05:58 localhost.localdomain osmo-sgsn[20795]: <001f> osmo_ss7.c:458 0: Creating SCCP instance Feb 13 05:05:58 localhost.localdomain systemd[1]: Started Osmocom SGSN (Serving GPRS Support Node). Feb 13 05:05:59 localhost.localdomain osmo-sgsn[20795]: <001d> gsup_client.c:75 GSUP connecting to 127.0.0.1:4222 Feb 13 05:05:59 localhost.localdomain osmo-sgsn[20795]: <0015> input/ipa.c:141 127.0.0.1:4222 connection done Feb 13 05:05:59 localhost.localdomain osmo-sgsn[20795]: <0015> input/ipaccess.c:920 received ID_GET for unit ID 0/0/0 + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-sip-connector ● osmo-sip-connector.service - Osmo SIP Connector Loaded: loaded (/usr/lib/systemd/system/osmo-sip-connector.service; disabled; vendor preset: disabled) Active: active (running) since Mon 2023-02-13 05:05:58 EST; 2s ago Main PID: 20800 (osmo-sip-connec) Tasks: 1 (limit: 4908) Memory: 2.1M CGroup: /system.slice/osmo-sip-connector.service └─20800 /usr/bin/osmo-sip-connector -c /etc/osmocom/osmo-sip-connector.cfg
Feb 13 05:05:58 localhost.localdomain osmo-sip-connector[20800]: <0004> telnet_interface.c:88 Available via telnet 127.0.0.1 4256 Feb 13 05:05:58 localhost.localdomain osmo-sip-connector[20800]: <0001> mncc.c:1083 Scheduling MNCC connect Feb 13 05:05:58 localhost.localdomain osmo-sip-connector[20800]: <0000> sip.c:724 su_source_port_create() returns 0x55641690b4a0 Feb 13 05:05:58 localhost.localdomain osmo-sip-connector[20800]: <0001> mncc.c:975 Failed to connect(/tmp/msc_mncc). Retrying Feb 13 05:05:58 localhost.localdomain systemd[1]: Started Osmo SIP Connector. + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-smlc ● osmo-smlc.service - Osmocom Serving Mobile Location Center (SMLC) Loaded: loaded (/usr/lib/systemd/system/osmo-smlc.service; disabled; vendor preset: disabled) Active: active (running) since Mon 2023-02-13 05:05:58 EST; 2s ago Main PID: 20817 (osmo-smlc) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-smlc.service └─20817 /usr/bin/osmo-smlc -c /etc/osmocom/osmo-smlc.cfg
Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <0004> telnet_interface.c:88 Available via telnet 127.0.0.1 4271 Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <000b> control_if.c:1014 CTRL at 127.0.0.1 4272 Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <0011> sccp_user.c:522 Lb: Creating SS7 instance Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <0011> sccp_user.c:549 Lb: Using SS7 instance 0, pc:0.23.6 Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <0011> sccp_user.c:556 Lb: Creating AS instance Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <0011> sccp_user.c:567 Lb: Using AS instance as-clnt-Lb Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <0011> sccp_user.c:572 Lb: Creating default route Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <0011> sccp_user.c:612 Lb: No unassociated ASP for m3ua, creating new ASP asp-clnt-Lb Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <0011> sccp_user.c:647 Lb: Using ASP instance asp-clnt-Lb Feb 13 05:05:58 localhost.localdomain osmo-smlc[20817]: <0010> osmo_ss7.c:458 0: Creating SCCP instance Feb 13 05:05:58 localhost.localdomain systemd[1]: Started Osmocom Serving Mobile Location Center (SMLC). + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-stp ● osmo-stp.service - Osmocom STP (Signal Transfer Point) Loaded: loaded (/usr/lib/systemd/system/osmo-stp.service; disabled; vendor preset: disabled) Active: active (running) since Mon 2023-02-13 05:05:58 EST; 2s ago Docs: https://osmocom.org/projects/osmo-stp/wiki Main PID: 20823 (osmo-stp) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-stp.service └─20823 /usr/bin/osmo-stp -c /etc/osmocom/osmo-stp.cfg
Feb 13 05:05:58 localhost.localdomain osmo-stp[20823]: DLGLOBAL <0000> telnet_interface.c:88 Available via telnet 127.0.0.1 4239 Feb 13 05:05:58 localhost.localdomain osmo-stp[20823]: DLSS7 <000c> osmo_ss7.c:1645 0: asp-asp-dyn-0: ASP Restart for server not implemented yet! Feb 13 05:05:58 localhost.localdomain systemd[1]: Started Osmocom STP (Signal Transfer Point). + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-bsc-nat ● osmo-bsc-nat.service - Osmocom BSC NAT Loaded: loaded (/usr/lib/systemd/system/osmo-bsc-nat.service; disabled; vendor preset: disabled) Active: active (running) since Mon 2023-02-13 05:05:58 EST; 2s ago Main PID: 20828 (osmo-bsc-nat) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-bsc-nat.service └─20828 /usr/bin/osmo-bsc-nat -c /etc/osmocom/osmo-bsc-nat.cfg
Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000d> osmo_ss7.c:1645 0: asp-asp-clnt-OsmoBSCNAT-CN: ASP Restart for server not implemented yet! Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000d> osmo_ss7.c:1645 1: asp-asp-clnt-OsmoBSCNAT-RAN: ASP Restart for server not implemented yet! Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <0001> telnet_interface.c:88 Available via telnet 127.0.0.1 4244 Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:549 OsmoBSCNAT-CN: Using SS7 instance 0, pc:0.23.3 Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:556 OsmoBSCNAT-CN: Creating AS instance Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:567 OsmoBSCNAT-CN: Using AS instance as-clnt-OsmoBSCNAT-CN Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:572 OsmoBSCNAT-CN: Creating default route Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:606 OsmoBSCNAT-CN: ASP asp-clnt-OsmoBSCNAT-CN for m3ua is not associated with any AS, using it Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:647 OsmoBSCNAT-CN: Using ASP instance asp-clnt-OsmoBSCNAT-CN Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000d> osmo_ss7.c:458 0: Creating SCCP instance Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:549 OsmoBSCNAT-RAN: Using SS7 instance 1, pc:0.23.1 Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:556 OsmoBSCNAT-RAN: Creating AS instance Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:567 OsmoBSCNAT-RAN: Using AS instance as-clnt-OsmoBSCNAT-RAN Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:572 OsmoBSCNAT-RAN: Creating default route Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:606 OsmoBSCNAT-RAN: ASP asp-clnt-OsmoBSCNAT-RAN for m3ua is not associated with any AS, using it Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000e> sccp_user.c:647 OsmoBSCNAT-RAN: Using ASP instance asp-clnt-OsmoBSCNAT-RAN Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000d> osmo_ss7.c:458 1: Creating SCCP instance Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <0011> mgcp_client.c:789 MGW(bscnat) MGCP client: using endpoint domain '@bscnat' Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <0000> bsc_nat_fsm.c:351 MGW pool with 1 pool member(s) initialized Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <0000> bssap.c:34 Tx RESET to PC=0.23.1 in CN Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <000d> m3ua.c:508 XUA_AS(as-clnt-OsmoBSCNAT-CN)[0x557df9cb2be0]{AS_DOWN}: Event AS-TRANSFER.req not permitted Feb 13 05:05:58 localhost.localdomain osmo-bsc-nat[20828]: <0000> msc_fsm.c:67 Could not send RESET to MSC (SCCP not up yet?) Feb 13 05:05:58 localhost.localdomain systemd[1]: Started Osmocom BSC NAT. + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-upf ● osmo-upf.service - Osmocom User Plane Function (UPF) Loaded: loaded (/usr/lib/systemd/system/osmo-upf.service; disabled; vendor preset: disabled) Active: activating (auto-restart) (Result: exit-code) since Mon 2023-02-13 05:06:00 EST; 166ms ago Process: 22589 ExecStart=/usr/bin/osmo-upf -c /etc/osmocom/osmo-upf.cfg (code=exited, status=255) Main PID: 22589 (code=exited, status=255) + failed=' osmo-upf' + journalctl -u osmo-upf -n 200 -- Logs begin at Mon 2023-02-13 05:04:11 EST, end at Mon 2023-02-13 05:06:00 EST. -- Feb 13 05:05:58 localhost.localdomain osmo-upf[20833]: 20230213050558741 DLGLOBAL NOTICE Available via telnet 127.0.0.1 4275 (telnet_interface.c:88) Feb 13 05:05:58 localhost.localdomain osmo-upf[20833]: 20230213050558741 DLCTRL NOTICE CTRL at 127.0.0.1 4276 (control_if.c:1014) Feb 13 05:05:58 localhost.localdomain systemd[1]: Started Osmocom User Plane Function (UPF). Feb 13 05:05:58 localhost.localdomain systemd[1]: osmo-upf.service: Main process exited, code=exited, status=255/n/a Feb 13 05:05:58 localhost.localdomain osmo-upf[20833]: Error: Could not process rule: Invalid argument Feb 13 05:05:58 localhost.localdomain osmo-upf[20833]: add table inet osmo-upf { flags owner; }; Feb 13 05:05:58 localhost.localdomain osmo-upf[20833]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 13 05:05:58 localhost.localdomain osmo-upf[20833]: 20230213050558766 DNFT ERROR error running nft ruleset: rc=-1 ruleset="add table inet osmo-upf { flags owner; };\n" (upf_nft.c:80) Feb 13 05:05:58 localhost.localdomain osmo-upf[20833]: 20230213050558766 DNFT ERROR Failed to create nft table "osmo-upf" (upf_nft.c:112) Feb 13 05:05:58 localhost.localdomain systemd[1]: osmo-upf.service: Failed with result 'exit-code'. Feb 13 05:05:58 localhost.localdomain osmo-upf[20833]: Feb 13 05:06:00 localhost.localdomain systemd[1]: osmo-upf.service: Service RestartSec=2s expired, scheduling restart. Feb 13 05:06:00 localhost.localdomain systemd[1]: osmo-upf.service: Scheduled restart job, restart counter is at 1. Feb 13 05:06:00 localhost.localdomain systemd[1]: Stopped Osmocom User Plane Function (UPF). Feb 13 05:06:00 localhost.localdomain systemd[1]: Started Osmocom User Plane Function (UPF). Feb 13 05:06:00 localhost.localdomain osmo-upf[22589]: 20230213050600796 DLGLOBAL NOTICE Available via telnet 127.0.0.1 4275 (telnet_interface.c:88) Feb 13 05:06:00 localhost.localdomain osmo-upf[22589]: 20230213050600796 DLCTRL NOTICE CTRL at 127.0.0.1 4276 (control_if.c:1014) Feb 13 05:06:00 localhost.localdomain osmo-upf[22589]: Error: Could not process rule: Invalid argument Feb 13 05:06:00 localhost.localdomain osmo-upf[22589]: add table inet osmo-upf { flags owner; }; Feb 13 05:06:00 localhost.localdomain osmo-upf[22589]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 13 05:06:00 localhost.localdomain osmo-upf[22589]: 20230213050600809 DNFT ERROR error running nft ruleset: rc=-1 ruleset="add table inet osmo-upf { flags owner; };\n" (upf_nft.c:80) Feb 13 05:06:00 localhost.localdomain osmo-upf[22589]: 20230213050600809 DNFT ERROR Failed to create nft table "osmo-upf" (upf_nft.c:112) Feb 13 05:06:00 localhost.localdomain osmo-upf[22589]: Feb 13 05:06:00 localhost.localdomain systemd[1]: osmo-upf.service: Main process exited, code=exited, status=255/n/a Feb 13 05:06:00 localhost.localdomain systemd[1]: osmo-upf.service: Failed with result 'exit-code'. + systemctl stop osmo-bsc osmo-bts-virtual osmo-cbc osmo-gbproxy osmo-gtphub osmo-hlr osmo-hnbgw osmo-hnodeb osmo-mgw osmo-msc osmo-pcap-client osmo-pcap-server osmo-pcu osmo-remsim-bankd osmo-remsim-server osmo-sgsn osmo-sip-connector osmo-smlc osmo-stp osmo-bsc-nat osmo-upf + '[' -n ' osmo-upf' ']' + set +x
ERROR: services failed to start: osmo-upf
+ clean_up_trap + [ -n ] + set +x
### Clean up ###
+ trap - EXIT INT TERM 0 + clean_up + [ -n ] + [ -e _repo_install_test_data/qemu.pid ] + cat _repo_install_test_data/qemu.pid + kill 1533075 + rm -f _repo_install_test_data/temp.qcow2 Build step 'Execute shell' marked build as failure
Changes:
------------------------------------------ [...truncated 298.37 KB...] + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-pcap-server ● osmo-pcap-server.service - PCAP Server for the PCAP aggregation Loaded: loaded (/usr/lib/systemd/system/osmo-pcap-server.service; disabled; vendor preset: disabled) Active: active (running) since Tue 2023-02-14 05:05:57 EST; 2s ago Main PID: 20811 (osmo-pcap-serve) Tasks: 1 (limit: 4908) Memory: 1.9M CGroup: /system.slice/osmo-pcap-server.service └─20811 /usr/bin/osmo-pcap-server -c /etc/osmocom/osmo-pcap-server.cfg
Feb 14 05:05:57 localhost.localdomain systemd[1]: Started PCAP Server for the PCAP aggregation. Feb 14 05:05:57 localhost.localdomain osmo-pcap-server[20811]: <0005> telnet_interface.c:88 Available via telnet 127.0.0.1 4228 Feb 14 05:05:57 localhost.localdomain osmo-pcap-server[20811]: <0004> osmo_tls.c:44 Going to create DH params for 3072 bits Feb 14 05:05:59 localhost.localdomain osmo-pcap-server[20811]: <0002> osmo_server_network.c:573 New connection from zecke + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-pcu ● osmo-pcu.service - Osmocom osmo-pcu Loaded: loaded (/usr/lib/systemd/system/osmo-pcu.service; disabled; vendor preset: disabled) Active: active (running) since Tue 2023-02-14 05:05:57 EST; 2s ago Main PID: 20820 (osmo-pcu) Tasks: 1 (limit: 4908) Memory: 2.4M CGroup: /system.slice/osmo-pcu.service └─20820 /usr/bin/osmo-pcu -c /etc/osmocom/osmo-pcu.cfg
Feb 14 05:05:57 localhost.localdomain systemd[1]: Started Osmocom osmo-pcu. Feb 14 05:05:57 localhost.localdomain osmo-pcu[20820]: <000f> telnet_interface.c:88 Available via telnet 127.0.0.1 4240 Feb 14 05:05:57 localhost.localdomain osmo-pcu[20820]: <0001> osmobts_sock.c:239 osmo-bts PCU socket /tmp/pcu_bts has been connected Feb 14 05:05:57 localhost.localdomain osmo-pcu[20820]: <0001> pcu_l1_if.cpp:1139 Received message for new BTS0 Feb 14 05:05:57 localhost.localdomain osmo-pcu[20820]: <0001> pcu_l1_if.cpp:752 BTS not available + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-remsim-bankd ● osmo-remsim-bankd.service - Osmocom Remote SIM BankD Loaded: loaded (/usr/lib/systemd/system/osmo-remsim-bankd.service; disabled; vendor preset: disabled) Active: active (running) since Tue 2023-02-14 05:05:57 EST; 2s ago Main PID: 20840 (osmo-remsim-ban) Tasks: 6 (limit: 4908) Memory: 3.4M CGroup: /system.slice/osmo-remsim-bankd.service └─20840 /usr/bin/osmo-remsim-bankd -i 127.0.0.1 -n 5
Feb 14 05:05:57 localhost.localdomain systemd[1]: Started Osmocom Remote SIM BankD. Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/0 -> regex 'ACS ACR33 ICC Reader 00 00' Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/1 -> regex 'ACS ACR33 ICC Reader 00 01' Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/2 -> regex 'ACS ACR33 ICC Reader 00 02' Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/3 -> regex 'ACS ACR33 ICC Reader 00 03' Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/4 -> regex 'ACS ACR33 ICC Reader 00 04' Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DMAIN INFO bankd_pcsc.c:102 PC/SC slot name: 1/23 -> regex 'Alcor Micro AU9560 00 00' Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [000 B0:0 INIT] Changing state to INIT Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [001 B0:0 INIT] Changing state to INIT Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [000 B65535:65535 INIT] Changing state to ACCEPTING Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [001 B65535:65535 INIT] Changing state to ACCEPTING Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [002 B0:0 INIT] Changing state to INIT Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [003 B0:0 INIT] Changing state to INIT Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [002 B65535:65535 INIT] Changing state to ACCEPTING Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [003 B65535:65535 INIT] Changing state to ACCEPTING Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [004 B0:0 INIT] Changing state to INIT Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DBANKDW INFO bankd_main.c:492 [004 B65535:65535 INIT] Changing state to ACCEPTING Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DRSPRO INFO ../rspro_client_fsm.c:389 RSPRO_CLIENT(server){REESTABLISH}: Creating TCP connection to server at 127.0.0.1:9998 Feb 14 05:05:57 localhost.localdomain osmo-remsim-bankd[20840]: 20840 DRSPRO NOTICE ../rspro_client_fsm.c:147 RSPRO_CLIENT(server){REESTABLISH}: RSPRO link to 127.0.0.1:9998 DOWN + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-remsim-server ● osmo-remsim-server.service - Osmocom Remote SIM Server Loaded: loaded (/usr/lib/systemd/system/osmo-remsim-server.service; disabled; vendor preset: disabled) Active: active (running) since Tue 2023-02-14 05:05:57 EST; 2s ago Main PID: 20848 (osmo-remsim-ser) Tasks: 2 (limit: 4908) Memory: 1.8M CGroup: /system.slice/osmo-remsim-server.service └─20848 /usr/bin/osmo-remsim-server
Feb 14 05:05:57 localhost.localdomain systemd[1]: Started Osmocom Remote SIM Server. + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-sgsn ● osmo-sgsn.service - Osmocom SGSN (Serving GPRS Support Node) Loaded: loaded (/usr/lib/systemd/system/osmo-sgsn.service; disabled; vendor preset: disabled) Active: active (running) since Tue 2023-02-14 05:05:57 EST; 2s ago Main PID: 20855 (osmo-sgsn) Tasks: 1 (limit: 4908) Memory: 3.0M CGroup: /system.slice/osmo-sgsn.service └─20855 /usr/bin/osmo-sgsn -c /etc/osmocom/osmo-sgsn.cfg
Feb 14 05:05:57 localhost.localdomain systemd[1]: Started Osmocom SGSN (Serving GPRS Support Node). Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: rate_ctr.c:87 validating counter group 0x55b9e6e795c0(sgsn) with 23 counters Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0013> telnet_interface.c:88 Available via telnet 127.0.0.1 4245 Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <001b> gsn.c:431 GTP: gtp_newgsn() started at 127.0.0.1 Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <001b> gsn.c:388 State information file (.//gsn_restart) not found. Creating new file. Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0004> sgsn_libgtp.c:886 Created GTP on 127.0.0.1 Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0004> sgsn_main.c:457 libGTP v1.10.0.1.3506.202302140006 initialized Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <001a> control_if.c:1014 CTRL at 127.0.0.1 4251 Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <001d> gsup_client.c:75 GSUP connecting to 127.0.0.1:4222 Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0020> sccp_user.c:522 OsmoSGSN: Creating SS7 instance Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0020> sccp_user.c:549 OsmoSGSN: Using SS7 instance 0, pc:0.23.4 Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0020> sccp_user.c:556 OsmoSGSN: Creating AS instance Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0020> sccp_user.c:567 OsmoSGSN: Using AS instance as-clnt-OsmoSGSN Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0020> sccp_user.c:572 OsmoSGSN: Creating default route Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0020> sccp_user.c:612 OsmoSGSN: No unassociated ASP for m3ua, creating new ASP asp-clnt-OsmoSGSN Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <0020> sccp_user.c:647 OsmoSGSN: Using ASP instance asp-clnt-OsmoSGSN Feb 14 05:05:57 localhost.localdomain osmo-sgsn[20855]: <001f> osmo_ss7.c:458 0: Creating SCCP instance Feb 14 05:05:58 localhost.localdomain osmo-sgsn[20855]: <001d> gsup_client.c:75 GSUP connecting to 127.0.0.1:4222 Feb 14 05:05:58 localhost.localdomain osmo-sgsn[20855]: <0015> input/ipa.c:141 127.0.0.1:4222 connection done Feb 14 05:05:58 localhost.localdomain osmo-sgsn[20855]: <0015> input/ipaccess.c:920 received ID_GET for unit ID 0/0/0 + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-sip-connector ● osmo-sip-connector.service - Osmo SIP Connector Loaded: loaded (/usr/lib/systemd/system/osmo-sip-connector.service; disabled; vendor preset: disabled) Active: active (running) since Tue 2023-02-14 05:05:57 EST; 2s ago Main PID: 20864 (osmo-sip-connec) Tasks: 1 (limit: 4908) Memory: 2.1M CGroup: /system.slice/osmo-sip-connector.service └─20864 /usr/bin/osmo-sip-connector -c /etc/osmocom/osmo-sip-connector.cfg
Feb 14 05:05:57 localhost.localdomain systemd[1]: Started Osmo SIP Connector. Feb 14 05:05:57 localhost.localdomain osmo-sip-connector[20864]: <0004> telnet_interface.c:88 Available via telnet 127.0.0.1 4256 Feb 14 05:05:57 localhost.localdomain osmo-sip-connector[20864]: <0001> mncc.c:1083 Scheduling MNCC connect Feb 14 05:05:57 localhost.localdomain osmo-sip-connector[20864]: <0000> sip.c:724 su_source_port_create() returns 0x55c47fae44a0 Feb 14 05:05:57 localhost.localdomain osmo-sip-connector[20864]: <0001> mncc.c:975 Failed to connect(/tmp/msc_mncc). Retrying + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-smlc ● osmo-smlc.service - Osmocom Serving Mobile Location Center (SMLC) Loaded: loaded (/usr/lib/systemd/system/osmo-smlc.service; disabled; vendor preset: disabled) Active: active (running) since Tue 2023-02-14 05:05:57 EST; 2s ago Main PID: 20873 (osmo-smlc) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-smlc.service └─20873 /usr/bin/osmo-smlc -c /etc/osmocom/osmo-smlc.cfg
Feb 14 05:05:57 localhost.localdomain systemd[1]: Started Osmocom Serving Mobile Location Center (SMLC). Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <0004> telnet_interface.c:88 Available via telnet 127.0.0.1 4271 Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <000b> control_if.c:1014 CTRL at 127.0.0.1 4272 Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <0011> sccp_user.c:522 Lb: Creating SS7 instance Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <0011> sccp_user.c:549 Lb: Using SS7 instance 0, pc:0.23.6 Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <0011> sccp_user.c:556 Lb: Creating AS instance Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <0011> sccp_user.c:567 Lb: Using AS instance as-clnt-Lb Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <0011> sccp_user.c:572 Lb: Creating default route Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <0011> sccp_user.c:612 Lb: No unassociated ASP for m3ua, creating new ASP asp-clnt-Lb Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <0011> sccp_user.c:647 Lb: Using ASP instance asp-clnt-Lb Feb 14 05:05:57 localhost.localdomain osmo-smlc[20873]: <0010> osmo_ss7.c:458 0: Creating SCCP instance + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-stp ● osmo-stp.service - Osmocom STP (Signal Transfer Point) Loaded: loaded (/usr/lib/systemd/system/osmo-stp.service; disabled; vendor preset: disabled) Active: active (running) since Tue 2023-02-14 05:05:57 EST; 2s ago Docs: https://osmocom.org/projects/osmo-stp/wiki Main PID: 20875 (osmo-stp) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-stp.service └─20875 /usr/bin/osmo-stp -c /etc/osmocom/osmo-stp.cfg
Feb 14 05:05:57 localhost.localdomain systemd[1]: Started Osmocom STP (Signal Transfer Point). Feb 14 05:05:57 localhost.localdomain osmo-stp[20875]: DLGLOBAL <0000> telnet_interface.c:88 Available via telnet 127.0.0.1 4239 Feb 14 05:05:57 localhost.localdomain osmo-stp[20875]: DLSS7 <000c> osmo_ss7.c:1645 0: asp-asp-dyn-0: ASP Restart for server not implemented yet! + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-bsc-nat ● osmo-bsc-nat.service - Osmocom BSC NAT Loaded: loaded (/usr/lib/systemd/system/osmo-bsc-nat.service; disabled; vendor preset: disabled) Active: active (running) since Tue 2023-02-14 05:05:57 EST; 2s ago Main PID: 20876 (osmo-bsc-nat) Tasks: 1 (limit: 4908) Memory: 2.0M CGroup: /system.slice/osmo-bsc-nat.service └─20876 /usr/bin/osmo-bsc-nat -c /etc/osmocom/osmo-bsc-nat.cfg
Feb 14 05:05:57 localhost.localdomain systemd[1]: Started Osmocom BSC NAT. Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000d> osmo_ss7.c:1645 0: asp-asp-clnt-OsmoBSCNAT-CN: ASP Restart for server not implemented yet! Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000d> osmo_ss7.c:1645 1: asp-asp-clnt-OsmoBSCNAT-RAN: ASP Restart for server not implemented yet! Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <0001> telnet_interface.c:88 Available via telnet 127.0.0.1 4244 Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:549 OsmoBSCNAT-CN: Using SS7 instance 0, pc:0.23.3 Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:556 OsmoBSCNAT-CN: Creating AS instance Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:567 OsmoBSCNAT-CN: Using AS instance as-clnt-OsmoBSCNAT-CN Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:572 OsmoBSCNAT-CN: Creating default route Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:606 OsmoBSCNAT-CN: ASP asp-clnt-OsmoBSCNAT-CN for m3ua is not associated with any AS, using it Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:647 OsmoBSCNAT-CN: Using ASP instance asp-clnt-OsmoBSCNAT-CN Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000d> osmo_ss7.c:458 0: Creating SCCP instance Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:549 OsmoBSCNAT-RAN: Using SS7 instance 1, pc:0.23.1 Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:556 OsmoBSCNAT-RAN: Creating AS instance Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:567 OsmoBSCNAT-RAN: Using AS instance as-clnt-OsmoBSCNAT-RAN Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:572 OsmoBSCNAT-RAN: Creating default route Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:606 OsmoBSCNAT-RAN: ASP asp-clnt-OsmoBSCNAT-RAN for m3ua is not associated with any AS, using it Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000e> sccp_user.c:647 OsmoBSCNAT-RAN: Using ASP instance asp-clnt-OsmoBSCNAT-RAN Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000d> osmo_ss7.c:458 1: Creating SCCP instance Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <0011> mgcp_client.c:789 MGW(bscnat) MGCP client: using endpoint domain '@bscnat' Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <0000> bsc_nat_fsm.c:351 MGW pool with 1 pool member(s) initialized Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <0000> bssap.c:34 Tx RESET to PC=0.23.1 in CN Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <000d> m3ua.c:508 XUA_AS(as-clnt-OsmoBSCNAT-CN)[0x5566ef671be0]{AS_DOWN}: Event AS-TRANSFER.req not permitted Feb 14 05:05:57 localhost.localdomain osmo-bsc-nat[20876]: <0000> msc_fsm.c:67 Could not send RESET to MSC (SCCP not up yet?) Feb 14 05:05:59 localhost.localdomain osmo-bsc-nat[20876]: <0010> m3ua.c:625 0: asp-asp-clnt-OsmoBSCNAT-CN: Received NOTIFY Type State Change:AS Inactive () Feb 14 05:05:59 localhost.localdomain osmo-bsc-nat[20876]: <000d> xua_default_lm_fsm.c:361 xua_default_lm(asp-clnt-OsmoBSCNAT-CN)[0x5566ef671db0]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm Feb 14 05:05:59 localhost.localdomain osmo-bsc-nat[20876]: <0010> m3ua.c:625 0: asp-asp-clnt-OsmoBSCNAT-CN: Received NOTIFY Type State Change:AS Active () + for service in $services_feed + systemctl --no-pager -l -n 200 status osmo-upf ● osmo-upf.service - Osmocom User Plane Function (UPF) Loaded: loaded (/usr/lib/systemd/system/osmo-upf.service; disabled; vendor preset: disabled) Active: activating (auto-restart) (Result: exit-code) since Tue 2023-02-14 05:05:59 EST; 189ms ago Process: 22586 ExecStart=/usr/bin/osmo-upf -c /etc/osmocom/osmo-upf.cfg (code=exited, status=255) Main PID: 22586 (code=exited, status=255) + failed=' osmo-upf' + journalctl -u osmo-upf -n 200 -- Logs begin at Tue 2023-02-14 05:04:11 EST, end at Tue 2023-02-14 05:05:59 EST. -- Feb 14 05:05:57 localhost.localdomain systemd[1]: Started Osmocom User Plane Function (UPF). Feb 14 05:05:57 localhost.localdomain osmo-upf[20885]: 20230214050557683 DLGLOBAL NOTICE Available via telnet 127.0.0.1 4275 (telnet_interface.c:88) Feb 14 05:05:57 localhost.localdomain osmo-upf[20885]: 20230214050557683 DLCTRL NOTICE CTRL at 127.0.0.1 4276 (control_if.c:1014) Feb 14 05:05:57 localhost.localdomain osmo-upf[20885]: Error: Could not process rule: Invalid argument Feb 14 05:05:57 localhost.localdomain osmo-upf[20885]: add table inet osmo-upf { flags owner; }; Feb 14 05:05:57 localhost.localdomain osmo-upf[20885]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 14 05:05:57 localhost.localdomain osmo-upf[20885]: 20230214050557699 DNFT ERROR error running nft ruleset: rc=-1 ruleset="add table inet osmo-upf { flags owner; };\n" (upf_nft.c:80) Feb 14 05:05:57 localhost.localdomain osmo-upf[20885]: 20230214050557699 DNFT ERROR Failed to create nft table "osmo-upf" (upf_nft.c:112) Feb 14 05:05:57 localhost.localdomain osmo-upf[20885]: Feb 14 05:05:57 localhost.localdomain systemd[1]: osmo-upf.service: Main process exited, code=exited, status=255/n/a Feb 14 05:05:57 localhost.localdomain systemd[1]: osmo-upf.service: Failed with result 'exit-code'. Feb 14 05:05:59 localhost.localdomain systemd[1]: osmo-upf.service: Service RestartSec=2s expired, scheduling restart. Feb 14 05:05:59 localhost.localdomain systemd[1]: osmo-upf.service: Scheduled restart job, restart counter is at 1. Feb 14 05:05:59 localhost.localdomain systemd[1]: Stopped Osmocom User Plane Function (UPF). Feb 14 05:05:59 localhost.localdomain systemd[1]: Started Osmocom User Plane Function (UPF). Feb 14 05:05:59 localhost.localdomain osmo-upf[22586]: 20230214050559733 DLGLOBAL NOTICE Available via telnet 127.0.0.1 4275 (telnet_interface.c:88) Feb 14 05:05:59 localhost.localdomain osmo-upf[22586]: 20230214050559734 DLCTRL NOTICE CTRL at 127.0.0.1 4276 (control_if.c:1014) Feb 14 05:05:59 localhost.localdomain osmo-upf[22586]: Error: Could not process rule: Invalid argument Feb 14 05:05:59 localhost.localdomain osmo-upf[22586]: add table inet osmo-upf { flags owner; }; Feb 14 05:05:59 localhost.localdomain osmo-upf[22586]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Feb 14 05:05:59 localhost.localdomain osmo-upf[22586]: 20230214050559749 DNFT ERROR error running nft ruleset: rc=-1 ruleset="add table inet osmo-upf { flags owner; };\n" (upf_nft.c:80) Feb 14 05:05:59 localhost.localdomain osmo-upf[22586]: 20230214050559749 DNFT ERROR Failed to create nft table "osmo-upf" (upf_nft.c:112) Feb 14 05:05:59 localhost.localdomain osmo-upf[22586]: Feb 14 05:05:59 localhost.localdomain systemd[1]: osmo-upf.service: Main process exited, code=exited, status=255/n/a Feb 14 05:05:59 localhost.localdomain systemd[1]: osmo-upf.service: Failed with result 'exit-code'. + systemctl stop osmo-bsc osmo-bts-virtual osmo-cbc osmo-gbproxy osmo-gtphub osmo-hlr osmo-hnbgw osmo-hnodeb osmo-mgw osmo-msc osmo-pcap-client osmo-pcap-server osmo-pcu osmo-remsim-bankd osmo-remsim-server osmo-sgsn osmo-sip-connector osmo-smlc osmo-stp osmo-bsc-nat osmo-upf + '[' -n ' osmo-upf' ']' + set +x
ERROR: services failed to start: osmo-upf
+ clean_up_trap + [ -n ] + set +x
### Clean up ###
+ trap - EXIT INT TERM 0 + clean_up + [ -n ] + [ -e _repo_install_test_data/qemu.pid ] + cat _repo_install_test_data/qemu.pid + kill 3481308 + rm -f _repo_install_test_data/temp.qcow2 Build step 'Execute shell' marked build as failure
jenkins-notifications@lists.osmocom.org