Build failed in Jenkins: Osmocom-Debian-install-nightly #416
See <https://jenkins.osmocom.org/jenkins/job/Osmocom-Debian-install-nightly/416/display/redirect> ------------------------------------------ [...truncated 306.50 KB...] Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-2: No Layer Manager, dropping M-ASP_UP.indication Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-2: xua_srv_conn_cb(): sctp_recvmsg() returned 16 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-2: Received M3UA Message (MGMT:NOTIFY) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:634 0: asp-asp-dyn-2: Received NOTIFY Type State Change:AS Inactive () Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-2: No Layer Manager, dropping M-NOTIFY.indication Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-0: xua_srv_conn_cb(): sctp_recvmsg() returned 148 (flags=0x8080) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1415 0: asp-asp-dyn-0: xUA SRV SCTP NOTIFICATION 32770 flags=0x0 Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1428 0: asp-asp-dyn-0: xUA SRV PEER_ADDR_CHANGE Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-0: xua_srv_conn_cb(): sctp_recvmsg() returned 44 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-0: Received M3UA Message (RKM:REG-REQ) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_rkm.c:195 0: asp-asp-dyn-0: RKM: Registering routing key 1 for DPC 0.23.4 Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> fsm.c:423 XUA_AS(as-rkm-1){AS_DOWN}: Allocated Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:963 0: as-as-rkm-1: Created AS Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:785 0: Creating route: pc=188=0.23.4 mask=0xffffff via AS 'as-rkm-1' Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:983 0: as-as-rkm-1: Adding ASP asp-dyn-0 to AS Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_rkm.c:316 XUA_AS(as-rkm-1){AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_as_fsm.c:223 XUA_AS(as-rkm-1){AS_DOWN}: state_chg to AS_INACTIVE Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-0: xua_srv_conn_cb(): sctp_recvmsg() returned 8 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-0: Received M3UA Message (ASPTM:ACTIVE) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> m3ua.c:686 XUA_ASP(asp-dyn-0){ASP_INACTIVE}: Received Event ASPTM-ASP_AC Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:475 XUA_ASP(asp-dyn-0){ASP_INACTIVE}: state_chg to ASP_ACTIVE Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:405 XUA_AS(as-rkm-1){AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_as_fsm.c:281 XUA_AS(as-rkm-1){AS_INACTIVE}: state_chg to AS_ACTIVE Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-0: No Layer Manager, dropping M-ASP_ACTIVE.indication Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-0: xua_srv_conn_cb(): sctp_recvmsg() returned 24 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-0: Received M3UA Message (MGMT:NOTIFY) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:634 0: asp-asp-dyn-0: Received NOTIFY Type State Change:AS Active () Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-0: No Layer Manager, dropping M-NOTIFY.indication Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-1: xua_srv_conn_cb(): sctp_recvmsg() returned 44 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-1: Received M3UA Message (RKM:REG-REQ) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_rkm.c:195 0: asp-asp-dyn-1: RKM: Registering routing key 2 for DPC 0.23.5 Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> fsm.c:423 XUA_AS(as-rkm-2){AS_DOWN}: Allocated Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:963 0: as-as-rkm-2: Created AS Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:785 0: Creating route: pc=189=0.23.5 mask=0xffffff via AS 'as-rkm-2' Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:983 0: as-as-rkm-2: Adding ASP asp-dyn-1 to AS Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_rkm.c:316 XUA_AS(as-rkm-2){AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_as_fsm.c:223 XUA_AS(as-rkm-2){AS_DOWN}: state_chg to AS_INACTIVE Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-1: xua_srv_conn_cb(): sctp_recvmsg() returned 8 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-1: Received M3UA Message (ASPTM:ACTIVE) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> m3ua.c:686 XUA_ASP(asp-dyn-1){ASP_INACTIVE}: Received Event ASPTM-ASP_AC Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:475 XUA_ASP(asp-dyn-1){ASP_INACTIVE}: state_chg to ASP_ACTIVE Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:405 XUA_AS(as-rkm-2){AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_as_fsm.c:281 XUA_AS(as-rkm-2){AS_INACTIVE}: state_chg to AS_ACTIVE Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-1: No Layer Manager, dropping M-ASP_ACTIVE.indication Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-1: xua_srv_conn_cb(): sctp_recvmsg() returned 24 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-1: Received M3UA Message (MGMT:NOTIFY) Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLM3UA <000f> m3ua.c:634 0: asp-asp-dyn-1: Received NOTIFY Type State Change:AS Active () Oct 29 04:54:12 b6b846a29505 osmo-stp[13357]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-1: No Layer Manager, dropping M-NOTIFY.indication + systemctl --no-pager -l -n 200 status osmo-pcap-server ● osmo-pcap-server.service - PCAP Server for the PCAP aggregation Loaded: loaded (/lib/systemd/system/osmo-pcap-server.service; disabled; vendor preset: enabled) Active: active (running) since Tue 2019-10-29 04:54:10 UTC; 2s ago Main PID: 13358 (osmo-pcap-serve) Tasks: 1 (limit: 4915) CGroup: /docker/b6b846a295055ef7f24e8e09e74f6c980d1e4a2e0235be127dd2a5c0c693cca6/system.slice/osmo-pcap-server.service └─13358 /usr/bin/osmo-pcap-server -c /etc/osmocom/osmo-pcap-server.cfg Oct 29 04:54:10 b6b846a29505 systemd[1]: Started PCAP Server for the PCAP aggregation. Oct 29 04:54:10 b6b846a29505 osmo-pcap-server[13358]: <0005> telnet_interface.c:104 Available via telnet 127.0.0.1 4238 Oct 29 04:54:10 b6b846a29505 osmo-pcap-server[13358]: <0004> osmo_tls.c:44 Going to create DH params for 3072 bits Oct 29 04:54:12 b6b846a29505 osmo-pcap-server[13358]: <0002> osmo_server_network.c:573 New connection from zecke + systemctl --no-pager -l -n 200 status osmo-sgsn ● osmo-sgsn.service - Osmocom SGSN (Serving GPRS Support Node) Loaded: loaded (/lib/systemd/system/osmo-sgsn.service; disabled; vendor preset: enabled) Active: active (running) since Tue 2019-10-29 04:54:10 UTC; 2s ago Main PID: 13359 (osmo-sgsn) Tasks: 1 (limit: 4915) CGroup: /docker/b6b846a295055ef7f24e8e09e74f6c980d1e4a2e0235be127dd2a5c0c693cca6/system.slice/osmo-sgsn.service └─13359 /usr/bin/osmo-sgsn -c /etc/osmocom/osmo-sgsn.cfg Oct 29 04:54:10 b6b846a29505 systemd[1]: osmo-sgsn.service: Failed to reset devices.list: Operation not permitted Oct 29 04:54:10 b6b846a29505 systemd[1]: Started Osmocom SGSN (Serving GPRS Support Node). Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <0020> telnet_interface.c:104 Available via telnet 127.0.0.1 4245 Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <0027> control_if.c:911 CTRL at 127.0.0.1 4251 Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <0028> gtp.c:902 GTP: gtp_newgsn() started at 127.0.0.1 Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <0028> gtp.c:859 State information file (.//gsn_restart) not found. Creating new file. Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <000e> sgsn_libgtp.c:767 Created GTP on 127.0.0.1 Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <000e> sgsn_main.c:474 libGTP v1.4.0.27-1bf4 initialized Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <002a> gsup_client.c:73 GSUP connecting to 127.0.0.1:4222 Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <000f> gprs_ns.c:2072 Listening for nsip packets on 127.0.0.1:23000 Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <000f> gprs_ns.c:2088 NS UDP socket at 127.0.0.1:23000 Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <002d> sccp_user.c:488 OsmoSGSN: Creating SS7 instance Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <002d> sccp_user.c:515 OsmoSGSN: Using SS7 instance 0, pc:0.23.4 Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <002d> sccp_user.c:522 OsmoSGSN: Creating AS instance Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <002d> sccp_user.c:533 OsmoSGSN: Using AS instance as-clnt-OsmoSGSN Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <002d> sccp_user.c:538 OsmoSGSN: Creating default route Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <002d> sccp_user.c:558 OsmoSGSN: Creating ASP instance Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <002d> sccp_user.c:596 OsmoSGSN: Using ASP instance asp-clnt-OsmoSGSN Oct 29 04:54:10 b6b846a29505 osmo-sgsn[13359]: <002c> osmo_ss7.c:432 0: Creating SCCP instance Oct 29 04:54:11 b6b846a29505 osmo-sgsn[13359]: <002a> gsup_client.c:73 GSUP connecting to 127.0.0.1:4222 Oct 29 04:54:12 b6b846a29505 osmo-sgsn[13359]: <002a> gsup_client.c:73 GSUP connecting to 127.0.0.1:4222 Oct 29 04:54:12 b6b846a29505 osmo-sgsn[13359]: <0022> input/ipa.c:128 127.0.0.1:4222 connection done Oct 29 04:54:12 b6b846a29505 osmo-sgsn[13359]: <0022> input/ipaccess.c:720 received ID get from 0/0/0 Oct 29 04:54:12 b6b846a29505 osmo-sgsn[13359]: <002f> m3ua.c:634 0: asp-asp-clnt-OsmoSGSN: Received NOTIFY Type State Change:AS Inactive () Oct 29 04:54:12 b6b846a29505 osmo-sgsn[13359]: <002c> xua_default_lm_fsm.c:354 xua_default_lm(asp-clnt-OsmoSGSN)[0x56351c225210]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm Oct 29 04:54:12 b6b846a29505 osmo-sgsn[13359]: <002f> m3ua.c:634 0: asp-asp-clnt-OsmoSGSN: Received NOTIFY Type State Change:AS Active () + systemctl --no-pager -l -n 200 status osmo-pcu ● osmo-pcu.service - Osmocom osmo-pcu Loaded: loaded (/lib/systemd/system/osmo-pcu.service; enabled; vendor preset: enabled) Active: active (running) since Tue 2019-10-29 04:54:10 UTC; 2s ago Main PID: 13360 (osmo-pcu) Tasks: 1 (limit: 4915) CGroup: /docker/b6b846a295055ef7f24e8e09e74f6c980d1e4a2e0235be127dd2a5c0c693cca6/system.slice/osmo-pcu.service └─13360 /usr/bin/osmo-pcu -c /etc/osmocom/osmo-pcu.cfg Oct 29 04:54:10 b6b846a29505 systemd[1]: osmo-pcu.service: Failed to reset devices.list: Operation not permitted Oct 29 04:54:10 b6b846a29505 systemd[1]: Started Osmocom osmo-pcu. Oct 29 04:54:10 b6b846a29505 osmo-pcu[13360]: <000b> gprs_ns.c:321 NSVCI=65534 Creating NS-VC Oct 29 04:54:10 b6b846a29505 osmo-pcu[13360]: <000e> telnet_interface.c:104 Available via telnet 127.0.0.1 4240 Oct 29 04:54:10 b6b846a29505 osmo-pcu[13360]: <0001> osmobts_sock.cpp:224 Opening OsmoPCU L1 interface to OsmoBTS Oct 29 04:54:10 b6b846a29505 osmo-pcu[13360]: <0001> osmobts_sock.cpp:238 Failed to connect to the BTS (/tmp/pcu_bts). Retrying... + systemctl --no-pager -l -n 200 status osmo-hnbgw ● osmo-hnbgw.service - Osmocom Home Nodeb Gateway (OsmoHNBGW) Loaded: loaded (/lib/systemd/system/osmo-hnbgw.service; disabled; vendor preset: enabled) Active: active (running) since Tue 2019-10-29 04:54:10 UTC; 2s ago Main PID: 13361 (osmo-hnbgw) Tasks: 1 (limit: 4915) CGroup: /docker/b6b846a295055ef7f24e8e09e74f6c980d1e4a2e0235be127dd2a5c0c693cca6/system.slice/osmo-hnbgw.service └─13361 /usr/bin/osmo-hnbgw -c /etc/osmocom/osmo-hnbgw.cfg Oct 29 04:54:10 b6b846a29505 systemd[1]: osmo-hnbgw.service: Failed to reset devices.list: Operation not permitted Oct 29 04:54:10 b6b846a29505 systemd[1]: Started Osmocom Home Nodeb Gateway (OsmoHNBGW). Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLGLOBAL <0004> telnet_interface.c:104 Available via telnet 127.0.0.1 4261 Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLCTRL <000b> control_if.c:911 CTRL at 127.0.0.1 4262 Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DMAIN <0000> hnbgw_cn.c:461 IuCS remote addr not configured, using default: RI=SSN_PC,PC=0.23.1,SSN=RANAP Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DMAIN <0000> hnbgw_cn.c:461 IuPS remote addr not configured, using default: RI=SSN_PC,PC=0.23.4,SSN=RANAP Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DRANAP <0003> hnbgw_cn.c:512 No cs7 instance configured for IuCS nor IuPS, creating default instance Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:361 0: Creating SS7 Instance Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:661 0: Creating Route Table system Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DRANAP <0003> hnbgw_cn.c:525 Local SCCP addr: RI=SSN_PC,PC=0.23.5,SSN=RANAP Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSCCP <0011> sccp_user.c:515 OsmoHNBGW: Using SS7 instance 0, pc:0.23.5 Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSCCP <0011> sccp_user.c:522 OsmoHNBGW: Creating AS instance Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> fsm.c:423 XUA_AS(as-clnt-OsmoHNBGW)[0x55ab70aae440]{AS_DOWN}: Allocated Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:963 0: as-as-clnt-OsmoHNBGW: Created AS Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSCCP <0011> sccp_user.c:533 OsmoHNBGW: Using AS instance as-clnt-OsmoHNBGW Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSCCP <0011> sccp_user.c:538 OsmoHNBGW: Creating default route Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:785 0: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as-clnt-OsmoHNBGW' Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSCCP <0011> sccp_user.c:558 OsmoHNBGW: Creating ASP instance Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:983 0: as-as-clnt-OsmoHNBGW: Adding ASP asp-clnt-OsmoHNBGW to AS Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> fsm.c:423 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{IDLE}: Allocated Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:1290 0: asp-asp-clnt-OsmoHNBGW: Restarting ASP asp-clnt-OsmoHNBGW, 0.0.0.0:0 ==> 127.0.0.1:2905 Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> fsm.c:423 XUA_ASP(asp-clnt-OsmoHNBGW)[0x55ab70aaf600]{ASP_DOWN}: Allocated Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSCCP <0011> sccp_user.c:596 OsmoHNBGW: Using ASP instance asp-clnt-OsmoHNBGW Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:432 0: Creating SCCP instance Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:456 registering user=SCCP for SI 3 with priv 0x55ab70aaf920 Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSCCP <0011> sccp_user.c:88 Binding user 'OsmoHNBGW' to SSN=142 PC=0.23.5 Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DRANAP <0003> hnbgw_cn.c:550 Remote SCCP addr: IuCS: RI=SSN_PC,PC=0.23.1,SSN=RANAP Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DRANAP <0003> hnbgw_cn.c:552 Remote SCCP addr: IuPS: RI=SSN_PC,PC=0.23.4,SSN=RANAP Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DHNBAP <0001> hnbgw.c:598 Using RNC-Id 23 Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DMAIN <0000> hnbgw.c:603 Listening for Iuh at 0.0.0.0 29169 Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:293 [CONNECTING] osmo_stream_cli_fd_cb(): connection done. Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:1546 0: asp-asp-clnt-OsmoHNBGW: Client connected (r=127.0.0.1:2905<->l=127.0.0.1:50177) Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{IDLE}: Received primitive M-SCTP_ESTABLISH.indication Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{IDLE}: Received Event SCTP-ESTABLISH.ind Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_default_lm_fsm.c:142 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{IDLE}: state_chg to WAIT_ASP_UP Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_default_lm_fsm.c:143 XUA_ASP(asp-clnt-OsmoHNBGW)[0x55ab70aaf600]{ASP_DOWN}: Received Event M-ASP_UP.req Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x80) Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (ASPSM:UP-ACK) Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> m3ua.c:686 XUA_ASP(asp-clnt-OsmoHNBGW)[0x55ab70aaf600]{ASP_DOWN}: Received Event ASPSM-ASP_UP_ACK Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_asp_fsm.c:307 XUA_ASP(asp-clnt-OsmoHNBGW)[0x55ab70aaf600]{ASP_DOWN}: T(ack) stopped Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_asp_fsm.c:356 XUA_ASP(asp-clnt-OsmoHNBGW)[0x55ab70aaf600]{ASP_DOWN}: state_chg to ASP_INACTIVE Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_asp_fsm.c:405 XUA_AS(as-clnt-OsmoHNBGW)[0x55ab70aae440]{AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_as_fsm.c:223 XUA_AS(as-clnt-OsmoHNBGW)[0x55ab70aae440]{AS_DOWN}: state_chg to AS_INACTIVE Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{WAIT_ASP_UP}: Received primitive M-ASP_UP.confirm Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{WAIT_ASP_UP}: Received Event ASP-UP.conf Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLSS7 <0010> xua_default_lm_fsm.c:154 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{WAIT_ASP_UP}: state_chg to WAIT_NOTIFY Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:10 b6b846a29505 osmo-hnbgw[13361]: 20191029045410625 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412627 DLSS7 <0010> fsm.c:284 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{WAIT_NOTIFY}: Timeout of T1 Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:176 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{WAIT_NOTIFY}: state_chg to RKM_REG Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_rkm.c:542 0: asp-asp-clnt-OsmoHNBGW: Received XUA Layer Manager Primitive: M-RK_REG.request) Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 36 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (RKM:REG-RESP) Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_rkm.c:418 0: asp-asp-clnt-OsmoHNBGW: Received RKM REG RES rctx=2 status=SUCCESS Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{RKM_REG}: Received primitive M-RK_REG.confirm Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{RKM_REG}: Received Event RKM_REG.conf Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:249 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{RKM_REG}: state_chg to WAIT_NOTIFY Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (MGMT:NOTIFY) Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLM3UA <0013> m3ua.c:634 0: asp-asp-clnt-OsmoHNBGW: Received NOTIFY Type State Change:AS Inactive () Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{WAIT_NOTIFY}: Received primitive M-NOTIFY.indication Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{WAIT_NOTIFY}: Received Event NOTIFY.ind Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:215 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{WAIT_NOTIFY}: state_chg to ACTIVE Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:216 XUA_ASP(asp-clnt-OsmoHNBGW)[0x55ab70aaf600]{ASP_INACTIVE}: Received Event M-ASP_ACTIVE.req Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (ASPTM:ACTIVE-ACK) Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> m3ua.c:686 XUA_ASP(asp-clnt-OsmoHNBGW)[0x55ab70aaf600]{ASP_INACTIVE}: Received Event ASPTM-ASP_AC_ACK Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_asp_fsm.c:307 XUA_ASP(asp-clnt-OsmoHNBGW)[0x55ab70aaf600]{ASP_INACTIVE}: T(ack) stopped Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_asp_fsm.c:438 XUA_ASP(asp-clnt-OsmoHNBGW)[0x55ab70aaf600]{ASP_INACTIVE}: state_chg to ASP_ACTIVE Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_asp_fsm.c:405 XUA_AS(as-clnt-OsmoHNBGW)[0x55ab70aae440]{AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_as_fsm.c:281 XUA_AS(as-clnt-OsmoHNBGW)[0x55ab70aae440]{AS_INACTIVE}: state_chg to AS_ACTIVE Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{ACTIVE}: Received primitive M-ASP_ACTIVE.confirm Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:354 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x80) Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (MGMT:NOTIFY) Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLM3UA <0013> m3ua.c:634 0: asp-asp-clnt-OsmoHNBGW: Received NOTIFY Type State Change:AS Active () Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{ACTIVE}: Received primitive M-NOTIFY.indication Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x55ab70aae910]{ACTIVE}: Received Event NOTIFY.ind Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 29 04:54:12 b6b846a29505 osmo-hnbgw[13361]: 20191029045412628 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data + systemctl --no-pager -l -n 200 status osmo-bts-virtual ● osmo-bts-virtual.service - Osmocom GSM BTS for virtual Um layer based on GSMTAP/UDP Loaded: loaded (/lib/systemd/system/osmo-bts-virtual.service; enabled; vendor preset: enabled) Active: active (running) since Tue 2019-10-29 04:54:10 UTC; 2s ago Main PID: 13362 (osmo-bts-virtua) Tasks: 1 (limit: 4915) CGroup: /docker/b6b846a295055ef7f24e8e09e74f6c980d1e4a2e0235be127dd2a5c0c693cca6/system.slice/osmo-bts-virtual.service └─13362 /usr/bin/osmo-bts-virtual -s -c /etc/osmocom/osmo-bts-virtual.cfg Oct 29 04:54:10 b6b846a29505 systemd[1]: osmo-bts-virtual.service: Failed to reset devices.list: Operation not permitted Oct 29 04:54:10 b6b846a29505 systemd[1]: Started Osmocom GSM BTS for virtual Um layer based on GSMTAP/UDP. + systemctl stop osmo-bsc osmo-gbproxy osmo-ggsn osmo-gtphub osmo-hlr osmo-mgw osmo-msc osmo-pcap-client osmo-sip-connector osmo-stp osmo-pcap-server osmo-sgsn osmo-pcu osmo-hnbgw osmo-bts-virtual + [ -n osmo-gbproxy osmo-ggsn ] + set +x ERROR: services failed to start: osmo-gbproxy osmo-ggsn + ret=1 + [ -n ] + docker container kill repo-install-test-nightly repo-install-test-nightly + exit 1 Build step 'Execute shell' marked build as failure
See <https://jenkins.osmocom.org/jenkins/job/Osmocom-Debian-install-nightly/417/display/redirect?page=changes> Changes: [Pau Espin Pedrol] Introduce ttcn3-stp-test [dwillmann] Add docker image for osmo-nitb-latest ------------------------------------------ [...truncated 306.84 KB...] Oct 30 04:53:52 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-2: Received M3UA Message (MGMT:NOTIFY) Oct 30 04:53:52 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:634 0: asp-asp-dyn-2: Received NOTIFY Type State Change:AS Inactive () Oct 30 04:53:52 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-2: No Layer Manager, dropping M-NOTIFY.indication Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-0: xua_srv_conn_cb(): sctp_recvmsg() returned 44 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-0: Received M3UA Message (RKM:REG-REQ) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_rkm.c:195 0: asp-asp-dyn-0: RKM: Registering routing key 1 for DPC 0.23.4 Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> fsm.c:423 XUA_AS(as-rkm-1){AS_DOWN}: Allocated Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:963 0: as-as-rkm-1: Created AS Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:785 0: Creating route: pc=188=0.23.4 mask=0xffffff via AS 'as-rkm-1' Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:983 0: as-as-rkm-1: Adding ASP asp-dyn-0 to AS Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_rkm.c:316 XUA_AS(as-rkm-1){AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_as_fsm.c:223 XUA_AS(as-rkm-1){AS_DOWN}: state_chg to AS_INACTIVE Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-0: xua_srv_conn_cb(): sctp_recvmsg() returned 8 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-0: Received M3UA Message (ASPTM:ACTIVE) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> m3ua.c:686 XUA_ASP(asp-dyn-0){ASP_INACTIVE}: Received Event ASPTM-ASP_AC Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_asp_fsm.c:475 XUA_ASP(asp-dyn-0){ASP_INACTIVE}: state_chg to ASP_ACTIVE Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_asp_fsm.c:405 XUA_AS(as-rkm-1){AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_as_fsm.c:281 XUA_AS(as-rkm-1){AS_INACTIVE}: state_chg to AS_ACTIVE Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-0: No Layer Manager, dropping M-ASP_ACTIVE.indication Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-0: xua_srv_conn_cb(): sctp_recvmsg() returned 24 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-0: Received M3UA Message (MGMT:NOTIFY) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:634 0: asp-asp-dyn-0: Received NOTIFY Type State Change:AS Active () Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-0: No Layer Manager, dropping M-NOTIFY.indication Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-1: xua_srv_conn_cb(): sctp_recvmsg() returned 44 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-1: Received M3UA Message (RKM:REG-REQ) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_rkm.c:195 0: asp-asp-dyn-1: RKM: Registering routing key 2 for DPC 0.23.5 Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> fsm.c:423 XUA_AS(as-rkm-2){AS_DOWN}: Allocated Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:963 0: as-as-rkm-2: Created AS Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:785 0: Creating route: pc=189=0.23.5 mask=0xffffff via AS 'as-rkm-2' Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:983 0: as-as-rkm-2: Adding ASP asp-dyn-1 to AS Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_rkm.c:316 XUA_AS(as-rkm-2){AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_as_fsm.c:223 XUA_AS(as-rkm-2){AS_DOWN}: state_chg to AS_INACTIVE Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-1: xua_srv_conn_cb(): sctp_recvmsg() returned 8 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-1: Received M3UA Message (ASPTM:ACTIVE) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> m3ua.c:686 XUA_ASP(asp-dyn-1){ASP_INACTIVE}: Received Event ASPTM-ASP_AC Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_asp_fsm.c:475 XUA_ASP(asp-dyn-1){ASP_INACTIVE}: state_chg to ASP_ACTIVE Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_asp_fsm.c:405 XUA_AS(as-rkm-2){AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_as_fsm.c:281 XUA_AS(as-rkm-2){AS_INACTIVE}: state_chg to AS_ACTIVE Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-1: No Layer Manager, dropping M-ASP_ACTIVE.indication Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> osmo_ss7.c:1482 0: asp-asp-dyn-1: xua_srv_conn_cb(): sctp_recvmsg() returned 24 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:722 0: asp-asp-dyn-1: Received M3UA Message (MGMT:NOTIFY) Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLM3UA <000f> m3ua.c:634 0: asp-asp-dyn-1: Received NOTIFY Type State Change:AS Active () Oct 30 04:53:53 afbf5abfb165 osmo-stp[13355]: DLSS7 <000c> xua_asp_fsm.c:125 0: asp-asp-dyn-1: No Layer Manager, dropping M-NOTIFY.indication + systemctl --no-pager -l -n 200 status osmo-pcap-server ● osmo-pcap-server.service - PCAP Server for the PCAP aggregation Loaded: loaded (/lib/systemd/system/osmo-pcap-server.service; disabled; vendor preset: enabled) Active: active (running) since Wed 2019-10-30 04:53:51 UTC; 2s ago Main PID: 13356 (osmo-pcap-serve) Tasks: 1 (limit: 4915) CGroup: /docker/afbf5abfb165a85abe9cd97cf45536407a57436a20b734ff133697ccaa010793/system.slice/osmo-pcap-server.service └─13356 /usr/bin/osmo-pcap-server -c /etc/osmocom/osmo-pcap-server.cfg Oct 30 04:53:51 afbf5abfb165 systemd[1]: osmo-pcap-server.service: Failed to reset devices.list: Operation not permitted Oct 30 04:53:51 afbf5abfb165 systemd[1]: Started PCAP Server for the PCAP aggregation. Oct 30 04:53:51 afbf5abfb165 osmo-pcap-server[13356]: <0005> telnet_interface.c:104 Available via telnet 127.0.0.1 4238 Oct 30 04:53:51 afbf5abfb165 osmo-pcap-server[13356]: <0004> osmo_tls.c:44 Going to create DH params for 3072 bits Oct 30 04:53:53 afbf5abfb165 osmo-pcap-server[13356]: <0002> osmo_server_network.c:573 New connection from zecke + systemctl --no-pager -l -n 200 status osmo-sgsn ● osmo-sgsn.service - Osmocom SGSN (Serving GPRS Support Node) Loaded: loaded (/lib/systemd/system/osmo-sgsn.service; disabled; vendor preset: enabled) Active: active (running) since Wed 2019-10-30 04:53:51 UTC; 2s ago Main PID: 13357 (osmo-sgsn) Tasks: 1 (limit: 4915) CGroup: /docker/afbf5abfb165a85abe9cd97cf45536407a57436a20b734ff133697ccaa010793/system.slice/osmo-sgsn.service └─13357 /usr/bin/osmo-sgsn -c /etc/osmocom/osmo-sgsn.cfg Oct 30 04:53:51 afbf5abfb165 systemd[1]: osmo-sgsn.service: Failed to reset devices.list: Operation not permitted Oct 30 04:53:51 afbf5abfb165 systemd[1]: Started Osmocom SGSN (Serving GPRS Support Node). Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <0020> telnet_interface.c:104 Available via telnet 127.0.0.1 4245 Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <0027> control_if.c:911 CTRL at 127.0.0.1 4251 Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <0028> gtp.c:902 GTP: gtp_newgsn() started at 127.0.0.1 Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <0028> gtp.c:859 State information file (.//gsn_restart) not found. Creating new file. Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <000e> sgsn_libgtp.c:767 Created GTP on 127.0.0.1 Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <000e> sgsn_main.c:474 libGTP v1.4.0.27-1bf4 initialized Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <002a> gsup_client.c:73 GSUP connecting to 127.0.0.1:4222 Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <000f> gprs_ns.c:2072 Listening for nsip packets on 127.0.0.1:23000 Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <000f> gprs_ns.c:2088 NS UDP socket at 127.0.0.1:23000 Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <002d> sccp_user.c:488 OsmoSGSN: Creating SS7 instance Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <002d> sccp_user.c:515 OsmoSGSN: Using SS7 instance 0, pc:0.23.4 Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <002d> sccp_user.c:522 OsmoSGSN: Creating AS instance Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <002d> sccp_user.c:533 OsmoSGSN: Using AS instance as-clnt-OsmoSGSN Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <002d> sccp_user.c:538 OsmoSGSN: Creating default route Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <002d> sccp_user.c:558 OsmoSGSN: Creating ASP instance Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <002d> sccp_user.c:596 OsmoSGSN: Using ASP instance asp-clnt-OsmoSGSN Oct 30 04:53:51 afbf5abfb165 osmo-sgsn[13357]: <002c> osmo_ss7.c:432 0: Creating SCCP instance Oct 30 04:53:52 afbf5abfb165 osmo-sgsn[13357]: <002a> gsup_client.c:73 GSUP connecting to 127.0.0.1:4222 Oct 30 04:53:52 afbf5abfb165 osmo-sgsn[13357]: <0022> input/ipa.c:128 127.0.0.1:4222 connection done Oct 30 04:53:52 afbf5abfb165 osmo-sgsn[13357]: <0022> input/ipaccess.c:720 received ID get from 0/0/0 Oct 30 04:53:53 afbf5abfb165 osmo-sgsn[13357]: <002f> m3ua.c:634 0: asp-asp-clnt-OsmoSGSN: Received NOTIFY Type State Change:AS Inactive () Oct 30 04:53:53 afbf5abfb165 osmo-sgsn[13357]: <002c> xua_default_lm_fsm.c:354 xua_default_lm(asp-clnt-OsmoSGSN)[0x55775ea94210]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm Oct 30 04:53:53 afbf5abfb165 osmo-sgsn[13357]: <002f> m3ua.c:634 0: asp-asp-clnt-OsmoSGSN: Received NOTIFY Type State Change:AS Active () + systemctl --no-pager -l -n 200 status osmo-pcu ● osmo-pcu.service - Osmocom osmo-pcu Loaded: loaded (/lib/systemd/system/osmo-pcu.service; enabled; vendor preset: enabled) Active: active (running) since Wed 2019-10-30 04:53:51 UTC; 2s ago Main PID: 13358 (osmo-pcu) Tasks: 1 (limit: 4915) CGroup: /docker/afbf5abfb165a85abe9cd97cf45536407a57436a20b734ff133697ccaa010793/system.slice/osmo-pcu.service └─13358 /usr/bin/osmo-pcu -c /etc/osmocom/osmo-pcu.cfg Oct 30 04:53:51 afbf5abfb165 systemd[1]: osmo-pcu.service: Failed to reset devices.list: Operation not permitted Oct 30 04:53:51 afbf5abfb165 systemd[1]: Started Osmocom osmo-pcu. Oct 30 04:53:51 afbf5abfb165 osmo-pcu[13358]: <000b> gprs_ns.c:321 NSVCI=65534 Creating NS-VC Oct 30 04:53:51 afbf5abfb165 osmo-pcu[13358]: <000e> telnet_interface.c:104 Available via telnet 127.0.0.1 4240 Oct 30 04:53:51 afbf5abfb165 osmo-pcu[13358]: <0001> osmobts_sock.cpp:224 Opening OsmoPCU L1 interface to OsmoBTS Oct 30 04:53:51 afbf5abfb165 osmo-pcu[13358]: <0001> osmobts_sock.cpp:238 Failed to connect to the BTS (/tmp/pcu_bts). Retrying... + systemctl --no-pager -l -n 200 status osmo-hnbgw ● osmo-hnbgw.service - Osmocom Home Nodeb Gateway (OsmoHNBGW) Loaded: loaded (/lib/systemd/system/osmo-hnbgw.service; disabled; vendor preset: enabled) Active: active (running) since Wed 2019-10-30 04:53:51 UTC; 2s ago Main PID: 13359 (osmo-hnbgw) Tasks: 1 (limit: 4915) CGroup: /docker/afbf5abfb165a85abe9cd97cf45536407a57436a20b734ff133697ccaa010793/system.slice/osmo-hnbgw.service └─13359 /usr/bin/osmo-hnbgw -c /etc/osmocom/osmo-hnbgw.cfg Oct 30 04:53:51 afbf5abfb165 systemd[1]: osmo-hnbgw.service: Failed to reset devices.list: Operation not permitted Oct 30 04:53:51 afbf5abfb165 systemd[1]: Started Osmocom Home Nodeb Gateway (OsmoHNBGW). Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLGLOBAL <0004> telnet_interface.c:104 Available via telnet 127.0.0.1 4261 Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLCTRL <000b> control_if.c:911 CTRL at 127.0.0.1 4262 Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DMAIN <0000> hnbgw_cn.c:461 IuCS remote addr not configured, using default: RI=SSN_PC,PC=0.23.1,SSN=RANAP Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DMAIN <0000> hnbgw_cn.c:461 IuPS remote addr not configured, using default: RI=SSN_PC,PC=0.23.4,SSN=RANAP Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DRANAP <0003> hnbgw_cn.c:512 No cs7 instance configured for IuCS nor IuPS, creating default instance Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSS7 <0010> osmo_ss7.c:361 0: Creating SS7 Instance Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSS7 <0010> osmo_ss7.c:661 0: Creating Route Table system Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DRANAP <0003> hnbgw_cn.c:525 Local SCCP addr: RI=SSN_PC,PC=0.23.5,SSN=RANAP Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSCCP <0011> sccp_user.c:515 OsmoHNBGW: Using SS7 instance 0, pc:0.23.5 Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSCCP <0011> sccp_user.c:522 OsmoHNBGW: Creating AS instance Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSS7 <0010> fsm.c:423 XUA_AS(as-clnt-OsmoHNBGW)[0x5622b52f2440]{AS_DOWN}: Allocated Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSS7 <0010> osmo_ss7.c:963 0: as-as-clnt-OsmoHNBGW: Created AS Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSCCP <0011> sccp_user.c:533 OsmoHNBGW: Using AS instance as-clnt-OsmoHNBGW Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSCCP <0011> sccp_user.c:538 OsmoHNBGW: Creating default route Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSS7 <0010> osmo_ss7.c:785 0: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as-clnt-OsmoHNBGW' Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSCCP <0011> sccp_user.c:558 OsmoHNBGW: Creating ASP instance Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSS7 <0010> osmo_ss7.c:983 0: as-as-clnt-OsmoHNBGW: Adding ASP asp-clnt-OsmoHNBGW to AS Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSS7 <0010> fsm.c:423 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{IDLE}: Allocated Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351112 DLSS7 <0010> osmo_ss7.c:1290 0: asp-asp-clnt-OsmoHNBGW: Restarting ASP asp-clnt-OsmoHNBGW, 0.0.0.0:0 ==> 127.0.0.1:2905 Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> fsm.c:423 XUA_ASP(asp-clnt-OsmoHNBGW)[0x5622b52f3600]{ASP_DOWN}: Allocated Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSCCP <0011> sccp_user.c:596 OsmoHNBGW: Using ASP instance asp-clnt-OsmoHNBGW Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> osmo_ss7.c:432 0: Creating SCCP instance Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> osmo_ss7.c:456 registering user=SCCP for SI 3 with priv 0x5622b52f3920 Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSCCP <0011> sccp_user.c:88 Binding user 'OsmoHNBGW' to SSN=142 PC=0.23.5 Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DRANAP <0003> hnbgw_cn.c:550 Remote SCCP addr: IuCS: RI=SSN_PC,PC=0.23.1,SSN=RANAP Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DRANAP <0003> hnbgw_cn.c:552 Remote SCCP addr: IuPS: RI=SSN_PC,PC=0.23.4,SSN=RANAP Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DHNBAP <0001> hnbgw.c:598 Using RNC-Id 23 Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DMAIN <0000> hnbgw.c:603 Listening for Iuh at 0.0.0.0 29169 Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:293 [CONNECTING] osmo_stream_cli_fd_cb(): connection done. Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> osmo_ss7.c:1546 0: asp-asp-clnt-OsmoHNBGW: Client connected (r=127.0.0.1:2905<->l=127.0.0.1:57403) Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{IDLE}: Received primitive M-SCTP_ESTABLISH.indication Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{IDLE}: Received Event SCTP-ESTABLISH.ind Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_default_lm_fsm.c:142 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{IDLE}: state_chg to WAIT_ASP_UP Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_default_lm_fsm.c:143 XUA_ASP(asp-clnt-OsmoHNBGW)[0x5622b52f3600]{ASP_DOWN}: Received Event M-ASP_UP.req Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x80) Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (ASPSM:UP-ACK) Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> m3ua.c:686 XUA_ASP(asp-clnt-OsmoHNBGW)[0x5622b52f3600]{ASP_DOWN}: Received Event ASPSM-ASP_UP_ACK Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_asp_fsm.c:307 XUA_ASP(asp-clnt-OsmoHNBGW)[0x5622b52f3600]{ASP_DOWN}: T(ack) stopped Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_asp_fsm.c:356 XUA_ASP(asp-clnt-OsmoHNBGW)[0x5622b52f3600]{ASP_DOWN}: state_chg to ASP_INACTIVE Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_asp_fsm.c:405 XUA_AS(as-clnt-OsmoHNBGW)[0x5622b52f2440]{AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_as_fsm.c:223 XUA_AS(as-clnt-OsmoHNBGW)[0x5622b52f2440]{AS_DOWN}: state_chg to AS_INACTIVE Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{WAIT_ASP_UP}: Received primitive M-ASP_UP.confirm Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{WAIT_ASP_UP}: Received Event ASP-UP.conf Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLSS7 <0010> xua_default_lm_fsm.c:154 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{WAIT_ASP_UP}: state_chg to WAIT_NOTIFY Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:51 afbf5abfb165 osmo-hnbgw[13359]: 20191030045351113 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 30 04:53:52 afbf5abfb165 osmo-hnbgw[13359]: 20191030045352940 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 30 04:53:52 afbf5abfb165 osmo-hnbgw[13359]: 20191030045352940 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 30 04:53:52 afbf5abfb165 osmo-hnbgw[13359]: 20191030045352940 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 148 (flags=0x8080) Oct 30 04:53:52 afbf5abfb165 osmo-hnbgw[13359]: 20191030045352940 DLSS7 <0010> osmo_ss7.c:1415 0: asp-asp-clnt-OsmoHNBGW: xUA CLNT SCTP NOTIFICATION 32770 flags=0x0 Oct 30 04:53:52 afbf5abfb165 osmo-hnbgw[13359]: 20191030045352940 DLSS7 <0010> osmo_ss7.c:1428 0: asp-asp-clnt-OsmoHNBGW: xUA CLNT PEER_ADDR_CHANGE Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> fsm.c:284 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{WAIT_NOTIFY}: Timeout of T1 Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_default_lm_fsm.c:176 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{WAIT_NOTIFY}: state_chg to RKM_REG Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_rkm.c:542 0: asp-asp-clnt-OsmoHNBGW: Received XUA Layer Manager Primitive: M-RK_REG.request) Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 36 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (RKM:REG-RESP) Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_rkm.c:418 0: asp-asp-clnt-OsmoHNBGW: Received RKM REG RES rctx=2 status=SUCCESS Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{RKM_REG}: Received primitive M-RK_REG.confirm Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{RKM_REG}: Received Event RKM_REG.conf Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_default_lm_fsm.c:249 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{RKM_REG}: state_chg to WAIT_NOTIFY Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (MGMT:NOTIFY) Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLM3UA <0013> m3ua.c:634 0: asp-asp-clnt-OsmoHNBGW: Received NOTIFY Type State Change:AS Inactive () Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{WAIT_NOTIFY}: Received primitive M-NOTIFY.indication Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{WAIT_NOTIFY}: Received Event NOTIFY.ind Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_default_lm_fsm.c:215 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{WAIT_NOTIFY}: state_chg to ACTIVE Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLSS7 <0010> xua_default_lm_fsm.c:216 XUA_ASP(asp-clnt-OsmoHNBGW)[0x5622b52f3600]{ASP_INACTIVE}: Received Event M-ASP_ACTIVE.req Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353113 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (ASPTM:ACTIVE-ACK) Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> m3ua.c:686 XUA_ASP(asp-clnt-OsmoHNBGW)[0x5622b52f3600]{ASP_INACTIVE}: Received Event ASPTM-ASP_AC_ACK Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> xua_asp_fsm.c:307 XUA_ASP(asp-clnt-OsmoHNBGW)[0x5622b52f3600]{ASP_INACTIVE}: T(ack) stopped Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> xua_asp_fsm.c:438 XUA_ASP(asp-clnt-OsmoHNBGW)[0x5622b52f3600]{ASP_INACTIVE}: state_chg to ASP_ACTIVE Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> xua_asp_fsm.c:405 XUA_AS(as-clnt-OsmoHNBGW)[0x5622b52f2440]{AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> xua_as_fsm.c:281 XUA_AS(as-clnt-OsmoHNBGW)[0x5622b52f2440]{AS_INACTIVE}: state_chg to AS_ACTIVE Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{ACTIVE}: Received primitive M-ASP_ACTIVE.confirm Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> xua_default_lm_fsm.c:354 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLINP <0006> stream.c:310 [ CONNECTED] osmo_stream_cli_fd_cb(): connected read Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLINP <0006> stream.c:219 [ CONNECTED] osmo_stream_cli_read(): message received Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> osmo_ss7.c:1623 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x80) Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLM3UA <0013> m3ua.c:722 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (MGMT:NOTIFY) Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLM3UA <0013> m3ua.c:634 0: asp-asp-clnt-OsmoHNBGW: Received NOTIFY Type State Change:AS Active () Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{ACTIVE}: Received primitive M-NOTIFY.indication Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLSS7 <0010> xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x5622b52f2910]{ACTIVE}: Received Event NOTIFY.ind Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLINP <0006> stream.c:314 [ CONNECTED] osmo_stream_cli_fd_cb(): connected write Oct 30 04:53:53 afbf5abfb165 osmo-hnbgw[13359]: 20191030045353114 DLINP <0006> stream.c:234 [ CONNECTED] osmo_stream_cli_write(): sending data + systemctl --no-pager -l -n 200 status osmo-bts-virtual ● osmo-bts-virtual.service - Osmocom GSM BTS for virtual Um layer based on GSMTAP/UDP Loaded: loaded (/lib/systemd/system/osmo-bts-virtual.service; enabled; vendor preset: enabled) Active: active (running) since Wed 2019-10-30 04:53:51 UTC; 2s ago Main PID: 13360 (osmo-bts-virtua) Tasks: 1 (limit: 4915) CGroup: /docker/afbf5abfb165a85abe9cd97cf45536407a57436a20b734ff133697ccaa010793/system.slice/osmo-bts-virtual.service └─13360 /usr/bin/osmo-bts-virtual -s -c /etc/osmocom/osmo-bts-virtual.cfg Oct 30 04:53:51 afbf5abfb165 systemd[1]: osmo-bts-virtual.service: Failed to reset devices.list: Operation not permitted Oct 30 04:53:51 afbf5abfb165 systemd[1]: Started Osmocom GSM BTS for virtual Um layer based on GSMTAP/UDP. + systemctl stop osmo-bsc osmo-gbproxy osmo-ggsn osmo-gtphub osmo-hlr osmo-mgw osmo-msc osmo-pcap-client osmo-sip-connector osmo-stp osmo-pcap-server osmo-sgsn osmo-pcu osmo-hnbgw osmo-bts-virtual + [ -n osmo-ggsn ] + set +x ERROR: services failed to start: osmo-ggsn + ret=1 + [ -n ] + docker container kill repo-install-test-nightly repo-install-test-nightly + exit 1 Build step 'Execute shell' marked build as failure
participants (1)
-
jenkins＠lists.osmocom.org