Project

General

Profile

Actions

Bug #1770

closed

osmo-bts-trx goes through OML initialization twice

Added by neels over 7 years ago. Updated over 7 years ago.

Status:
Closed
Priority:
Normal
Assignee:
Category:
-
Target version:
-
Start date:
07/08/2016
Due date:
% Done:

100%

Spec Reference:

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

Related to OsmoBTS - Feature #1771: osmo-bts-trx: clarify check_transceiver_availability()New07/08/2016

Actions
Actions #1

Updated by neels over 7 years ago

  • Description updated (diff)
  • % Done changed from 0 to 80
Actions #2

Updated by neels over 7 years ago

  • Related to Feature #1771: osmo-bts-trx: clarify check_transceiver_availability() added
Actions #3

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.

Actions #4

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

Actions #5

Updated by laforge over 7 years ago

  • Status changed from Resolved to Closed
Actions

Also available in: Atom PDF

Add picture from clipboard (Maximum size: 48.8 MB)