Bug #1770
closedosmo-bts-trx goes through OML initialization twice
100%
Description
Currently, osmo-bts-trx goes through a most curious initialization sequence:
- The RADIO-CARRIER and then all CHANNEL OML objects are enabled.
- When all seems done, they're Disabled again.
- And finally, all is Enabled again.
This is the situation in detail:
Note, osmo-bts-trx so far has a hackish implementation of OML. It
simply sends the ->Enabled state changes directly upon receiving an OPSTART.
BTS BSC |<-----| SITE_MANAGER OPSTART n |----->| BTS state change: OPSTATE_DISABLED, AVSTATE_DEPENDENCY o | | This signals to nm_statechg_event() in bts_ipaccess_nanobts.c r | | to (a) Set BTS Attributes and (b) send BTS OPSTART m |<-----| Set BTS Attributes (a) a | | When osmo-bts-trx receives a Set BTS Attributes, it sends l |----->| CHANNEL state change: OPSTATE_DISABLED x8 | | This signals the BSC to Set CHANNEL Attributes and OPSTART i |<-----| Set CHANNEL Attributes x8 n |<-----| CHANNEL OPSTART x8 i |----->| CHANNEL state change: OPSTATE_ENABLED, AVSTATE_OK x8 t | | |<-----| BTS OPSTART (b) | | osmo-bts-trx immediately replies with: |----->| BTS state change: OPSTATE_ENABLED, AVSTATE_DEPENDENCY | | Unfortunately, availability is left at DEPENDENCY, | | and the NM_OC_BTS case in nm_statechg_event() only | | checks for availability, not for the opstate. | | Hence nm_statechg_event() again feels inclined to | | to (a) Set BTS Attributes and (b) send BTS OPSTART, | | --+------+----- This is where the second round starts | | s |<-----| Set BTS Attributes (a) e | | When osmo-bts-trx receives a Set BTS Attributes, it sends c |----->| CHANNEL state change: OPSTATE_DISABLED x8 o | | All channels are disabled again, and then re-launched: n |<-----| Set CHANNEL Attributes x8 d |<-----| CHANNEL OPSTART x8 |----->| CHANNEL state change: OPSTATE_ENABLED, AVSTATE_OK x8 | | i |<-----| BTS OPSTART (b) n | | osmo-bts-trx again sets the OPSTATE_ENABLED, but since i | | this time it was already enabled, no further state change t | | is sent back to the BSC.
This nightmare pivots on two hinges:
1. osmo-bts-trx fails to send BTS availability as AVSTATE_OK for OPSTATE_ENABLED.
2. nm_statechg_event() in openbsc fails to heed the OPSTATE_ENABLED of the BTS state
change and only checks the AVSTATE_DEPENDENCY.
Since I am already suggesting a fix (send AVSTATE_OK with OPSTATE_ENABLED), this issue
serves mostly as a point of reference to link to.
Here is some log output:
BTS
I have added a log output to check_transceiver_availability_trx(), the function
that sends the CHANNEL OPSTATE_DISABLED after Set BTS Attributes is received
(the function is a bit of a misnomer).
[[[ ((*)) | / \ OsmoBTS 20160708003226518 DLINP <0012> input/ipaccess.c:876 enabling ipaccess BTS mode 20160708003226519 DL1C <0006> scheduler.c:144 Init scheduler for trx=0 20160708003226519 DTRX <000b> trx_if.c:560 Open transceiver for phy0.0 20160708003226519 DLINP <0012> input/ipa.c:129 connection done. 20160708003226519 DLINP <0012> input/ipaccess.c:700 received ID get 20160708003226519 DOML <0001> oml.c:217 OC=SITE-MANAGER INST=(ff,ff,ff) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=BTS INST=(00,ff,ff) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=GPRS-NSE INST=(00,ff,ff) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=GPRS-CELL INST=(00,ff,ff) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=GPRS-NSVC INST=(00,00,ff) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=GPRS-NSVC INST=(00,01,ff) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=RADIO-CARRIER INST=(00,00,ff) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,00) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,01) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,02) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,03) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,04) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,05) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,06) Tx STATE CHG REP 20160708003226519 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,07) Tx STATE CHG REP 20160708003226519 DL1C <0006> scheduler_trx.c:1433 GSM clock skew: old fn=0, new fn=1987827 20160708003226558 DOML <0001> oml.c:547 Ignoring T200[0] (150 ms) as sent by BSC due to suspected LAPDm bug! 20160708003226558 DOML <0001> oml.c:547 Ignoring T200[1] (180 ms) as sent by BSC due to suspected LAPDm bug! 20160708003226558 DOML <0001> oml.c:547 Ignoring T200[2] (180 ms) as sent by BSC due to suspected LAPDm bug! 20160708003226558 DOML <0001> oml.c:547 Ignoring T200[3] (1680 ms) as sent by BSC due to suspected LAPDm bug! 20160708003226558 DOML <0001> oml.c:547 Ignoring T200[4] (520 ms) as sent by BSC due to suspected LAPDm bug! 20160708003226558 DOML <0001> oml.c:547 Ignoring T200[5] (165 ms) as sent by BSC due to suspected LAPDm bug! 20160708003226558 DOML <0001> oml.c:547 Ignoring T200[6] (1680 ms) as sent by BSC due to suspected LAPDm bug! 20160708003226558 DL1C <0006> l1_if.c:107 check_transceiver_availability_trx() avail==1 ^ first avail==1 Now follows the expected first state change of all CHANNELs to Disabled. 20160708003226558 DOML <0001> oml.c:249 OC=RADIO-CARRIER INST=(00,00,ff) AVAIL STATE Power off -> OK 20160708003226558 DOML <0001> oml.c:256 OC=RADIO-CARRIER INST=(00,00,ff) OPER STATE NULL -> Disabled 20160708003226558 DOML <0001> oml.c:217 OC=RADIO-CARRIER INST=(00,00,ff) Tx STATE CHG REP 20160708003226558 DOML <0001> oml.c:249 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) AVAIL STATE Power off -> OK 20160708003226558 DOML <0001> oml.c:217 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) Tx STATE CHG REP 20160708003226558 DOML <0001> oml.c:362 OC=RADIO-CARRIER INST=(00,00,ff) Tx SW ACT REP 20160708003226558 DOML <0001> oml.c:362 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) Tx SW ACT REP 20160708003226558 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,00) AVAIL STATE Power off -> Dependency 20160708003226558 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,00) OPER STATE NULL -> Disabled 20160708003226558 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,00) Tx STATE CHG REP 20160708003226558 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,01) AVAIL STATE Power off -> Dependency 20160708003226558 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,01) OPER STATE NULL -> Disabled 20160708003226558 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,01) Tx STATE CHG REP 20160708003226558 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,02) AVAIL STATE Power off -> Dependency 20160708003226558 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,02) OPER STATE NULL -> Disabled 20160708003226558 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,02) Tx STATE CHG REP 20160708003226558 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,03) AVAIL STATE Power off -> Dependency 20160708003226558 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,03) OPER STATE NULL -> Disabled 20160708003226558 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,03) Tx STATE CHG REP 20160708003226558 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,04) AVAIL STATE Power off -> Dependency 20160708003226558 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,04) OPER STATE NULL -> Disabled 20160708003226558 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,04) Tx STATE CHG REP 20160708003226558 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,05) AVAIL STATE Power off -> Dependency 20160708003226558 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,05) OPER STATE NULL -> Disabled 20160708003226558 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,05) Tx STATE CHG REP 20160708003226558 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,06) AVAIL STATE Power off -> Dependency 20160708003226558 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,06) OPER STATE NULL -> Disabled 20160708003226558 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,06) Tx STATE CHG REP 20160708003226558 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,07) AVAIL STATE Power off -> Dependency 20160708003226558 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,07) OPER STATE NULL -> Disabled 20160708003226558 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,07) Tx STATE CHG REP 20160708003226598 DOML <0001> oml.c:256 OC=BTS INST=(00,ff,ff) OPER STATE NULL -> Enabled 20160708003226598 DOML <0001> oml.c:217 OC=BTS INST=(00,ff,ff) Tx STATE CHG REP ^ BTS state changes to ENABLED, but it is still in Availability = DEPENDENCY --> the BSC already queues the next Set BTS Attributes message. First some more Enabling flushes through. 20160708003226638 DOML <0001> oml.c:256 OC=GPRS-NSE INST=(00,ff,ff) OPER STATE NULL -> Enabled 20160708003226638 DOML <0001> oml.c:217 OC=GPRS-NSE INST=(00,ff,ff) Tx STATE CHG REP 20160708003226678 DOML <0001> oml.c:256 OC=GPRS-CELL INST=(00,ff,ff) OPER STATE NULL -> Enabled 20160708003226678 DOML <0001> oml.c:217 OC=GPRS-CELL INST=(00,ff,ff) Tx STATE CHG REP 20160708003226718 DOML <0001> oml.c:256 OC=GPRS-NSVC INST=(00,00,ff) OPER STATE NULL -> Enabled 20160708003226718 DOML <0001> oml.c:217 OC=GPRS-NSVC INST=(00,00,ff) Tx STATE CHG REP 20160708003226758 DOML <0001> oml.c:249 OC=RADIO-CARRIER INST=(00,00,ff) AVAIL STATE OK -> OK 20160708003226758 DOML <0001> oml.c:256 OC=RADIO-CARRIER INST=(00,00,ff) OPER STATE Disabled -> Enabled 20160708003226758 DOML <0001> oml.c:217 OC=RADIO-CARRIER INST=(00,00,ff) Tx STATE CHG REP 20160708003226798 DOML <0001> oml.c:642 Set RF Max Power Reduction = 18 dBm 20160708003226798 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003226798 DOML <0001> oml.c:256 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) OPER STATE NULL -> Enabled 20160708003226798 DOML <0001> oml.c:217 OC=BASEBAND-TRANSCEIVER INST=(00,00,ff) Tx STATE CHG REP 20160708003226838 DOML <0001> oml.c:1141 Rx IPA RSL CONNECT IP=127.0.0.42 PORT=3003 STREAM=0x00 20160708003226838 DLINP <0012> input/ipa.c:129 connection done. 20160708003226838 DLINP <0012> input/ipaccess.c:700 received ID get 20160708003226838 DRSL <0000> rsl.c:284 Tx RSL RF RESource INDication 20160708003226838 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,00) SET CHAN ATTR (TSC = 7) 20160708003226838 DL1C <0006> scheduler.c:1275 Configuring multiframe with BCCH+CCCH+SDCCH/4+SACCH/4 trx=0 ts=0 mf[2] 20160708003226838 DL1C <0006> scheduler_trx.c:1433 GSM clock skew: old fn=1987887, new fn=1987827 20160708003226839 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,00) AVAIL STATE Dependency -> OK 20160708003226839 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,00) OPER STATE Disabled -> Enabled 20160708003226839 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,00) Tx STATE CHG REP 20160708003226878 DRSL <0000> rsl.c:2058 (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO 20160708003226878 DRSL <0000> rsl.c:334 Rx RSL BCCH INFO (SI1) 20160708003226878 DPAG <0005> paging.c:545 Paging: BS_AG_BLKS_RES = 0 != 1 not fully supported 20160708003226878 DPAG <0005> paging.c:523 Paging SI update 20160708003226878 DRSL <0000> rsl.c:2058 (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO 20160708003226878 DRSL <0000> rsl.c:334 Rx RSL BCCH INFO (SI2) 20160708003226878 DPAG <0005> paging.c:545 Paging: BS_AG_BLKS_RES = 0 != 1 not fully supported 20160708003226878 DPAG <0005> paging.c:523 Paging SI update 20160708003226878 DRSL <0000> rsl.c:2058 (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO 20160708003226878 DRSL <0000> rsl.c:334 Rx RSL BCCH INFO (SI3) 20160708003226878 DPAG <0005> paging.c:523 Paging SI update 20160708003226878 DRSL <0000> bts.c:365 Updated AGCH max queue length to 12 20160708003226878 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003226878 DRSL <0000> rsl.c:2058 (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO 20160708003226878 DRSL <0000> rsl.c:334 Rx RSL BCCH INFO (SI4) 20160708003226878 DPAG <0005> paging.c:523 Paging SI update 20160708003226878 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003226878 DRSL <0000> rsl.c:2058 (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO 20160708003226878 DRSL <0000> rsl.c:334 Rx RSL BCCH INFO (SI13) 20160708003226878 DPAG <0005> paging.c:523 Paging SI update 20160708003226878 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003226878 DRSL <0000> rsl.c:497 Rx RSL SACCH FILLING (SI5) 20160708003226878 DPAG <0005> paging.c:523 Paging SI update 20160708003226878 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003226878 DRSL <0000> rsl.c:497 Rx RSL SACCH FILLING (SI6) 20160708003226878 DPAG <0005> paging.c:523 Paging SI update 20160708003226878 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003226878 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,01) SET CHAN ATTR (TSC = 7) 20160708003226878 DL1C <0006> scheduler.c:1275 Configuring multiframe with SDCCH/8+SACCH/8 trx=0 ts=1 mf[3] 20160708003226878 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,01) AVAIL STATE Dependency -> OK 20160708003226878 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,01) OPER STATE Disabled -> Enabled 20160708003226878 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003226878 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,01) Tx STATE CHG REP 20160708003226918 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,02) SET CHAN ATTR (TSC = 7) 20160708003226918 DL1C <0006> scheduler.c:1275 Configuring multiframe with TCH/F+SACCH trx=0 ts=2 mf[6] 20160708003226918 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,02) AVAIL STATE Dependency -> OK 20160708003226918 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,02) OPER STATE Disabled -> Enabled 20160708003226918 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003226918 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,02) Tx STATE CHG REP 20160708003226958 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,03) SET CHAN ATTR (TSC = 7) 20160708003226958 DL1C <0006> scheduler.c:1275 Configuring multiframe with TCH/F+SACCH trx=0 ts=3 mf[7] 20160708003226958 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,03) AVAIL STATE Dependency -> OK 20160708003226958 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,03) OPER STATE Disabled -> Enabled 20160708003226958 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003226958 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,03) Tx STATE CHG REP 20160708003226998 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,04) SET CHAN ATTR (TSC = 7) 20160708003226998 DL1C <0006> scheduler.c:1275 Configuring multiframe with TCH/F+SACCH trx=0 ts=4 mf[8] 20160708003226998 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,04) AVAIL STATE Dependency -> OK 20160708003226998 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,04) OPER STATE Disabled -> Enabled 20160708003226998 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003226998 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,04) Tx STATE CHG REP 20160708003227038 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,05) SET CHAN ATTR (TSC = 7) 20160708003227038 DL1C <0006> scheduler.c:1275 Configuring multiframe with PDCH trx=0 ts=5 mf[16] 20160708003227038 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,05) AVAIL STATE Dependency -> OK 20160708003227038 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,05) OPER STATE Disabled -> Enabled 20160708003227038 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227038 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,05) Tx STATE CHG REP 20160708003227078 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,06) SET CHAN ATTR (TSC = 7) 20160708003227078 DL1C <0006> scheduler.c:1275 Configuring multiframe with PDCH trx=0 ts=6 mf[16] 20160708003227078 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,06) AVAIL STATE Dependency -> OK 20160708003227078 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,06) OPER STATE Disabled -> Enabled 20160708003227078 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227078 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,06) Tx STATE CHG REP 20160708003227118 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,07) SET CHAN ATTR (TSC = 7) 20160708003227118 DL1C <0006> scheduler.c:1275 Configuring multiframe with PDCH trx=0 ts=7 mf[16] 20160708003227118 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,07) AVAIL STATE Dependency -> OK 20160708003227118 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,07) OPER STATE Disabled -> Enabled 20160708003227118 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227118 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,07) Tx STATE CHG REP 20160708003227158 DOML <0001> oml.c:547 Ignoring T200[0] (150 ms) as sent by BSC due to suspected LAPDm bug! 20160708003227158 DOML <0001> oml.c:547 Ignoring T200[1] (180 ms) as sent by BSC due to suspected LAPDm bug! 20160708003227158 DOML <0001> oml.c:547 Ignoring T200[2] (180 ms) as sent by BSC due to suspected LAPDm bug! 20160708003227158 DOML <0001> oml.c:547 Ignoring T200[3] (1680 ms) as sent by BSC due to suspected LAPDm bug! 20160708003227158 DOML <0001> oml.c:547 Ignoring T200[4] (520 ms) as sent by BSC due to suspected LAPDm bug! 20160708003227158 DOML <0001> oml.c:547 Ignoring T200[5] (165 ms) as sent by BSC due to suspected LAPDm bug! 20160708003227158 DOML <0001> oml.c:547 Ignoring T200[6] (1680 ms) as sent by BSC due to suspected LAPDm bug! 20160708003227158 DL1C <0006> l1_if.c:107 check_transceiver_availability_trx() avail==1 ^ We've just received the second Set BTS Attributes message and are sending another Disabled cascade, causing a new initialization from the BSC: 20160708003227158 DOML <0001> oml.c:249 OC=RADIO-CARRIER INST=(00,00,ff) AVAIL STATE OK -> OK 20160708003227158 DOML <0001> oml.c:256 OC=RADIO-CARRIER INST=(00,00,ff) OPER STATE Enabled -> Disabled 20160708003227158 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227158 DOML <0001> oml.c:217 OC=RADIO-CARRIER INST=(00,00,ff) Tx STATE CHG REP 20160708003227158 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,00) AVAIL STATE OK -> Dependency 20160708003227158 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,00) OPER STATE Enabled -> Disabled 20160708003227158 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227158 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,00) Tx STATE CHG REP 20160708003227158 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,01) AVAIL STATE OK -> Dependency 20160708003227158 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,01) OPER STATE Enabled -> Disabled 20160708003227158 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227158 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,01) Tx STATE CHG REP 20160708003227158 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,02) AVAIL STATE OK -> Dependency 20160708003227158 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,02) OPER STATE Enabled -> Disabled 20160708003227158 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227158 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,02) Tx STATE CHG REP 20160708003227158 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,03) AVAIL STATE OK -> Dependency 20160708003227158 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,03) OPER STATE Enabled -> Disabled 20160708003227158 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227158 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,03) Tx STATE CHG REP 20160708003227158 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,04) AVAIL STATE OK -> Dependency 20160708003227158 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,04) OPER STATE Enabled -> Disabled 20160708003227158 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227158 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,04) Tx STATE CHG REP 20160708003227158 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,05) AVAIL STATE OK -> Dependency 20160708003227158 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,05) OPER STATE Enabled -> Disabled 20160708003227158 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227158 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,05) Tx STATE CHG REP 20160708003227158 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,06) AVAIL STATE OK -> Dependency 20160708003227158 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,06) OPER STATE Enabled -> Disabled 20160708003227158 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227158 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,06) Tx STATE CHG REP 20160708003227158 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,07) AVAIL STATE OK -> Dependency 20160708003227158 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,07) OPER STATE Enabled -> Disabled ^ All channels Disabled again 20160708003227158 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227158 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,07) Tx STATE CHG REP 20160708003227198 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227198 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227199 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227199 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227199 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227199 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227199 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227199 DOML <0001> oml.c:249 OC=RADIO-CARRIER INST=(00,00,ff) AVAIL STATE OK -> OK 20160708003227199 DOML <0001> oml.c:256 OC=RADIO-CARRIER INST=(00,00,ff) OPER STATE Disabled -> Enabled ^ Rx another OPSTART from BSC, and now everything gets Enabled again: 20160708003227199 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227199 DOML <0001> oml.c:217 OC=RADIO-CARRIER INST=(00,00,ff) Tx STATE CHG REP 20160708003227238 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,00) SET CHAN ATTR (TSC = 7) 20160708003227238 DL1C <0006> scheduler.c:1275 Configuring multiframe with BCCH+CCCH+SDCCH/4+SACCH/4 trx=0 ts=0 mf[2] 20160708003227238 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227238 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,00) AVAIL STATE Dependency -> OK 20160708003227238 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,00) OPER STATE Disabled -> Enabled 20160708003227238 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227238 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,00) Tx STATE CHG REP 20160708003227278 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,01) SET CHAN ATTR (TSC = 7) 20160708003227278 DL1C <0006> scheduler.c:1275 Configuring multiframe with SDCCH/8+SACCH/8 trx=0 ts=1 mf[3] 20160708003227278 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227278 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,01) AVAIL STATE Dependency -> OK 20160708003227278 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,01) OPER STATE Disabled -> Enabled 20160708003227278 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227278 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,01) Tx STATE CHG REP 20160708003227279 DL1C <0006> scheduler_trx.c:1433 GSM clock skew: old fn=1987922, new fn=1987827 20160708003227318 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,02) SET CHAN ATTR (TSC = 7) 20160708003227318 DL1C <0006> scheduler.c:1275 Configuring multiframe with TCH/F+SACCH trx=0 ts=2 mf[6] 20160708003227318 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227318 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,02) AVAIL STATE Dependency -> OK 20160708003227318 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,02) OPER STATE Disabled -> Enabled 20160708003227318 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227318 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,02) Tx STATE CHG REP 20160708003227358 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,03) SET CHAN ATTR (TSC = 7) 20160708003227358 DL1C <0006> scheduler.c:1275 Configuring multiframe with TCH/F+SACCH trx=0 ts=3 mf[7] 20160708003227358 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227358 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,03) AVAIL STATE Dependency -> OK 20160708003227358 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,03) OPER STATE Disabled -> Enabled 20160708003227358 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227358 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,03) Tx STATE CHG REP 20160708003227398 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,04) SET CHAN ATTR (TSC = 7) 20160708003227398 DL1C <0006> scheduler.c:1275 Configuring multiframe with TCH/F+SACCH trx=0 ts=4 mf[8] 20160708003227398 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227398 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,04) AVAIL STATE Dependency -> OK 20160708003227398 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,04) OPER STATE Disabled -> Enabled 20160708003227398 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227398 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,04) Tx STATE CHG REP 20160708003227438 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,05) SET CHAN ATTR (TSC = 7) 20160708003227438 DL1C <0006> scheduler.c:1275 Configuring multiframe with PDCH trx=0 ts=5 mf[16] 20160708003227438 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227438 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,05) AVAIL STATE Dependency -> OK 20160708003227438 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,05) OPER STATE Disabled -> Enabled 20160708003227438 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227438 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,05) Tx STATE CHG REP 20160708003227478 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,06) SET CHAN ATTR (TSC = 7) 20160708003227478 DL1C <0006> scheduler.c:1275 Configuring multiframe with PDCH trx=0 ts=6 mf[16] 20160708003227478 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227478 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,06) AVAIL STATE Dependency -> OK 20160708003227478 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,06) OPER STATE Disabled -> Enabled 20160708003227478 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227478 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,06) Tx STATE CHG REP 20160708003227518 DOML <0001> oml.c:817 OC=CHANNEL INST=(00,00,07) SET CHAN ATTR (TSC = 7) 20160708003227518 DL1C <0006> scheduler.c:1275 Configuring multiframe with PDCH trx=0 ts=7 mf[16] 20160708003227518 DOML <0001> oml.c:884 ADM state already was Unlocked 20160708003227518 DOML <0001> oml.c:249 OC=CHANNEL INST=(00,00,07) AVAIL STATE Dependency -> OK 20160708003227518 DOML <0001> oml.c:256 OC=CHANNEL INST=(00,00,07) OPER STATE Disabled -> Enabled 20160708003227518 DPCU <0009> pcu_sock.c:627 PCU socket not connected, dropping message 20160708003227518 DOML <0001> oml.c:217 OC=CHANNEL INST=(00,00,07) Tx STATE CHG REP 20160708003227626 DL1C <0006> scheduler_trx.c:1433 GSM clock skew: old fn=1987902, new fn=1987827 20160708003228422 DL1C <0006> scheduler.c:240 Prim for trx=0 ts=0 at fn=1987945 is out of range, or channel already disabled. If this happens in conjunction with PCU, increase 'rts-advance' by 5. (current fn=1987982) 20160708003229100 DL1C <0006> scheduler_trx.c:722 Received Access Burst on RACH fn=1988105 toa=4.37 20160708003229100 DL1C <0006> scheduler_trx.c:728 Received bad AB frame at fn=1988105 (23/51) 20160708003229499 DPCU <0009> pcu_sock.c:824 PCU socket connected to external PCU 20160708003229499 DL1C <0006> scheduler.c:1315 Activating PDTCH on trx=0 ts=5 20160708003229499 DL1C <0006> scheduler.c:1315 Activating PTCCH on trx=0 ts=5 20160708003229499 DL1C <0006> l1_if.c:596 (bts=0,trx=0,ts=5,ss=0) lchan_activate() pchan=PDCH as_pchan=mf[16] success and returns 20160708003229499 DRSL <0000> rsl.c:579 (bts=0,trx=0,ts=5,ss=0) not sending CHAN ACT ACK 20160708003229499 DL1C <0006> scheduler.c:1315 Activating PDTCH on trx=0 ts=6 20160708003229499 DL1C <0006> scheduler.c:1315 Activating PTCCH on trx=0 ts=6 20160708003229499 DL1C <0006> l1_if.c:596 (bts=0,trx=0,ts=6,ss=0) lchan_activate() pchan=PDCH as_pchan=mf[16] success and returns 20160708003229499 DRSL <0000> rsl.c:579 (bts=0,trx=0,ts=6,ss=0) not sending CHAN ACT ACK 20160708003229499 DL1C <0006> scheduler.c:1315 Activating PDTCH on trx=0 ts=7 20160708003229499 DL1C <0006> scheduler.c:1315 Activating PTCCH on trx=0 ts=7 20160708003229499 DL1C <0006> l1_if.c:596 (bts=0,trx=0,ts=7,ss=0) lchan_activate() pchan=PDCH as_pchan=mf[16] success and returns 20160708003229499 DRSL <0000> rsl.c:579 (bts=0,trx=0,ts=7,ss=0) not sending CHAN ACT ACK 20160708003246511 DRSL <0000> rsl.c:2031 (bts=0,trx=0,ts=0,ss=4) Fwd RLL msg CHAN_RQD from LAPDm to A-bis 20160708003246512 DRSL <0000> rsl.c:2112 (bts=0,trx=0,ts=2,ss=0) Rx RSL CHAN_ACTIV 20160708003246512 DRSL <0000> rsl.c:880 chan_nr=0x0a type=0x00 mode=0x00 20160708003246512 DL1C <0006> l1_if.c:558 lchan_activate chan_nr=10 ts->nr=2 20160708003246512 DL1C <0006> l1_if.c:563 (bts=0,trx=0,ts=2,ss=0) lchan_activate() pchan=TCH/F as_pchan=mf[6] 20160708003246512 DL1C <0006> l1_if.c:565 lchan_activate l1t=0x1e30910 tn=2 l1ts 0x1e34008 mf_index=6 20160708003246512 DL1C <0006> scheduler.c:1315 Activating TCH/F on trx=0 ts=2 20160708003246512 DL1C <0006> scheduler.c:1315 Activating SACCH/TF on trx=0 ts=2 20160708003246512 DL1C <0006> scheduler.c:1364 Set mode 3, 0, handover 0 on TCH/F of trx=0 ts=2 20160708003246512 DL1C <0006> scheduler.c:1364 Set mode 3, 0, handover 0 on PDTCH of trx=0 ts=2 20160708003246512 DL1C <0006> scheduler.c:1364 Set mode 3, 0, handover 0 on PTCCH of trx=0 ts=2 20160708003246512 DL1C <0006> scheduler.c:1429 Set a5/0 uplink for TCH/F on trx=0 ts=2 20160708003246512 DL1C <0006> scheduler.c:1429 Set a5/0 uplink for SACCH/TF on trx=0 ts=2 20160708003246512 DL1C <0006> scheduler.c:1429 Set a5/0 downlink for TCH/F on trx=0 ts=2 20160708003246512 DL1C <0006> scheduler.c:1429 Set a5/0 downlink for SACCH/TF on trx=0 ts=2 20160708003246512 DL1C <0006> l1_if.c:596 (bts=0,trx=0,ts=2,ss=0) lchan_activate() pchan=TCH/F as_pchan=mf[6] success and returns 20160708003246512 DRSL <0000> rsl.c:583 (bts=0,trx=0,ts=2,ss=0) Tx CHAN ACT ACK 20160708003246512 DRSL <0000> rsl.c:2058 (bts=0,trx=0,ts=0,ss=0) Rx RSL IMM_ASS_CMD
NITB
20160708002233549 DNM <0005> bsc_init.c:498 VTY at 127.0.0.1 4242 20160708002233549 DLINP <001d> input/ipaccess.c:838 enabling ipaccess BSC mode 20160708002233549 DSMPP <0016> smpp_smsc.c:978 SMPP at 0.0.0.0 2775 20160708002233549 DNM <0005> bsc_hack.c:305 CTRL at 127.0.0.1 4249 DB: Database initialized. DB: Database prepared. 20160708002242171 DLINP <001d> input/ipa.c:265 accept()ed new link from 127.0.0.1 to port 3002 20160708002242172 DNM <0005> abis_nm.c:316 OC=SITE-MANAGER(00) INST=(ff,ff,ff) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242172 DNM <0005> abis_nm.c:1765 OC=SITE-MANAGER(00) INST=(ff,ff,ff) Sending OPSTART 20160708002242172 DNM <0005> abis_nm.c:316 OC=BTS(01) INST=(00,ff,ff) STATE CHG: OP_STATE=NULL AVAIL=Dependency(05) 20160708002242172 DNM <0005> abis_nm.c:1449 Set BTS Attr (bts=0) ^ First Set BTS Attributes 20160708002242172 DNM <0005> abis_nm.c:1765 OC=BTS(01) INST=(00,ff,ff) Sending OPSTART 20160708002242172 DNM <0005> abis_nm.c:316 OC=GPRS-NSE(f0) INST=(00,ff,ff) STATE CHG: OP_STATE=NULL AVAIL=Dependency(05) 20160708002242172 DNM <0005> abis_nm.c:1765 OC=GPRS-NSE(f0) INST=(00,ff,ff) Sending OPSTART 20160708002242172 DNM <0005> abis_nm.c:316 OC=GPRS-CELL(f1) INST=(00,ff,ff) STATE CHG: OP_STATE=NULL AVAIL=Dependency(05) 20160708002242172 DNM <0005> abis_nm.c:1765 OC=GPRS-CELL(f1) INST=(00,00,ff) Sending OPSTART 20160708002242172 DNM <0005> abis_nm.c:316 OC=GPRS-NSVC(f2) INST=(00,00,ff) STATE CHG: OP_STATE=NULL AVAIL=Dependency(05) 20160708002242172 DNM <0005> abis_nm.c:1765 OC=GPRS-NSVC(f2) INST=(00,00,ff) Sending OPSTART 20160708002242172 DNM <0005> abis_nm.c:316 OC=GPRS-NSVC(f2) INST=(00,01,ff) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) 20160708002242172 DNM <0005> abis_nm.c:316 OC=RADIO-CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242172 DNM <0005> abis_nm.c:316 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242172 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242172 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242172 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242172 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242172 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242172 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242172 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242172 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=NULL AVAIL=Power off(02) 20160708002242210 DNM <0005> abis_nm.c:316 OC=RADIO-CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=Disabled AVAIL=OK(ff) 20160708002242210 DNM <0005> abis_nm.c:1765 OC=RADIO-CARRIER(02) INST=(00,00,ff) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) STATE CHG: OP_STATE=NULL AVAIL=OK(ff) 20160708002242250 DNM <0005> abis_nm.c:316 OC=RADIO-CARRIER(02) INST=(00,00,ff) Software Activated Report 20160708002242250 DNM <0005> abis_nm.c:1466 Set TRX Attr (bts=0,trx=0) 20160708002242250 DNM <0005> abis_nm.c:1765 OC=RADIO-CARRIER(02) INST=(00,00,ff) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) Software Activated Report 20160708002242250 DNM <0005> abis_nm.c:1765 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:2584 ip.access RSL CONNECT IP=0.0.0.0 PORT=3003 STREAM=0x00 20160708002242250 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242250 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=0) 20160708002242250 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,00) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242250 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=1) 20160708002242250 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,01) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242250 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=2) 20160708002242250 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,02) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242250 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=3) 20160708002242250 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,03) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242250 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=4) 20160708002242250 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,04) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242250 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=5) 20160708002242250 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,05) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242250 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=6) 20160708002242250 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,06) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242250 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=7) 20160708002242250 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,07) Sending OPSTART 20160708002242250 DNM <0005> abis_nm.c:316 OC=BTS(01) INST=(00,ff,ff) STATE CHG: OP_STATE=Enabled AVAIL=Dependency(05) 20160708002242251 DNM <0005> abis_nm.c:1449 Set BTS Attr (bts=0) ^ Second Set BTS Attributes First, receiving some more state changes from the BTS: 20160708002242251 DNM <0005> abis_nm.c:1765 OC=BTS(01) INST=(00,ff,ff) Sending OPSTART 20160708002242290 DNM <0005> abis_nm.c:2426 OC=GPRS-NSE(f0) INST=(00,ff,ff) IPACCESS(0xf6): SET ATTR ACK 20160708002242290 DNM <0005> abis_nm.c:316 OC=GPRS-NSE(f0) INST=(00,ff,ff) STATE CHG: OP_STATE=Enabled AVAIL=Dependency(05) 20160708002242290 DNM <0005> abis_nm.c:1765 OC=GPRS-NSE(f0) INST=(00,ff,ff) Sending OPSTART 20160708002242330 DNM <0005> abis_nm.c:2426 OC=GPRS-CELL(f1) INST=(00,00,ff) IPACCESS(0xf6): SET ATTR ACK 20160708002242330 DNM <0005> abis_nm.c:316 OC=GPRS-CELL(f1) INST=(00,ff,ff) STATE CHG: OP_STATE=Enabled AVAIL=Dependency(05) 20160708002242330 DNM <0005> abis_nm.c:1765 OC=GPRS-CELL(f1) INST=(00,00,ff) Sending OPSTART 20160708002242370 DNM <0005> abis_nm.c:2426 OC=GPRS-NSVC(f2) INST=(00,00,ff) IPACCESS(0xf6): SET ATTR ACK 20160708002242370 DNM <0005> abis_nm.c:316 OC=GPRS-NSVC(f2) INST=(00,00,ff) STATE CHG: OP_STATE=Enabled AVAIL=Dependency(05) 20160708002242370 DNM <0005> abis_nm.c:1765 OC=GPRS-NSVC(f2) INST=(00,00,ff) Sending OPSTART 20160708002242410 DNM <0005> abis_nm.c:316 OC=RADIO-CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242450 DNM <0005> abis_nm.c:316 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242490 DLINP <001d> input/ipa.c:265 accept()ed new link from 127.0.0.1 to port 3003 20160708002242490 DNM <0005> abis_nm.c:2426 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) IPACCESS(0xe1): RSL CONNECT ACK 20160708002242491 DRSL <0004> bsc_init.c:287 bootstrapping RSL for BTS/TRX (0/0) on ARFCN 868 using MCC=1 MNC=868 LAC=1 CID=0 BSIC=63 20160708002242491 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242530 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242571 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242610 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242651 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242690 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242730 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242770 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) ^ CHANNELs Enabled Now the BTS starts acting on the Set BTS Attributes message by Disabling: 20160708002242810 DNM <0005> abis_nm.c:316 OC=RADIO-CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=Disabled AVAIL=OK(ff) 20160708002242810 DNM <0005> abis_nm.c:1765 OC=RADIO-CARRIER(02) INST=(00,00,ff) Sending OPSTART ^ ...and the BSC thus sends OPSTART again 20160708002242850 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242850 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=0) 20160708002242850 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,00) Sending OPSTART 20160708002242850 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242850 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=1) 20160708002242850 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,01) Sending OPSTART 20160708002242850 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242850 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=2) 20160708002242850 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,02) Sending OPSTART 20160708002242850 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242850 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=3) 20160708002242850 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,03) Sending OPSTART 20160708002242850 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242850 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=4) 20160708002242850 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,04) Sending OPSTART 20160708002242850 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242850 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=5) 20160708002242850 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,05) Sending OPSTART 20160708002242850 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242850 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=6) 20160708002242850 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,06) Sending OPSTART 20160708002242850 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) 20160708002242851 DNM <0005> abis_nm.c:1658 Set Chan Attr (bts=0,trx=0,ts=7) 20160708002242851 DNM <0005> abis_nm.c:1765 OC=CHANNEL(03) INST=(00,00,07) Sending OPSTART ^ Each channel is Disabled and OPSTARTed again 20160708002242851 DNM <0005> abis_nm.c:2426 OC=GPRS-NSE(f0) INST=(00,ff,ff) IPACCESS(0xf6): SET ATTR ACK 20160708002242851 DNM <0005> abis_nm.c:2426 OC=GPRS-CELL(f1) INST=(00,00,ff) IPACCESS(0xf6): SET ATTR ACK 20160708002242851 DNM <0005> abis_nm.c:2426 OC=GPRS-NSVC(f2) INST=(00,00,ff) IPACCESS(0xf6): SET ATTR ACK 20160708002242852 DNM <0005> abis_nm.c:316 OC=RADIO-CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242890 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242930 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002242970 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002243010 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002243050 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002243090 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002243130 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) 20160708002243170 DNM <0005> abis_nm.c:316 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) ^ CHANNELs come back online
Related issues
Updated by neels over 7 years ago
- Description updated (diff)
- % Done changed from 0 to 80
Updated by neels over 7 years ago
- Related to Feature #1771: osmo-bts-trx: clarify check_transceiver_availability() added
Updated by neels over 7 years ago
- Status changed from New to In Progress
This is pending a test in the lab to be submitted for review.
Updated by neels over 7 years ago
- Status changed from In Progress to Resolved
- % Done changed from 80 to 100
Tested and submitted.
https://gerrit.osmocom.org/498