Bug #1770
Updated by neels almost 8 years ago
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.
<pre>
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.
</pre>
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).
<pre>
[[[
((*))
|
/ \ 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
</pre>
]]]
*NITB*
<pre> [[[
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
</pre>
