Project

General

Profile

Bug #3273

osmo-bts-octphy failing to start in prod setup

Added by pespin 8 days ago.

Status:
New
Priority:
Normal
Assignee:
Target version:
-
Start date:
05/16/2018
Due date:
% Done:

0%

Spec Reference:

Description

Since a few days ago, the osmo-bts-octphy tests fail during startup of the OCTBTS:

(launched: 2018-05-16_13:19:33.210299)
<0006> l1_if.c:770 model_init()
20180516131933306 DLCTRL <0017> control_if.c:866 CTRL at 127.0.0.1 4238
20180516131933306 DLGLOBAL <0010> telnet_interface.c:104 telnet at 127.0.0.1 4241
20180516131933307 DLINP <0012> input/ipaccess.c:891 enabling ipaccess BTS mode, OML connecting to 10.42.42.7:3002
20180516131933307 DABIS <000d> abis.c:229 Input Signal 4 received
20180516131933307 DL1C <0006> phy_link.c:58 PHY link state change shutdown -> connecting
20180516131933307 DL1C <0006> l1_if.c:1740 Opening L1 interface for OctPHY (enp2s0)
20180516131933321 DLINP <0012> input/ipa.c:131 10.42.42.7:3002 connection done
20180516131933321 DLINP <0012> input/ipaccess.c:712 received ID get from 8/0/0
20180516131933322 DABIS <000d> abis.c:107 OML Signalling link up
20180516131933322 DOML <0001> oml.c:313 OC=SITE-MANAGER INST=(ff,ff,ff) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=BTS INST=(00,ff,ff) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=GPRS-NSE INST=(00,ff,ff) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=GPRS-CELL INST=(00,ff,ff) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=GPRS-NSVC INST=(00,00,ff) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=GPRS-NSVC INST=(00,01,ff) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=RADIO-CARRIER INST=(00,00,ff) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,00) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,01) Tx STATE CHG REP
20180516131933322 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,02) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,03) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,04) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,05) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,06) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,07) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:348 OC=RADIO-CARRIER INST=(00,00,ff) AVAIL STATE Power off -> OK
20180516131933323 DOML <0001> oml.c:355 OC=RADIO-CARRIER INST=(00,00,ff) OPER STATE NULL -> Disabled
20180516131933323 DOML <0001> oml.c:313 OC=RADIO-CARRIER INST=(00,00,ff) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:467 OC=RADIO-CARRIER INST=(00,00,ff) Tx SW ACT REP
20180516131933323 DOML <0001> oml.c:348 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) AVAIL STATE Power off -> OK
20180516131933323 DOML <0001> oml.c:313 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:467 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) Tx SW ACT REP
20180516131933323 DOML <0001> oml.c:348 OC=CHANNEL INST=(00,00,00) AVAIL STATE Power off -> Dependency
20180516131933323 DOML <0001> oml.c:355 OC=CHANNEL INST=(00,00,00) OPER STATE NULL -> Disabled
20180516131933323 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,00) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:348 OC=CHANNEL INST=(00,00,01) AVAIL STATE Power off -> Dependency
20180516131933323 DOML <0001> oml.c:355 OC=CHANNEL INST=(00,00,01) OPER STATE NULL -> Disabled
20180516131933323 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,01) Tx STATE CHG REP
20180516131933323 DOML <0001> oml.c:348 OC=CHANNEL INST=(00,00,02) AVAIL STATE Power off -> Dependency
20180516131933324 DOML <0001> oml.c:355 OC=CHANNEL INST=(00,00,02) OPER STATE NULL -> Disabled
20180516131933324 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,02) Tx STATE CHG REP
20180516131933324 DOML <0001> oml.c:348 OC=CHANNEL INST=(00,00,03) AVAIL STATE Power off -> Dependency
20180516131933324 DOML <0001> oml.c:355 OC=CHANNEL INST=(00,00,03) OPER STATE NULL -> Disabled
20180516131933324 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,03) Tx STATE CHG REP
20180516131933324 DOML <0001> oml.c:348 OC=CHANNEL INST=(00,00,04) AVAIL STATE Power off -> Dependency
20180516131933324 DOML <0001> oml.c:355 OC=CHANNEL INST=(00,00,04) OPER STATE NULL -> Disabled
20180516131933324 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,04) Tx STATE CHG REP
20180516131933324 DOML <0001> oml.c:348 OC=CHANNEL INST=(00,00,05) AVAIL STATE Power off -> Dependency
20180516131933324 DOML <0001> oml.c:355 OC=CHANNEL INST=(00,00,05) OPER STATE NULL -> Disabled
20180516131933324 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,05) Tx STATE CHG REP
20180516131933324 DOML <0001> oml.c:348 OC=CHANNEL INST=(00,00,06) AVAIL STATE Power off -> Dependency
20180516131933324 DOML <0001> oml.c:355 OC=CHANNEL INST=(00,00,06) OPER STATE NULL -> Disabled
20180516131933324 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,06) Tx STATE CHG REP
20180516131933324 DOML <0001> oml.c:348 OC=CHANNEL INST=(00,00,07) AVAIL STATE Power off -> Dependency
20180516131933324 DOML <0001> oml.c:355 OC=CHANNEL INST=(00,00,07) OPER STATE NULL -> Disabled
20180516131933324 DOML <0001> oml.c:313 OC=CHANNEL INST=(00,00,07) Tx STATE CHG REP
20180516131933325 DOML <0001> oml.c:548 OC=BTS(01) INST=(ff,ff,ff) Rx GET ATTR
20180516131933325 DOML <0001> oml.c:277 BTS Tx Get Attribute Response
20180516131933380 DOML <0001> oml.c:548 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) Rx GET ATTR
20180516131933380 DOML <0001> oml.c:277 BASEBAND-TRANSCEIVER Tx Get Attribute Response
20180516131933380 DOML <0001> oml.c:229 O&M Get Attributes [0], Manufacturer Dependent State is unsupported by TRX.
20180516131933382 DOML <0001> oml.c:1001 OC=SITE-MANAGER(00) INST=(ff,ff,ff) Rx OPSTART
20180516131933382 DOML <0001> oml.c:1012 ... automatic ACK, OP state already was Enabled
20180516131933383 DOML <0001> oml.c:580 OC=BTS(01) INST=(00,ff,ff) Rx SET BTS ATTR
20180516131933383 DOML <0001> oml.c:681 Ignoring T200[0] (150 ms) as sent by BSC due to suspected LAPDm bug!
20180516131933383 DOML <0001> oml.c:681 Ignoring T200[1] (180 ms) as sent by BSC due to suspected LAPDm bug!
20180516131933383 DOML <0001> oml.c:681 Ignoring T200[2] (180 ms) as sent by BSC due to suspected LAPDm bug!
20180516131933383 DOML <0001> oml.c:681 Ignoring T200[3] (1680 ms) as sent by BSC due to suspected LAPDm bug!
20180516131933383 DOML <0001> oml.c:681 Ignoring T200[4] (520 ms) as sent by BSC due to suspected LAPDm bug!
20180516131933384 DOML <0001> oml.c:681 Ignoring T200[5] (165 ms) as sent by BSC due to suspected LAPDm bug!
20180516131933384 DOML <0001> oml.c:681 Ignoring T200[6] (1680 ms) as sent by BSC due to suspected LAPDm bug!
20180516131933384 DOML <0001> oml.c:451 Sending FOM ACK.
20180516131933385 DOML <0001> oml.c:1029 OC=BTS(01) INST=(00,ff,ff) Rx CHG ADM STATE
20180516131933385 DOML <0001> oml.c:313 OC=BTS INST=(00,ff,ff) Tx STATE CHG REP
20180516131933386 DOML <0001> oml.c:1001 OC=BTS(01) INST=(00,ff,ff) Rx OPSTART
20180516131933386 DOML <0001> oml.c:355 OC=BTS INST=(00,ff,ff) OPER STATE NULL -> Enabled
20180516131933386 DOML <0001> oml.c:313 OC=BTS INST=(00,ff,ff) Tx STATE CHG REP
20180516131933430 DOML <0001> oml.c:1001 OC=RADIO-CARRIER(02) INST=(00,00,ff) Rx OPSTART
20180516131933432 DOML <0001> oml.c:750 OC=RADIO-CARRIER(02) INST=(00,00,ff) Rx SET RADIO CARRIER ATTR
20180516131933432 DOML <0001> oml.c:782 Set RF Max Power Reduction = 0 dBm
20180516131933432 DL1C <0006> tx_power.c:248 power_ramp_start(cur=0, tgt=0)
20180516131933432 DOML <0001> oml.c:451 Sending FOM ACK.
20180516131933433 DOML <0001> oml.c:1029 OC=RADIO-CARRIER(02) INST=(00,00,ff) Rx CHG ADM STATE
20180516131933434 DOML <0001> oml.c:1055 ADM state already was Unlocked
20180516131933434 DOML <0001> oml.c:313 OC=RADIO-CARRIER INST=(00,00,ff) Tx STATE CHG REP
20180516131933435 DOML <0001> oml.c:1001 OC=RADIO-CARRIER(02) INST=(00,00,ff) Rx OPSTART
20180516131933435 DL1C <0006> l1_oml.c:1228 Tx APP-INFO.req
20180516131933435 DL1C <0006> l1_oml.c:1159 Tx APP-INFO-SYSTEM.req
20180516131933436 DL1C <0006> l1_oml.c:1364 Tx TRX-OPEN.req(trx=0, rf_port=0, arfcn=868, center=868, tsc=7, rx_gain=70, tx_atten=0)
20180516131934384 DLINP <0012> e1_input.c:235 abis_sendmsg: msg->dst == NULL: 0c 12 01 90 0f ff ff 
20180516131934433 DL1C <0006> tx_power.c:181 ramping TRX board output power to 0 mdBm.
20180516131935385 DLINP <0012> e1_input.c:235 abis_sendmsg: msg->dst == NULL: 0c 12 01 90 0f ff ff 
20180516131936385 DLINP <0012> e1_input.c:235 abis_sendmsg: msg->dst == NULL: 0c 12 01 90 0f ff ff 
20180516131937386 DLINP <0012> e1_input.c:235 abis_sendmsg: msg->dst == NULL: 0c 12 01 90 0f ff ff 
20180516131938321 DL1C <0006> l1_if.c:203 Timeout waiting for L1 primitive TRX-CLOSE-ALL

Attach a run.tar.gz from osmo-gsm-tester showing the issue.

trial-1304-run.tgz (12.1 MB) pespin, 05/16/2018 02:28 PM

Also available in: Atom PDF