Build failed in Jenkins: Osmocom-Debian-install-nightly #417

30 Oct 2019

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

2024

2023

2022

2021

2020

2019

Build failed in Jenkins: Osmocom-Debian-install-nightly #417