Hi,
after long time (may years), I do a "compileathlon"/"configathlon" with a osmo-bsc/osmo-stp/osmo-msc/osmo-sip-connector setup. If I interpret this the shown osmo-bsc output correctly, it attaches successfully through the osmo-stp with the osmo-msc. Then I start an ipaccess nanobts. It connects via OML, comes to a point where provisioning stops or times out.
I use a fresh debian installation with am64 and todays master branches from git.osmocom.org repository.
A pcap file of the OML connection is attached to this message. (The order of packets in the trace is messed up, due to my switch's port mirroring.) Also attached is the osmo-bsc.cfg.
Any idea?
Thank you,
Andreas
<0004> fsm.c:461 NM_BTS_SM_OP[0x558f2b1d17e0]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_BTS_OP[0x558f2b1d5120]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_GPRS_CELL_OP[0x558f2b1d5250]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_RCARRIER_OP[0x558f2b1da770]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_BB_TRANSC_OP[0x558f2b1da940]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_CHAN_OP[0x558f2b1dac40]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_CHAN_OP[0x558f2b1db340]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_CHAN_OP[0x558f2b1dbb40]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_CHAN_OP[0x558f2b1dc340]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_CHAN_OP[0x558f2b1dcb40]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_CHAN_OP[0x558f2b1dd3d0]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_CHAN_OP[0x558f2b1ddc60]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_CHAN_OP[0x558f2b1de4f0]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_GPRS_NSE_OP[0x558f2b1debd0]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_GPRS_NSVC_OP[0x558f2b1ded90]{DISABLED_NOTINSTALLED}: Allocated <0004> fsm.c:461 NM_GPRS_NSVC_OP[0x558f2b1defc0]{DISABLED_NOTINSTALLED}: Allocated <0015> telnet_interface.c:104 Available via telnet 127.0.0.1 4242 <0017> input/ipaccess.c:1035 enabling ipaccess BSC mode on 0.0.0.0 with OML 3002 and RSL 3003 TCP ports <001c> control_if.c:922 CTRL at 127.0.0.1 4249 <0025> mgcp_client.c:782 MGCP client: using endpoint domain '@mgw' <0007> osmo_bsc_sigtran.c:548 Initializing SCCP connection for A/m3ua on cs7 instance 0 <0022> sccp_user.c:548 A-0-m3ua: Using SS7 instance 0, pc:1.23.3 <0022> sccp_user.c:555 A-0-m3ua: Creating AS instance <0022> sccp_user.c:566 A-0-m3ua: Using AS instance as-clnt-A-0-m3ua <0022> sccp_user.c:571 A-0-m3ua: Creating default route <0022> sccp_user.c:605 A-0-m3ua: ASP my-asp for m3ua is not associated with any AS, using it <0022> sccp_user.c:638 A-0-m3ua: Using ASP instance my-asp <0021> osmo_ss7.c:456 0: Creating SCCP instance <0007> osmo_bsc_sigtran.c:600 A-0-m3ua msc-0: local (BSC) SCCP address: RI=SSN_PC,PC=1.23.3,SSN=BSSAP <0007> osmo_bsc_sigtran.c:602 A-0-m3ua msc-0: remote (MSC) SCCP address: RI=SSN_PC,PC=0.23.1,SSN=BSSAP <0007> osmo_bsc_sigtran.c:607 A-0-m3ua msc-0: binding SCCP user <0021> m3ua.c:507 XUA_AS(as-clnt-A-0-m3ua)[0x558f2b1e2c10]{AS_DOWN}: Event AS-TRANSFER.req not permitted <0024> m3ua.c:622 0: asp-my-asp: Received NOTIFY Type State Change:AS Inactive () <0021> xua_default_lm_fsm.c:361 xua_default_lm(my-asp)[0x558f2b1e2f40]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm <0024> m3ua.c:622 0: asp-my-asp: Received NOTIFY Type State Change:AS Active () <0007> osmo_bsc_bssap.c:81 RESET ACK from MSC: RI=SSN_PC,PC=0.23.1,SSN=BSSAP <0014> bssmap_reset.c:83 bssmap_reset(msc-0)[0x558f2b1e3d50]{CONN}: link up <0007> a_reset.c:44 (msc0) BSSMAP assocation is up <0017> input/ipa.c:284 0.0.0.0:3002 accept()ed new link from 10.0.0.96:48280 <0004> abis_nm.c:258 OC=SITE-MANAGER(00) INST=(ff,ff,ff): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) <0004> bts_ipaccess_nanobts.c:156 NM_BTS_SM_OP(bts_sm)[0x558f2b1d17e0]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=BTS(01) INST=(00,ff,ff): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:160 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:164 NM_BB_TRANSC_OP(bts0-trx0)[0x558f2b1da940]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=CHANNEL(03) INST=(00,00,00): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:169 NM_CHAN_OP(bts0-trx0-ts0)[0x558f2b1dac40]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=CHANNEL(03) INST=(00,00,01): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:169 NM_CHAN_OP(bts0-trx0-ts1)[0x558f2b1db340]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=CHANNEL(03) INST=(00,00,02): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:169 NM_CHAN_OP(bts0-trx0-ts2)[0x558f2b1dbb40]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=CHANNEL(03) INST=(00,00,03): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:169 NM_CHAN_OP(bts0-trx0-ts3)[0x558f2b1dc340]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=CHANNEL(03) INST=(00,00,04): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:169 NM_CHAN_OP(bts0-trx0-ts4)[0x558f2b1dcb40]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=CHANNEL(03) INST=(00,00,05): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:169 NM_CHAN_OP(bts0-trx0-ts5)[0x558f2b1dd3d0]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=CHANNEL(03) INST=(00,00,06): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:169 NM_CHAN_OP(bts0-trx0-ts6)[0x558f2b1ddc60]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=CHANNEL(03) INST=(00,00,07): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:169 NM_CHAN_OP(bts0-trx0-ts7)[0x558f2b1de4f0]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=RADIO-CARRIER(02) INST=(00,00,ff): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:173 NM_RCARRIER_OP(bts0-trx0)[0x558f2b1da770]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=GPRS-NSE(f0) INST=(00,ff,ff): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:177 NM_GPRS_NSE_OP(nse0)[0x558f2b1debd0]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=GPRS-CELL(f1) INST=(00,00,ff): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:181 NM_GPRS_CELL_OP(gprs-cell0)[0x558f2b1d5250]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=GPRS-NSVC(f2) INST=(00,00,ff): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> bts_ipaccess_nanobts.c:188 NM_GPRS_NSVC_OP(nsvc0)[0x558f2b1ded90]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:258 OC=GPRS-NSVC(f2) INST=(00,01,ff): STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked <0004> abis_nm.c:724 OC=SITE-MANAGER(00) INST=(ff,ff,ff): Software Activate Request, ACKing and Activating <0004> abis_nm.c:743 OC=SITE-MANAGER(00) INST=(ff,ff,ff): Found SW config: 42 12 00 08 31 32 30 61 30 30 32 00 13 00 09 76 32 36 37 62 35 64 30 00 <0004> abis_nm.c:755 OC=SITE-MANAGER(00) INST=(ff,ff,ff): Selected sw description 0 of 1 <0004> abis_nm.c:258 OC=SITE-MANAGER(00) INST=(ff,ff,ff): STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) <0004> bts_ipaccess_nanobts.c:156 NM_BTS_SM_OP(bts_sm)[0x558f2b1d17e0]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> abis_nm.c:2186 OC=SITE-MANAGER(00) INST=(ff,ff,ff): Sending OPSTART <0004> nm_bts_sm_fsm.c:83 NM_BTS_SM_OP(bts_sm)[0x558f2b1d17e0]{DISABLED_NOTINSTALLED}: state_chg to ENABLED <0004> abis_nm.c:478 OC=SITE-MANAGER(00) INST=(ff,ff,ff): Software Activated Report <0004> bts_ipaccess_nanobts.c:211 NM_BTS_SM_OP(bts_sm)[0x558f2b1d17e0]{ENABLED}: Received Event SW_ACT_REP <0004> bts_ipaccess_nanobts.c:211 NM_BTS_SM_OP(bts_sm)[0x558f2b1d17e0]{ENABLED}: Event SW_ACT_REP not permitted <0004> abis_nm.c:724 OC=BTS(01) INST=(00,ff,ff): Software Activate Request, ACKing and Activating <0004> abis_nm.c:743 OC=BTS(01) INST=(00,ff,ff): Found SW config: 42 12 00 08 31 32 30 61 30 30 32 00 13 00 09 76 32 36 37 62 35 64 30 00 <0004> abis_nm.c:755 OC=BTS(01) INST=(00,ff,ff): Selected sw description 0 of 1 <0004> abis_nm.c:724 OC=GPRS-NSE(f0) INST=(00,ff,ff): Software Activate Request, ACKing and Activating <0004> abis_nm.c:743 OC=GPRS-NSE(f0) INST=(00,ff,ff): Found SW config: 42 12 00 08 31 32 30 61 30 30 32 00 13 00 09 76 32 36 37 62 35 64 30 00 <0004> abis_nm.c:755 OC=GPRS-NSE(f0) INST=(00,ff,ff): Selected sw description 0 of 1 <0004> abis_nm.c:839 OC=SITE-MANAGER(00) INST=(ff,ff,ff): Opstart ACK <0004> bts_ipaccess_nanobts.c:271 NM_BTS_SM_OP(bts_sm)[0x558f2b1d17e0]{ENABLED}: Received Event OPSTART_ACK <0004> abis_nm.c:258 OC=BTS(01) INST=(00,ff,ff): STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) <0004> bts_ipaccess_nanobts.c:160 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> nm_bts_fsm.c:77 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_NOTINSTALLED}: state_chg to DISABLED_DEPENDENCY <0004> nm_bts_fsm.c:153 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_DEPENDENCY}: state_chg to DISABLED_OFFLINE <0004> abis_nm.c:1835 OC=BTS(01) INST=(00,ff,ff): Tx Get Attributes (Request) <0004> abis_nm.c:478 OC=BTS(01) INST=(00,ff,ff): Software Activated Report <0004> bts_ipaccess_nanobts.c:214 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_OFFLINE}: Received Event SW_ACT_REP <0004> bts_ipaccess_nanobts.c:214 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_OFFLINE}: Event SW_ACT_REP not permitted <0004> abis_nm.c:724 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): Software Activate Request, ACKing and Activating <0004> abis_nm.c:743 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): Found SW config: 42 12 00 08 31 32 30 61 30 30 31 00 13 00 09 76 32 36 37 62 35 64 30 00 42 12 00 08 31 32 30 61 30 30 32 00 13 00 09 76 32 36 37 62 35 64 30 00 <0004> abis_nm.c:755 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): Selected sw description 0 of 2 <0004> abis_nm.c:724 OC=RADIO-CARRIER(02) INST=(00,00,ff): Software Activate Request, ACKing and Activating <0004> abis_nm.c:743 OC=RADIO-CARRIER(02) INST=(00,00,ff): Found SW config: 42 12 00 08 31 32 30 61 30 30 31 00 13 00 09 76 32 36 37 62 35 64 30 00 42 12 00 08 31 32 30 61 30 30 32 00 13 00 09 76 32 36 37 62 35 64 30 00 <0004> abis_nm.c:755 OC=RADIO-CARRIER(02) INST=(00,00,ff): Selected sw description 0 of 2 <0004> abis_nm.c:258 OC=GPRS-NSE(f0) INST=(00,ff,ff): STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) <0004> bts_ipaccess_nanobts.c:177 NM_GPRS_NSE_OP(nse0)[0x558f2b1debd0]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> nm_gprs_nse_fsm.c:76 NM_GPRS_NSE_OP(nse0)[0x558f2b1debd0]{DISABLED_NOTINSTALLED}: state_chg to DISABLED_DEPENDENCY <0004> nm_gprs_nse_fsm.c:131 NM_GPRS_NSE_OP(nse0)[0x558f2b1debd0]{DISABLED_DEPENDENCY}: state_chg to DISABLED_OFFLINE <0004> abis_nm.c:478 OC=GPRS-NSE(f0) INST=(00,ff,ff): Software Activated Report <0004> bts_ipaccess_nanobts.c:232 NM_GPRS_NSE_OP(nse0)[0x558f2b1debd0]{DISABLED_OFFLINE}: Received Event SW_ACT_REP <0004> bts_ipaccess_nanobts.c:232 NM_GPRS_NSE_OP(nse0)[0x558f2b1debd0]{DISABLED_OFFLINE}: Event SW_ACT_REP not permitted <0004> abis_nm.c:724 OC=GPRS-CELL(f1) INST=(00,00,ff): Software Activate Request, ACKing and Activating <0004> abis_nm.c:743 OC=GPRS-CELL(f1) INST=(00,00,ff): Found SW config: 42 12 00 08 31 32 30 61 30 30 32 00 13 00 09 76 32 36 37 62 35 64 30 00 <0004> abis_nm.c:755 OC=GPRS-CELL(f1) INST=(00,00,ff): Selected sw description 0 of 1 <0004> abis_nm.c:724 OC=GPRS-NSVC(f2) INST=(00,00,ff): Software Activate Request, ACKing and Activating <0004> abis_nm.c:743 OC=GPRS-NSVC(f2) INST=(00,00,ff): Found SW config: 42 12 00 08 31 32 30 61 30 30 32 00 13 00 09 76 32 36 37 62 35 64 30 00 <0004> abis_nm.c:755 OC=GPRS-NSVC(f2) INST=(00,00,ff): Selected sw description 0 of 1 <0004> abis_nm.c:724 OC=GPRS-NSVC(f2) INST=(00,01,ff): Software Activate Request, ACKing and Activating <0004> abis_nm.c:743 OC=GPRS-NSVC(f2) INST=(00,01,ff): Found SW config: 42 12 00 08 31 32 30 61 30 30 32 00 13 00 09 76 32 36 37 62 35 64 30 00 <0004> abis_nm.c:755 OC=GPRS-NSVC(f2) INST=(00,01,ff): Selected sw description 0 of 1 <0004> abis_nm.c:698 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response <0004> abis_nm.c:592 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: reported unexpectedly long (13 bytes) feature vector - most likely it was compiled against newer BSC headers. Consider upgrading your BSC to later version. <0004> abis_nm.c:610 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: reported feature 'MULTI_TSC' is not supported, while we thought it is. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x17. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x1a. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x1c. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x1d. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x1e. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x21. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x22. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x24. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x27. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x29. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x2a. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x2b. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x31. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x32. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x37. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x39. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x3a. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x3e. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x3f. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x41. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x42. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x46. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x47. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x49. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x4a. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x4d. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x4f. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x51. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x52. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x53. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x56. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x57. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x59. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x5a. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x5b. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x5e. Consider upgrading osmo-bsc. <0004> abis_nm.c:602 OC=BTS(01) INST=(00,ff,ff): Get Attributes Response: unknown feature 0x5f. Consider upgrading osmo-bsc. <0004> abis_nm.c:633 OC=BTS(01) INST=(00,ff,ff): ARI reported sw[0/2]: 120a004 is v267b5d0 <0004> abis_nm.c:633 OC=BTS(01) INST=(00,ff,ff): ARI reported sw[1/2]: 120a002 is v267b5d0 <0004> bts_ipaccess_nanobts.c:361 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_OFFLINE}: Received Event GET_ATTR_REP <0004> nm_bts_fsm.c:139 NM_GPRS_NSVC_OP(nsvc0)[0x558f2b1ded90]{DISABLED_NOTINSTALLED}: Received Event FEATURE_NEGOTIATED <0004> abis_nm.c:1847 OC=BTS(01) INST=(00,ff,ff): Set BTS Attr <0004> abis_nm.c:258 OC=GPRS-CELL(f1) INST=(00,00,ff): STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) <0004> bts_ipaccess_nanobts.c:181 NM_GPRS_CELL_OP(gprs-cell0)[0x558f2b1d5250]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> nm_gprs_cell_fsm.c:75 NM_GPRS_CELL_OP(gprs-cell0)[0x558f2b1d5250]{DISABLED_NOTINSTALLED}: state_chg to DISABLED_DEPENDENCY <0004> nm_gprs_cell_fsm.c:130 NM_GPRS_CELL_OP(gprs-cell0)[0x558f2b1d5250]{DISABLED_DEPENDENCY}: state_chg to DISABLED_OFFLINE <0004> abis_nm.c:478 OC=GPRS-CELL(f1) INST=(00,00,ff): Software Activated Report <0004> bts_ipaccess_nanobts.c:235 NM_GPRS_CELL_OP(gprs-cell0)[0x558f2b1d5250]{DISABLED_OFFLINE}: Received Event SW_ACT_REP <0004> bts_ipaccess_nanobts.c:235 NM_GPRS_CELL_OP(gprs-cell0)[0x558f2b1d5250]{DISABLED_OFFLINE}: Event SW_ACT_REP not permitted <0004> abis_nm.c:258 OC=GPRS-NSVC(f2) INST=(00,00,ff): STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) <0004> bts_ipaccess_nanobts.c:188 NM_GPRS_NSVC_OP(nsvc0)[0x558f2b1ded90]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> nm_gprs_nsvc_fsm.c:83 NM_GPRS_NSVC_OP(nsvc0)[0x558f2b1ded90]{DISABLED_NOTINSTALLED}: state_chg to DISABLED_OFFLINE <0004> abis_nm.c:258 OC=GPRS-NSVC(f2) INST=(00,01,ff): STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) <0004> abis_nm.c:478 OC=GPRS-NSVC(f2) INST=(00,00,ff): Software Activated Report <0004> bts_ipaccess_nanobts.c:240 NM_GPRS_NSVC_OP(nsvc0)[0x558f2b1ded90]{DISABLED_OFFLINE}: Received Event SW_ACT_REP <0004> bts_ipaccess_nanobts.c:240 NM_GPRS_NSVC_OP(nsvc0)[0x558f2b1ded90]{DISABLED_OFFLINE}: Event SW_ACT_REP not permitted <0004> abis_nm.c:478 OC=GPRS-NSVC(f2) INST=(00,01,ff): Software Activated Report <0004> bts_ipaccess_nanobts.c:240 NM_GPRS_NSVC_OP(nsvc1)[0x558f2b1defc0]{DISABLED_NOTINSTALLED}: Received Event SW_ACT_REP <0004> abis_nm.c:2852 OC=GPRS-NSE(f0) INST=(00,ff,ff): Rx IPACCESS(0xf6): 9d 00 02 00 00 a0 00 07 03 03 03 03 1e 03 0a a1 00 0b 03 03 03 03 03 0a 03 0a 03 0a 03 <0004> abis_nm.c:2909 OC=GPRS-NSE(f0) INST=(00,ff,ff): SET ATTR ACK <0004> bts_ipaccess_nanobts.c:431 NM_GPRS_NSE_OP(nse0)[0x558f2b1debd0]{DISABLED_OFFLINE}: Received Event SET_ATTR_ACK <0004> abis_nm.c:871 OC=BTS(01) INST=(00,ff,ff): Set BTS Attributes ACK <0004> bts_ipaccess_nanobts.c:383 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_OFFLINE}: Received Event SET_ATTR_ACK <0004> abis_nm.c:780 OC=GPRS-NSE(f0) INST=(00,ff,ff): Rx Change Administrative State ACK Unlocked <0004> bts_ipaccess_nanobts.c:177 NM_GPRS_NSE_OP(nse0)[0x558f2b1debd0]{DISABLED_OFFLINE}: Received Event STATE_CHG_REP <0004> abis_nm.c:2186 OC=GPRS-NSE(f0) INST=(00,ff,ff): Sending OPSTART <0004> abis_nm.c:258 OC=GPRS-NSE(f0) INST=(00,ff,ff): STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked (No State change detected) <0004> abis_nm.c:780 OC=BTS(01) INST=(00,ff,ff): Rx Change Administrative State ACK Unlocked <0004> bts_ipaccess_nanobts.c:160 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_OFFLINE}: Received Event STATE_CHG_REP <0004> abis_nm.c:2186 OC=BTS(01) INST=(00,ff,ff): Sending OPSTART <0004> abis_nm.c:258 OC=BTS(01) INST=(00,ff,ff): STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked (No State change detected) <0004> abis_nm.c:839 OC=GPRS-NSE(f0) INST=(00,ff,ff): Opstart ACK <0004> bts_ipaccess_nanobts.c:290 NM_GPRS_NSE_OP(nse0)[0x558f2b1debd0]{DISABLED_OFFLINE}: Received Event OPSTART_ACK <0004> abis_nm.c:839 OC=BTS(01) INST=(00,ff,ff): Opstart ACK <0004> bts_ipaccess_nanobts.c:274 NM_BTS_OP(bts0)[0x558f2b1d5120]{DISABLED_OFFLINE}: Received Event OPSTART_ACK
After a while the OML connection is lost.
<0004> abis_nm.c:258 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) <0004> bts_ipaccess_nanobts.c:164 NM_BB_TRANSC_OP(bts0-trx0)[0x56372d474940]{DISABLED_NOTINSTALLED}: Received Event STATE_CHG_REP <0004> nm_bb_transc_fsm.c:75 NM_BB_TRANSC_OP(bts0-trx0)[0x56372d474940]{DISABLED_NOTINSTALLED}: state_chg to DISABLED_DEPENDENCY <0004> nm_bb_transc_fsm.c:132 NM_BB_TRANSC_OP(bts0-trx0)[0x56372d474940]{DISABLED_DEPENDENCY}: state_chg to DISABLED_OFFLINE <0004> abis_nm.c:1835 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): Tx Get Attributes (Request) <0004> abis_nm.c:698 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): Get Attributes Response <0004> abis_nm.c:633 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): ARI reported sw[0/3]: 120a004 is v267b5d0 <0004> abis_nm.c:633 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): ARI reported sw[1/3]: 120a002 is v267b5d0 <0004> abis_nm.c:633 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): ARI reported sw[2/3]: 120a001 is v267b5d0 <0004> abis_nm.c:551 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): Get Attributes Response Info: 2 bytes total with 1 unreported attributes <0004> abis_nm.c:556 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): Attribute Manufacturer Dependent State is unreported <0004> bts_ipaccess_nanobts.c:366 NM_BB_TRANSC_OP(bts0-trx0)[0x56372d474940]{DISABLED_OFFLINE}: Received Event GET_ATTR_REP <0004> abis_nm.c:945 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff): CHANGE ADMINISTRATIVE STATE NACK CAUSE=Message cannot be performed <0004> osmo_bsc_main.c:229 Got CHANGE ADMINISTRATIVE STATE NACK going to drop the OML links. <0017> bts_ipaccess_nanobts.c:620 (bts=0) Deferring Drop of OML link. <0017> bts_ipaccess_nanobts.c:555 (bts=0) Dropping OML link: Deferred link drop <0019> osmo_bsc_main.c:405 (bts=0,trx=0) Lost E1 OML link <0019> osmo_bsc_main.c:405 (bts=0,trx=0) Lost E1 OSMO link <0004> bts_ipaccess_nanobts.c:568 NM_BB_TRANSC_OP(bts0-trx0)[0x56372d474940]{DISABLED_OFFLINE}: Received Event OML_DOWN <0004> nm_bb_transc_fsm.c:291 NM_BB_TRANSC_OP(bts0-trx0)[0x56372d474940]{DISABLED_OFFLINE}: state_chg to DISABLED_NOTINSTALLED <0004> bts_ipaccess_nanobts.c:569 NM_RCARRIER_OP(bts0-trx0)[0x56372d474770]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:572 NM_CHAN_OP(bts0-trx0-ts0)[0x56372d474c40]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:572 NM_CHAN_OP(bts0-trx0-ts1)[0x56372d475340]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:572 NM_CHAN_OP(bts0-trx0-ts2)[0x56372d475b40]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:572 NM_CHAN_OP(bts0-trx0-ts3)[0x56372d476340]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:572 NM_CHAN_OP(bts0-trx0-ts4)[0x56372d476b40]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:572 NM_CHAN_OP(bts0-trx0-ts5)[0x56372d4773d0]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:572 NM_CHAN_OP(bts0-trx0-ts6)[0x56372d477c60]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:572 NM_CHAN_OP(bts0-trx0-ts7)[0x56372d4784f0]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:576 NM_BTS_SM_OP(bts_sm)[0x56372d46b7e0]{ENABLED}: Received Event OML_DOWN <0004> nm_bts_sm_fsm.c:216 NM_BTS_SM_OP(bts_sm)[0x56372d46b7e0]{ENABLED}: state_chg to DISABLED_NOTINSTALLED <0004> bts_ipaccess_nanobts.c:577 NM_GPRS_NSE_OP(nse0)[0x56372d478bd0]{DISABLED_OFFLINE}: Received Event OML_DOWN <0004> nm_gprs_nse_fsm.c:298 NM_GPRS_NSE_OP(nse0)[0x56372d478bd0]{DISABLED_OFFLINE}: state_chg to DISABLED_NOTINSTALLED <0004> bts_ipaccess_nanobts.c:579 NM_GPRS_NSVC_OP(nsvc0)[0x56372d478d90]{DISABLED_OFFLINE}: Received Event OML_DOWN <0004> nm_gprs_nsvc_fsm.c:307 NM_GPRS_NSVC_OP(nsvc0)[0x56372d478d90]{DISABLED_OFFLINE}: state_chg to DISABLED_NOTINSTALLED <0004> bts_ipaccess_nanobts.c:579 NM_GPRS_NSVC_OP(nsvc1)[0x56372d478fc0]{DISABLED_NOTINSTALLED}: Received Event OML_DOWN <0004> bts_ipaccess_nanobts.c:581 NM_BTS_OP(bts0)[0x56372d46f120]{DISABLED_OFFLINE}: Received Event OML_DOWN <0004> nm_bts_fsm.c:319 NM_BTS_OP(bts0)[0x56372d46f120]{DISABLED_OFFLINE}: state_chg to DISABLED_NOTINSTALLED <0004> bts_ipaccess_nanobts.c:582 NM_GPRS_CELL_OP(gprs-cell0)[0x56372d46f250]{DISABLED_OFFLINE}: Received Event OML_DOWN <0004> nm_gprs_cell_fsm.c:296 NM_GPRS_CELL_OP(gprs-cell0)[0x56372d46f250]{DISABLED_OFFLINE}: state_chg to DISABLED_NOTINSTALLED ^Csignal 2 received <0004> bsc_init.c:66 shutting down OML for BTS 0
Hi Andreas,
It would be great if you could provide a proper pcap file since as you may know order of such messages is rather important :)
I'm having difficulties matching 100% the log file vs the pcap. Using gmstap logging here may use in order to have all the info in the same place and more or less ordrered.
There seems to be an issue configuring the BTS object in OML. From pcap file, this is what looks like:
frame 10: BTS => BSC: BTS State Changed Event Report(Not installed, Locked) frame 18: BTS => BSC: Software Activate Request frame 21: BTS <= BSC: Software Activate Request ACK frame 22: BTS <= BSC: Activate Software frame 27: BTS => BSC: Activate Software ACK frame 27: BTS => BSC: BTS State Changed Event Report(Disabled, Dependency) frame 27: BTS => BSC: Software Activated Report frame 29: BTS <= BSC: Get Attributes frame 39: BTS => BSC: Get Attributes Response frame 48: BTS <= BSC: Set BTS Attributes frame 49: BTS => BSC: Set BTS Attributes ACK frame 52: BTS <= BSC: Change Administrative State (Unlocked) frame 55: BTS => BSC: Change Administrative State ACK (Unlocked) frame 55: BTS => BSC: BTS State Changed Event Report(Dependency, Unlocked) frame 61: BTS <= BSC: Opstart frame 160: BTS => BSC: BTS State Changed Event Report(Enabled) frame 200: BTS => BSC: Get Attributes Response ?!?!?!?! where does this come from?!?!?! frame 219: BTS <= BSC: Set BTS Attributes frame238: BTS => BSC: Opstart ACK
Some of those, specially at the end, look really weird, but it could be due to your setup as you mentioned.
In any case, according to pcap the BSC receives a "BTS State Changed Event Report(Enabled)" at frame 160, but I never see that message being processed in the log file (NM_BTS_OP(bts0) FSM is still in state DISABLED_OFFLINE when receiving the OPSTART_ACK but it should have moved to ENABLED already when receiving such report afaict from nm_bts_fsm.c:230.
Feel free to create a ticket if you can sort out the proper recording of pcap file and you still find issues.
Regards, Pau
Pau Espin Pedrol wrote:
Some of those, specially at the end, look really weird, but it could be due to your setup as you mentioned.
In any case, according to pcap the BSC receives a "BTS State Changed Event Report(Enabled)" at frame 160, but I never see that message being processed in the log file (NM_BTS_OP(bts0) FSM is still in state DISABLED_OFFLINE when receiving the OPSTART_ACK but it should have moved to ENABLED already when receiving such report afaict from nm_bts_fsm.c:230.
Hi Pau,
it is not a Software issue. The BTS crashes and reboots when it starts transmitting. Maybe a faulty solder connection. The other BTS works well!
Regards,
Andreas
-
Andreas Eversberg -
Neels Hofmeyr -
Pau Espin Pedrol