See
<https://jenkins.osmocom.org/jenkins/job/Osmocom-repo-install-debian9/feed=nightly,label=repo-install-test/25/display/redirect>
Changes:
------------------------------------------
[...truncated 348.47 KB...]
Feb 17 10:43:53 ea033645555f osmo-pcap-client[15461]: <0005> telnet_interface.c:104
Available via telnet 127.0.0.1 4237
Feb 17 10:43:53 ea033645555f systemd[1]: osmo-pcap-client.service: Failed to reset
devices.list: Operation not permitted
Feb 17 10:43:53 ea033645555f systemd[1]: Started PCAP Client for the PCAP aggregation.
Feb 17 10:43:53 ea033645555f osmo-pcap-client[15461]: <0001>
osmo_client_network.c:78 Lost connection on read conn=default
Feb 17 10:43:55 ea033645555f osmo-pcap-client[15461]: <0001>
osmo_client_network.c:78 Lost connection on read conn=default
+ systemctl --no-pager -l -n 200 status osmo-sip-connector
● osmo-sip-connector.service - Osmo SIP Connector
Loaded: loaded (/lib/systemd/system/osmo-sip-connector.service; enabled; vendor preset:
enabled)
Active: active (running) since Wed 2021-02-17 10:43:53 UTC; 2s ago
Main PID: 15462 (osmo-sip-connec)
Tasks: 1 (limit: 4915)
CGroup:
/docker/ea033645555f84c2eb962f8786c25ff0275716b9d1d8b5ae767673da0fd08527/system.slice/osmo-sip-connector.service
└─15462 /usr/bin/osmo-sip-connector -c /etc/osmocom/osmo-sip-connector.cfg
Feb 17 10:43:53 ea033645555f osmo-sip-connector[15462]: <0004>
telnet_interface.c:104 Available via telnet 127.0.0.1 4256
Feb 17 10:43:53 ea033645555f osmo-sip-connector[15462]: <0001> mncc.c:1054
Scheduling MNCC connect
Feb 17 10:43:53 ea033645555f osmo-sip-connector[15462]: <0000> sip.c:679
su_source_port_create() returns 0x5650e33fab60
Feb 17 10:43:53 ea033645555f systemd[1]: osmo-sip-connector.service: Failed to reset
devices.list: Operation not permitted
Feb 17 10:43:53 ea033645555f osmo-sip-connector[15462]: <0001> mncc.c:946 Failed to
connect(/tmp/msc_mncc). Retrying
Feb 17 10:43:53 ea033645555f systemd[1]: Started Osmo SIP Connector.
+ systemctl --no-pager -l -n 200 status osmo-stp
● osmo-stp.service - Osmocom STP (Signal Transfer Point)
Loaded: loaded (/lib/systemd/system/osmo-stp.service; disabled; vendor preset:
enabled)
Active: active (running) since Wed 2021-02-17 10:43:53 UTC; 2s ago
Docs:
https://osmocom.org/projects/osmo-stp/wiki
Main PID: 15463 (osmo-stp)
Tasks: 1 (limit: 4915)
CGroup:
/docker/ea033645555f84c2eb962f8786c25ff0275716b9d1d8b5ae767673da0fd08527/system.slice/osmo-stp.service
└─15463 /usr/bin/osmo-stp -c /etc/osmocom/osmo-stp.cfg
Feb 17 10:43:53 ea033645555f systemd[1]: osmo-stp.service: Failed to reset devices.list:
Operation not permitted
Feb 17 10:43:53 ea033645555f systemd[1]: Started Osmocom STP (Signal Transfer Point).
Feb 17 10:43:53 ea033645555f osmo-stp[15463]: DLGLOBAL <0000> telnet_interface.c:104
Available via telnet 127.0.0.1 4239
Feb 17 10:43:53 ea033645555f osmo-stp[15463]: DLSS7 <000c> osmo_ss7.c:1495 0:
asp-asp-dyn-0: ASP Restart for server not implemented yet!
Feb 17 10:43:53 ea033645555f osmo-stp[15463]: DLSS7 <000c> osmo_ss7.c:1495 0:
asp-asp-dyn-1: ASP Restart for server not implemented yet!
Feb 17 10:43:53 ea033645555f osmo-stp[15463]: DLSS7 <000c> osmo_ss7.c:1495 0:
asp-asp-dyn-2: ASP Restart for server not implemented yet!
+ 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: activating (auto-restart) (Result: exit-code) since Wed 2021-02-17 10:43:55
UTC; 43ms ago
Process: 15471 ExecStart=/usr/bin/osmo-pcap-server -c /etc/osmocom/osmo-pcap-server.cfg
(code=exited, status=1/FAILURE)
Main PID: 15471 (code=exited, status=1/FAILURE)
Feb 17 10:43:55 ea033645555f osmo-pcap-server[15471]: <0005> socket.c:912 unable to
bind socket:127.0.0.1:4238: Address already in use
Feb 17 10:43:55 ea033645555f osmo-pcap-server[15471]: <0005> socket.c:923 no
suitable addr found for: 127.0.0.1:4238
Feb 17 10:43:55 ea033645555f osmo-pcap-server[15471]: <0005> telnet_interface.c:100
Cannot bind telnet at 127.0.0.1 4238
Feb 17 10:43:55 ea033645555f osmo-pcap-server[15471]: <0001> osmo_server_main.c:233
Failed to bind telnet interface
Feb 17 10:43:55 ea033645555f systemd[1]: Stopped PCAP Server for the PCAP aggregation.
Feb 17 10:43:55 ea033645555f osmo-pcap-server[15471]:
Feb 17 10:43:55 ea033645555f systemd[1]: osmo-pcap-server.service: Failed to reset
devices.list: Operation not permitted
Feb 17 10:43:55 ea033645555f systemd[1]: Started PCAP Server for the PCAP aggregation.
Feb 17 10:43:55 ea033645555f systemd[1]: osmo-pcap-server.service: Main process exited,
code=exited, status=1/FAILURE
Feb 17 10:43:55 ea033645555f systemd[1]: osmo-pcap-server.service: Unit entered failed
state.
Feb 17 10:43:55 ea033645555f systemd[1]: osmo-pcap-server.service: Failed with result
'exit-code'.
+ failed= osmo-pcap-server
+ 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 2021-02-17 10:43:53 UTC; 2s ago
Main PID: 15467 (osmo-sgsn)
Tasks: 1 (limit: 4915)
CGroup:
/docker/ea033645555f84c2eb962f8786c25ff0275716b9d1d8b5ae767673da0fd08527/system.slice/osmo-sgsn.service
└─15467 /usr/bin/osmo-sgsn -c /etc/osmocom/osmo-sgsn.cfg
Feb 17 10:43:53 ea033645555f systemd[1]: Started Osmocom SGSN (Serving GPRS Support
Node).
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0014> telnet_interface.c:104
Available via telnet 127.0.0.1 4245
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <001b> control_if.c:916 CTRL at
127.0.0.1 4251
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <001c> gtp.c:902 GTP: gtp_newgsn()
started at 127.0.0.1
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <001c> gtp.c:859 State information
file (.//gsn_restart) not found. Creating new file.
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0004> sgsn_libgtp.c:777 Created GTP
on 127.0.0.1
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0004> sgsn_main.c:447 libGTP
v1.6.0.16-02a8 initialized
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <001e> gsup_client.c:75 GSUP
connecting to 127.0.0.1:4222
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0021> sccp_user.c:521 OsmoSGSN:
Creating SS7 instance
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0021> sccp_user.c:548 OsmoSGSN:
Using SS7 instance 0, pc:0.23.4
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0021> sccp_user.c:555 OsmoSGSN:
Creating AS instance
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0021> sccp_user.c:566 OsmoSGSN:
Using AS instance as-clnt-OsmoSGSN
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0021> sccp_user.c:571 OsmoSGSN:
Creating default route
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0021> sccp_user.c:611 OsmoSGSN: No
unassociated ASP for m3ua, creating new ASP asp-clnt-OsmoSGSN
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0021> sccp_user.c:638 OsmoSGSN:
Using ASP instance asp-clnt-OsmoSGSN
Feb 17 10:43:53 ea033645555f osmo-sgsn[15467]: <0020> osmo_ss7.c:447 0: Creating
SCCP instance
Feb 17 10:43:54 ea033645555f osmo-sgsn[15467]: <001e> gsup_client.c:75 GSUP
connecting to 127.0.0.1:4222
Feb 17 10:43:54 ea033645555f osmo-sgsn[15467]: <0016> input/ipa.c:128 127.0.0.1:4222
connection done
Feb 17 10:43:54 ea033645555f osmo-sgsn[15467]: <0016> input/ipaccess.c:895 received
ID_GET for unit ID 0/0/0
Feb 17 10:43:55 ea033645555f osmo-sgsn[15467]: <0023> m3ua.c:622 0:
asp-asp-clnt-OsmoSGSN: Received NOTIFY Type State Change:AS Inactive ()
Feb 17 10:43:55 ea033645555f osmo-sgsn[15467]: <0020> xua_default_lm_fsm.c:354
xua_default_lm(asp-clnt-OsmoSGSN)[0x555d233c8d20]{ACTIVE}: Ignoring primitive
M-ASP_ACTIVE.confirm
Feb 17 10:43:55 ea033645555f osmo-sgsn[15467]: <0023> m3ua.c:622 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 2021-02-17 10:43:53 UTC; 2s ago
Main PID: 15466 (osmo-pcu)
Tasks: 1 (limit: 4915)
CGroup:
/docker/ea033645555f84c2eb962f8786c25ff0275716b9d1d8b5ae767673da0fd08527/system.slice/osmo-pcu.service
└─15466 /usr/bin/osmo-pcu -c /etc/osmocom/osmo-pcu.cfg
Feb 17 10:43:53 ea033645555f systemd[1]: Started Osmocom osmo-pcu.
Feb 17 10:43:53 ea033645555f osmo-pcu[15466]: <000f> telnet_interface.c:104
Available via telnet 127.0.0.1 4240
Feb 17 10:43:53 ea033645555f osmo-pcu[15466]: <0001> osmobts_sock.c:234 osmo-bts PCU
socket /tmp/pcu_bts has been connected
Feb 17 10:43:53 ea033645555f osmo-pcu[15466]: <0001> pcu_l1_if.cpp:901 Received
message for new BTS0
Feb 17 10:43:53 ea033645555f osmo-pcu[15466]: <0001> pcu_l1_if.cpp:606 BTS not
available
+ 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 2021-02-17 10:43:53 UTC; 2s ago
Main PID: 15465 (osmo-hnbgw)
Tasks: 1 (limit: 4915)
CGroup:
/docker/ea033645555f84c2eb962f8786c25ff0275716b9d1d8b5ae767673da0fd08527/system.slice/osmo-hnbgw.service
└─15465 /usr/bin/osmo-hnbgw -c /etc/osmocom/osmo-hnbgw.cfg
Feb 17 10:43:53 ea033645555f systemd[1]: Started Osmocom Home Nodeb Gateway (OsmoHNBGW).
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLGLOBAL <0004>
telnet_interface.c:104 Available via telnet 127.0.0.1 4261
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLCTRL <000b>
control_if.c:916 CTRL at 127.0.0.1 4262
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DMAIN <0000>
hnbgw_cn.c:461 IuCS remote addr not configured, using default:
RI=SSN_PC,PC=0.23.1,SSN=RANAP
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DMAIN <0000>
hnbgw_cn.c:461 IuPS remote addr not configured, using default:
RI=SSN_PC,PC=0.23.4,SSN=RANAP
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DRANAP <0003>
hnbgw_cn.c:512 No cs7 instance configured for IuCS nor IuPS, creating default instance
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:376 0: Creating SS7 Instance
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:676 0: Creating Route Table system
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DRANAP <0003>
hnbgw_cn.c:525 Local SCCP addr: RI=SSN_PC,PC=0.23.5,SSN=RANAP
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSCCP <0011>
sccp_user.c:548 OsmoHNBGW: Using SS7 instance 0, pc:0.23.5
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSCCP <0011>
sccp_user.c:555 OsmoHNBGW: Creating AS instance
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
fsm.c:461 XUA_AS(as-clnt-OsmoHNBGW)[0x564a93ec6f40]{AS_DOWN}: Allocated
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:978 0: as-as-clnt-OsmoHNBGW: Created AS
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSCCP <0011>
sccp_user.c:566 OsmoHNBGW: Using AS instance as-clnt-OsmoHNBGW
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSCCP <0011>
sccp_user.c:571 OsmoHNBGW: Creating default route
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:800 0: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as-clnt-OsmoHNBGW'
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSCCP <0011>
sccp_user.c:611 OsmoHNBGW: No unassociated ASP for m3ua, creating new ASP
asp-clnt-OsmoHNBGW
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:998 0: as-as-clnt-OsmoHNBGW: Adding ASP asp-clnt-OsmoHNBGW to AS
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
fsm.c:461 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{IDLE}: Allocated
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:1447 0: asp-asp-clnt-OsmoHNBGW: Restarting ASP asp-clnt-OsmoHNBGW,
r=localhost:2905<->l=localhost:0
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
fsm.c:461 XUA_ASP(asp-clnt-OsmoHNBGW)[0x564a93ecaf90]{ASP_DOWN}: Allocated
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSCCP <0011>
sccp_user.c:638 OsmoHNBGW: Using ASP instance asp-clnt-OsmoHNBGW
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:447 0: Creating SCCP instance
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSCCP <0011>
sccp_user.c:107 Binding user 'SCCP Maangement' to SSN=1 PC=(no PC)
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:471 registering user=SCCP for SI 3 with priv 0x564a93ecb2b0
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSCCP <0011>
sccp_user.c:107 Binding user 'OsmoHNBGW' to SSN=142 PC=0.23.5
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DRANAP <0003>
hnbgw_cn.c:550 Remote SCCP addr: IuCS: RI=SSN_PC,PC=0.23.1,SSN=RANAP
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DRANAP <0003>
hnbgw_cn.c:552 Remote SCCP addr: IuPS: RI=SSN_PC,PC=0.23.4,SSN=RANAP
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DHNBAP <0001>
hnbgw.c:624 Using RNC-Id 23
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DMAIN <0000>
hnbgw.c:629 Listening for Iuh at 0.0.0.0 29169
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLINP <0006>
stream.c:396 [CONNECTING] osmo_stream_cli_fd_cb(): connection done.
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLINP <0006>
stream.c:114 sizes of 'struct sctp_event_subscribe': compile-time 10, kernel: 13
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:1706 0: asp-asp-clnt-OsmoHNBGW: Client connected
(r=::1:2905<->l=::1:52831)
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{IDLE}:
Received primitive M-SCTP_ESTABLISH.indication
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{IDLE}:
Received Event SCTP-ESTABLISH.ind
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_default_lm_fsm.c:142 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{IDLE}:
state_chg to WAIT_ASP_UP
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_default_lm_fsm.c:143 XUA_ASP(asp-clnt-OsmoHNBGW)[0x564a93ecaf90]{ASP_DOWN}: Received
Event M-ASP_UP.req
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLINP <0006>
stream.c:417 [CONNECTED] osmo_stream_cli_fd_cb(): connected write
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLINP <0006>
stream.c:350 [CONNECTED] osmo_stream_cli_write(): sending 8 bytes of data
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLINP <0006>
stream.c:417 [CONNECTED] osmo_stream_cli_fd_cb(): connected write
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLINP <0006>
stream.c:413 [CONNECTED] osmo_stream_cli_fd_cb(): connected read
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLINP <0006>
stream.c:322 [CONNECTED] osmo_stream_cli_read(): message received
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
osmo_ss7.c:1783 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 8
(flags=0x80)
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLM3UA <0013>
m3ua.c:712 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (ASPSM:UP-ACK)
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
m3ua.c:674 XUA_ASP(asp-clnt-OsmoHNBGW)[0x564a93ecaf90]{ASP_DOWN}: Received Event
ASPSM-ASP_UP_ACK
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_asp_fsm.c:352 XUA_ASP(asp-clnt-OsmoHNBGW)[0x564a93ecaf90]{ASP_DOWN}: T(ack) stopped
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_asp_fsm.c:401 XUA_ASP(asp-clnt-OsmoHNBGW)[0x564a93ecaf90]{ASP_DOWN}: state_chg to
ASP_INACTIVE
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_asp_fsm.c:450 XUA_AS(as-clnt-OsmoHNBGW)[0x564a93ec6f40]{AS_DOWN}: Received Event
ASPAS-ASP_INACTIVE.ind
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_as_fsm.c:289 XUA_AS(as-clnt-OsmoHNBGW)[0x564a93ec6f40]{AS_DOWN}: state_chg to
AS_INACTIVE
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{WAIT_ASP_UP}:
Received primitive M-ASP_UP.confirm
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{WAIT_ASP_UP}:
Received Event ASP-UP.conf
Feb 17 10:43:53 ea033645555f osmo-hnbgw[15465]: 20210217104353234 DLSS7 <0010>
xua_default_lm_fsm.c:154 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{WAIT_ASP_UP}:
state_chg to WAIT_NOTIFY
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355105 DLINP <0006>
stream.c:413 [CONNECTED] osmo_stream_cli_fd_cb(): connected read
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355105 DLINP <0006>
stream.c:322 [CONNECTED] osmo_stream_cli_read(): message received
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355105 DLSS7 <0010>
osmo_ss7.c:1783 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 148
(flags=0x8080)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355105 DLSS7 <0010>
osmo_ss7.c:1575 0: asp-asp-clnt-OsmoHNBGW: xUA CLNT SCTP NOTIFICATION 32770 flags=0x0
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355105 DLSS7 <0010>
osmo_ss7.c:1588 0: asp-asp-clnt-OsmoHNBGW: xUA CLNT PEER_ADDR_CHANGE
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355234 DLSS7 <0010>
fsm.c:322 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{WAIT_NOTIFY}: Timeout of T1
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355234 DLSS7 <0010>
xua_default_lm_fsm.c:176 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{WAIT_NOTIFY}:
state_chg to RKM_REG
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355234 DLSS7 <0010>
xua_rkm.c:559 0: asp-asp-clnt-OsmoHNBGW: Received XUA Layer Manager Primitive:
M-RK_REG.request)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355234 DLINP <0006>
stream.c:417 [CONNECTED] osmo_stream_cli_fd_cb(): connected write
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355234 DLINP <0006>
stream.c:350 [CONNECTED] osmo_stream_cli_write(): sending 44 bytes of data
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355234 DLINP <0006>
stream.c:417 [CONNECTED] osmo_stream_cli_fd_cb(): connected write
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355234 DLINP <0006>
stream.c:413 [CONNECTED] osmo_stream_cli_fd_cb(): connected read
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355234 DLINP <0006>
stream.c:322 [CONNECTED] osmo_stream_cli_read(): message received
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355234 DLSS7 <0010>
osmo_ss7.c:1783 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 36
(flags=0x80)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLM3UA <0013>
m3ua.c:712 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (RKM:REG-RESP)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_rkm.c:435 0: asp-asp-clnt-OsmoHNBGW: Received RKM REG RES rctx=1 status=SUCCESS
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{RKM_REG}:
Received primitive M-RK_REG.confirm
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{RKM_REG}:
Received Event RKM_REG.conf
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:249 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{RKM_REG}:
state_chg to WAIT_NOTIFY
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLINP <0006>
stream.c:413 [CONNECTED] osmo_stream_cli_fd_cb(): connected read
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLINP <0006>
stream.c:322 [CONNECTED] osmo_stream_cli_read(): message received
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
osmo_ss7.c:1783 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 24
(flags=0x80)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLM3UA <0013>
m3ua.c:712 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (MGMT:NOTIFY)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLM3UA <0013>
m3ua.c:622 0: asp-asp-clnt-OsmoHNBGW: Received NOTIFY Type State Change:AS Inactive ()
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{WAIT_NOTIFY}:
Received primitive M-NOTIFY.indication
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{WAIT_NOTIFY}:
Received Event NOTIFY.ind
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:215 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{WAIT_NOTIFY}:
state_chg to ACTIVE
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:216 XUA_ASP(asp-clnt-OsmoHNBGW)[0x564a93ecaf90]{ASP_INACTIVE}:
Received Event M-ASP_ACTIVE.req
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLINP <0006>
stream.c:417 [CONNECTED] osmo_stream_cli_fd_cb(): connected write
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLINP <0006>
stream.c:350 [CONNECTED] osmo_stream_cli_write(): sending 16 bytes of data
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLINP <0006>
stream.c:417 [CONNECTED] osmo_stream_cli_fd_cb(): connected write
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLINP <0006>
stream.c:413 [CONNECTED] osmo_stream_cli_fd_cb(): connected read
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLINP <0006>
stream.c:322 [CONNECTED] osmo_stream_cli_read(): message received
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
osmo_ss7.c:1783 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 16
(flags=0x80)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLM3UA <0013>
m3ua.c:712 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (ASPTM:ACTIVE-ACK)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
m3ua.c:674 XUA_ASP(asp-clnt-OsmoHNBGW)[0x564a93ecaf90]{ASP_INACTIVE}: Received Event
ASPTM-ASP_AC_ACK
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_asp_fsm.c:352 XUA_ASP(asp-clnt-OsmoHNBGW)[0x564a93ecaf90]{ASP_INACTIVE}: T(ack)
stopped
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_asp_fsm.c:484 XUA_ASP(asp-clnt-OsmoHNBGW)[0x564a93ecaf90]{ASP_INACTIVE}: state_chg to
ASP_ACTIVE
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_asp_fsm.c:450 XUA_AS(as-clnt-OsmoHNBGW)[0x564a93ec6f40]{AS_INACTIVE}: Received Event
ASPAS-ASP_ACTIVE.ind
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_as_fsm.c:369 XUA_AS(as-clnt-OsmoHNBGW)[0x564a93ec6f40]{AS_INACTIVE}: state_chg to
AS_ACTIVE
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{ACTIVE}:
Received primitive M-ASP_ACTIVE.confirm
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:354 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{ACTIVE}:
Ignoring primitive M-ASP_ACTIVE.confirm
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLINP <0006>
stream.c:413 [CONNECTED] osmo_stream_cli_fd_cb(): connected read
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLINP <0006>
stream.c:322 [CONNECTED] osmo_stream_cli_read(): message received
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
osmo_ss7.c:1783 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 24
(flags=0x80)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLM3UA <0013>
m3ua.c:712 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (MGMT:NOTIFY)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLM3UA <0013>
m3ua.c:622 0: asp-asp-clnt-OsmoHNBGW: Received NOTIFY Type State Change:AS Active ()
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:351 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{ACTIVE}:
Received primitive M-NOTIFY.indication
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355235 DLSS7 <0010>
xua_default_lm_fsm.c:358 xua_default_lm(asp-clnt-OsmoHNBGW)[0x564a93ec7490]{ACTIVE}:
Received Event NOTIFY.ind
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DLINP <0006>
stream.c:413 [CONNECTED] osmo_stream_cli_fd_cb(): connected read
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DLINP <0006>
stream.c:322 [CONNECTED] osmo_stream_cli_read(): message received
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DLSS7 <0010>
osmo_ss7.c:1783 0: asp-asp-clnt-OsmoHNBGW: xua_cli_read_cb(): sctp_recvmsg() returned 24
(flags=0x80)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DLM3UA <0013>
m3ua.c:712 0: asp-asp-clnt-OsmoHNBGW: Received M3UA Message (SNM:DAVA)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DLM3UA <0013>
xua_snm.c:356 0: asp-asp-clnt-OsmoHNBGW: Rx DAVA() for 0.23.4/0,
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DLSCCP <0011>
sccp_user.c:175 Delivering N-PCSTATE.indication to SCCP User 'SCCP Maangement'
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DLSCCP <0011>
sccp_scmg.c:299 unsupported SCCP user primitive N-PCSTATE.indication
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DLSCCP <0011>
sccp_user.c:175 Delivering N-PCSTATE.indication to SCCP User 'OsmoHNBGW'
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DMAIN <0000>
hnbgw_cn.c:401 sccp_sap_up(N-PCSTATE.indication)
Feb 17 10:43:55 ea033645555f osmo-hnbgw[15465]: 20210217104355240 DMAIN <0000>
hnbgw_cn.c:434 Received unknown prim 2562 from SCCP USER SAP
+ 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 2021-02-17 10:43:53 UTC; 2s ago
Main PID: 15464 (osmo-bts-virtua)
Tasks: 1 (limit: 4915)
CGroup:
/docker/ea033645555f84c2eb962f8786c25ff0275716b9d1d8b5ae767673da0fd08527/system.slice/osmo-bts-virtual.service
└─15464 /usr/bin/osmo-bts-virtual -s -c /etc/osmocom/osmo-bts-virtual.cfg
Feb 17 10:43:53 ea033645555f systemd[1]: osmo-bts-virtual.service: Failed to reset
devices.list: Operation not permitted
Feb 17 10:43:53 ea033645555f systemd[1]: Started Osmocom GSM BTS for virtual Um layer
based on GSMTAP/UDP.
Feb 17 10:43:53 ea033645555f osmo-bts-virtual[15464]: ((*))
Feb 17 10:43:53 ea033645555f osmo-bts-virtual[15464]: |
Feb 17 10:43:53 ea033645555f osmo-bts-virtual[15464]: / \ OsmoBTS
Feb 17 10:43:53 ea033645555f osmo-bts-virtual[15464]: % Command 'uplink-power-target
<-110-0>' has been deprecated.
Feb 17 10:43:53 ea033645555f osmo-bts-virtual[15464]: % MS/BS Power control parameters
should be configured in osmo-bsc: use 'rxlev-thresh lower 35 upper 35'.
+ systemctl stop osmo-bsc osmo-gbproxy 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-pcap-server ]
+ set +x
ERROR: services failed to start: osmo-pcap-server
+ ret=1
+ [ -n ]
+ docker container kill debian9-repo-install-test-nightly
debian9-repo-install-test-nightly
+ exit 1
Build step 'Execute shell' marked build as failure