Open Source Mobile Communications: Issueshttps://osmocom.org/https://osmocom.org/favicon.ico?16647414092023-06-02T15:08:09ZOpen Source Mobile Communications
Redmine OsmoSGSN - Feature #6050 (Closed): Add missing testcase to test GERAN originated RIM RAN informat...https://osmocom.org/issues/60502023-06-02T15:08:09Zdexter
<p>At the moment we only have a testcase for an EUTRAN originated RAN information request that targets a GERAN cell (TC_rim_eutran_to_geran). We have no testcase that tests the reverse direction, which is equally important. (We also have no testcase for requests that go from EUTRAN to EUTRAN.)</p>
<p>We should add those two missing testcases to the testsuite in order to be sure that RIM message forwarding is working correctly.</p> OsmoMGW - Bug #5984 (Closed): fix regression in TC_two_crcx_and_one_mdcx_rtp_hohttps://osmocom.org/issues/59842023-03-29T20:00:42Zdexter
<p>TC_two_crcx_and_one_mdcx_rtp_ho fails with reason: "RTP packets received while RX was disabled"</p> OsmoMSC - Bug #4160 (Rejected): osmo-msc segfaultshttps://osmocom.org/issues/41602019-08-20T11:40:11Zdexter
<pre>
Tue Aug 20 13:24:18 2019 DPAG <0005> paging.c:97 Paging: IMSI-001010000000102:MSISDN-23102 for MNCC: establish call: Starting paging
Tue Aug 20 13:24:18 2019 DMSC <0006> sgs_iface.c:470 XXXXXXXXXX state == 1 conf_by_radio_contact_ind == 1
Tue Aug 20 13:24:18 2019 DREF <000a> vlr_sgs.c:351 VLR subscr IMSI-001010000000102:MSISDN-23102 + SGs-paging-req: now used by 6 (2*SGs,attached,mncc_tx_to_gsm_cc,CC,SGs-paging-req)
Tue Aug 20 13:24:18 2019 DREF <000a> paging.c:107 VLR subscr IMSI-001010000000102:MSISDN-23102 + Paging: now used by 7 (2*SGs,attached,mncc_tx_to_gsm_cc,CC,SGs-paging-req,Paging)
Tue Aug 20 13:24:18 2019 DREF <000a> gsm_04_08_cc.c:1925 VLR subscr IMSI-001010000000102:MSISDN-23102 - mncc_tx_to_gsm_cc: now used by 6 (2*SGs,attached,CC,SGs-paging-req,Paging)
Tue Aug 20 13:24:19 2019 DREF <000a> sgs_iface.c:274 VLR subscr IMSI-001010000000102:MSISDN-23102 + check_sgs_association: now used by 7 (2*SGs,attached,CC,SGs-paging-req,Paging,check_sgs_association)
Tue Aug 20 13:24:19 2019 DREF <000a> sgs_iface.c:293 VLR subscr IMSI-001010000000102:MSISDN-23102 - check_sgs_association: now used by 6 (2*SGs,attached,CC,SGs-paging-req,Paging)
Tue Aug 20 13:24:19 2019 DREF <000a> sgs_iface.c:786 VLR subscr IMSI-001010000000102:MSISDN-23102 + sgs_rx_service_req: now used by 7 (2*SGs,attached,CC,SGs-paging-req,Paging,sgs_rx_service_req)
Tue Aug 20 13:24:19 2019 DREF <000a> vlr_sgs.c:263 VLR subscr IMSI-001010000000102:MSISDN-23102 + vlr_sgs_pag_ack: now used by 8 (2*SGs,attached,CC,SGs-paging-req,Paging,sgs_rx_service_req,vlr_sgs_pag_ack)
Tue Aug 20 13:24:19 2019 DREF <000a> vlr_sgs.c:270 VLR subscr IMSI-001010000000102:MSISDN-23102 - SGs-paging-req: now used by 7 (2*SGs,attached,CC,Paging,sgs_rx_service_req,vlr_sgs_pag_ack)
Tue Aug 20 13:24:19 2019 DREF <000a> vlr_sgs.c:272 VLR subscr IMSI-001010000000102:MSISDN-23102 - vlr_sgs_pag_ack: now used by 6 (2*SGs,attached,CC,Paging,sgs_rx_service_req)
Tue Aug 20 13:24:19 2019 DREF <000a> sgs_iface.c:823 VLR subscr IMSI-001010000000102:MSISDN-23102 - sgs_rx_service_req: now used by 5 (2*SGs,attached,CC,Paging)
Tue Aug 20 13:24:21 2019 DBSSAP <0010> sccp_ran.c:84 (GERAN-A-1 from RI=SSN_PC,PC=0.23.3,SSN=BSSAP) sccp_ran_sap_up(N-CONNECT.indication)
Tue Aug 20 13:24:21 2019 DRR <0003> ran_peer.c:596 ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55d7abeed440]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO_INITIAL
Tue Aug 20 13:24:21 2019 DMSC <0006> fsm.c:423 msub_fsm[0x55d7abeed2a0]{active}: Allocated
Tue Aug 20 13:24:21 2019 DMSC <0006> fsm.c:423 msc_i[0x55d7abeee460]{READY}: Allocated
Tue Aug 20 13:24:21 2019 DMSC <0006> fsm.c:453 msc_i[0x55d7abeee460]{READY}: is child of msub_fsm[0x55d7abeed2a0]
Tue Aug 20 13:24:21 2019 DMSC <0006> fsm.c:423 msc_a[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: Allocated
Tue Aug 20 13:24:21 2019 DMSC <0006> fsm.c:453 msc_a[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: is child of msub_fsm[0x55d7abeed2a0]
Tue Aug 20 13:24:21 2019 DMSC <0006> msc_a.c:1039 msc_a(unknown:GERAN-A-1:NONE)[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: State change to MSC_A_ST_VALIDATE_L3 (X1, 5s)
Tue Aug 20 13:24:21 2019 DMSC <0006> ran_peer.c:392 msc_i(unknown:GERAN-A-1:NONE)[0x55d7abeee460]{READY}: Received Event MSC_EV_FROM_RAN_COMPLETE_LAYER_3
Tue Aug 20 13:24:21 2019 DMSC <0006> msc_i.c:103 msc_a(unknown:GERAN-A-1:NONE)[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_FROM_I_COMPLETE_LAYER_3
Tue Aug 20 13:24:21 2019 DREF <000a> msc_a.c:176 msc_a(unknown:GERAN-A-1:NONE)[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: + msc_a_ran_dec: now used by 1 (msc_a_ran_dec)
Tue Aug 20 13:24:21 2019 DBSSAP <0010> ran_msg_a.c:783 msc_a(unknown:GERAN-A-1:NONE)[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: RAN decode: BSSMAP: Rx BSSMAP DT1 COMPLETE LAYER 3
Tue Aug 20 13:24:21 2019 DBSSAP <0010> msc_a.c:1513 msc_a(unknown:GERAN-A-1:NONE)[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: RAN decode: BSSMAP Complete Layer 3
Tue Aug 20 13:24:21 2019 DRLL <0000> msc_a.c:1160 msc_a(unknown:GERAN-A-1:NONE)[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: Dispatching 04.08 message: RR GSM48_MT_RR_PAG_RESP
Tue Aug 20 13:24:21 2019 DRR <0003> gsm_04_08.c:1145 msc_a(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: Rx PAGING RESPONSE
Tue Aug 20 13:24:21 2019 DREF <000a> gsm_04_08.c:1147 msc_a(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: + paging-response: now used by 2 (msc_a_ran_dec,paging-response)
Tue Aug 20 13:24:21 2019 DVLR <000e> fsm.c:423 Process_Access_Request_VLR(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeeebb0]{PR_ARQ_S_INIT}: Allocated
Tue Aug 20 13:24:21 2019 DVLR <000e> fsm.c:453 Process_Access_Request_VLR(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeeebb0]{PR_ARQ_S_INIT}: is child of msc_a(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeee920]
Tue Aug 20 13:24:21 2019 DVLR <000e> vlr_access_req_fsm.c:675 Process_Access_Request_VLR(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeeebb0]{PR_ARQ_S_INIT}: rev=R99 net=GERAN (no Auth)
Tue Aug 20 13:24:21 2019 DVLR <000e> vlr_access_req_fsm.c:699 Process_Access_Request_VLR(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeeebb0]{PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
Tue Aug 20 13:24:21 2019 DVLR <000e> vlr_access_req_fsm.c:402 Process_Access_Request_VLR(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeeebb0]{PR_ARQ_S_INIT}: proc_arq_fsm_done(IMSI_UNKNOWN_IN_VLR)
Tue Aug 20 13:24:21 2019 DVLR <000e> vlr_access_req_fsm.c:103 Process_Access_Request_VLR(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeeebb0]{PR_ARQ_S_INIT}: State change to PR_ARQ_S_DONE (no timeout)
Tue Aug 20 13:24:21 2019 DVLR <000e> vlr_access_req_fsm.c:112 Process_Access_Request_VLR(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeeebb0]{PR_ARQ_S_DONE}: Process Access Request result: IMSI_UNKNOWN_IN_VLR
Tue Aug 20 13:24:21 2019 DMSC <0006> vlr_access_req_fsm.c:153 msc_a(TMSI-0x2009A35F:GERAN-A-1:PAGING_RESP)[0x55d7abeee920]{MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_CN_CLOSE
./start-msc_ext_mncc.sh: line 5: 8168 Segmentation fault sudo osmo-msc -c ./osmo-msc.cfg -M /tmp/bsc_mncc
</pre> OsmoGSMTester - Feature #3916 (Rejected): Format TTCN3 log output https://osmocom.org/issues/39162019-04-11T08:16:02Zdexter
<p>The TTCN3 log output that the tester currently delivers is not formatted. This means one has to format it manually before it can be viewed. Since we already do the logmerge directly on jenkins we could do the formatting as well. Then inspection of the build artifacts would be a lot easier.</p>
<p>Example commandline:<br />ttcn3_logformat ./MSC_Tests.TC_lu_and_mt_call.merged > formatted.log</p>
<p>(Attached one finds the two files from the example)</p> OsmoBTS - Feature #3530 (Rejected): merge PRIM_INFO_MEAS into PRIM_PH_DATA and PRIM_TCHhttps://osmocom.org/issues/35302018-09-06T16:07:43Zdexter
<p>At the moment measurement indication primitives are handed to the upper layers separately. However, they could also be integrated into the primitives that carry the actual data block to which the measurement belongs. This would make things a lot easier to handle and understand since whenever one gets data from the phy, there must be a measurement report attached to it.</p>
<p>When lookint into l1sap.c we can see that PRIM_INFO_MEAS is sent along in an PRIM_INFO_MEAS. PRIM_INFO_MEAS only carries the measurement indication and the time indication. We may decide to flatten this once the measurement indication is settled.</p>
<p>PRIM_PH_DATA and PRIM_TCH carry data and voice. Thats where we need to integrate the measurement indication.</p> OsmoBTS - Bug #3478 (Rejected): struct member lchan->meas.bts_tx_pwr is never sethttps://osmocom.org/issues/34782018-08-20T09:51:53Zdexter
<p>The struct member bts_tx_pwr is no where set, but it is included in the measurement report when rsl_tx_meas_res() in rsl.c runs. This is odd. It should have a value and since the transmit power of the BTS is fixed, this will a fixed value derived from the bts configuration. We also may check if RSL_IE_BS_POWER is optional and if it is optional we could just leave it out.</p> OsmoBTS - Bug #3299 (Closed): osmo_ortp: scheduler is not startedhttps://osmocom.org/issues/32992018-05-29T13:54:18Zdexter
<p>Apparently the scheduler of ortp is not started. Presumably older versions did not require starting the scheduler explicitly, so we just do not call the relevant function:</p>
<pre>
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(101600): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(80640): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(101760): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(80800): ERROR!
Tue May 29 15:41:00 2018 <0000> rsl.c:2633 (bts=0,trx=0,ts=3,ss=0) Handing RLL msg UNIT_DATA_IND from LAPDm to MEAS REP
Tue May 29 15:41:00 2018 <0000> rsl.c:2550 (bts=0,trx=0,ts=3,ss=0) chan_num:11 Tx MEAS RES valid(2), flags(03)
Tue May 29 15:41:00 2018 <0000> rsl.c:2568 (bts=0,trx=0,ts=3,ss=0) Send Meas RES: NUM:20, RXLEV_FULL:63, RXLEV_SUB:63, RXQUAL_FULL:0, RXQUAL_SUB:0, MS_PWR:56, UL_TA:0, L3_LEN:18, TimingOff:0
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(101920): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:0 Cannot use the scheduled mode: the scheduler is not started. Call ortp_scheduler_init() at the begginning of the application.Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:0 can't guess current timestamp because session is not scheduled.Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:145 osmo-ortp(16386): timestamp_jump, new TS 0, resyncing
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(80960): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:0 Cannot use the scheduled mode: the scheduler is not started. Call ortp_scheduler_init() at the begginning of the application.Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:0 can't guess current timestamp because session is not scheduled.Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:145 osmo-ortp(16384): timestamp_jump, new TS 0, resyncing
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(102080): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(81120): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(102240): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(81280): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(102400): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(81440): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(102560): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(81600): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(102720): ERROR!
Tue May 29 15:41:00 2018 <0015> trau/osmo_ortp.c:180 osmo_rtp_socket_poll(82080): ERROR!
</pre> OsmoBTS - Bug #3016 (Rejected): octphy: tx power can not be modifiedhttps://osmocom.org/issues/30162018-02-28T12:11:25Zdexter
<p>See osmo-bts-octphy:l1_oml.c:bts_model_change_power(). The function is not implemented. This makes changing the TX power impossible. Presumably only changes after BTS startup are affected. It should still be possible to configure a specific transceiver output power but it is not possible to change it on the fly. As a result - for example - ramping can not work.</p> OsmoBTS - Bug #3015 (Rejected): octphy: Mode Modify is currently not supported for Octasic PHYhttps://osmocom.org/issues/30152018-02-28T12:00:59Zdexter
<p>osmo-bts-octphy seems not to support MODE MODIFY.</p>
<p>See osmo-bts-octphy:l1_if.c:mph_info_req(). The call to l1if_rsl_mode_modify(lchan) is commented out.</p> OsmoBTS - Bug #2976 (Rejected): octphy: no response to assignment command on RSL levelhttps://osmocom.org/issues/29762018-02-21T17:34:25Zdexter
<p>osmo-bts-octphy seems not to work with pmaier/fsm3. I narrowed the cause down to a missing assignment complete on RSL level. We use the assignment complete response as input for the GSCON FSM (see bsc_api.c:handle_ass_compl()).</p>
<p>Attached one finds a trace that illustrates the expected behavior (normal_call_example.pcapng). After the Assignment Request on the A-Interface a channel activation happens and then we can see Assignment Command and shortly after Assignment Complete. In assignment_complete_missing.pcapng we again see the Assignment Request on the A-Interface. The channel activation on RSL and then the Assignment Command, but no Assignmnet complete. What then happins is that T10 times out and the assignment fails.</p>
<p>Actually the Assignment Command von the RSL side is a Data-Request. When I get the things right this message is not touched by the BTS. It goes straight to the mobile and the assignment complete comes from the mobile and passes straight into the BSC.</p> OsmoMGW - Bug #2598 (Closed): Choose correct interface/ip automaticallyhttps://osmocom.org/issues/25982017-10-27T11:11:27Zdexter
<p>If we run osmo-mgw in a setup where RTP streams are switched through more than a single interface will run into a problem. On startup osmo-mgw gets a statically configured IP-Address via VTY config. This IP-Address is returned with every MDCX/CRCX response. This works fine as long as all RTP traffic is passing through the configured interface.</p>
<p>In cases where a client tries to switch an RTP stream through an interface different from the configured one osmo-mgw still (binds to and) returns the IP-Address that has been configured. The problem is that the other side will then try to send the RTP traffic to that IP-Address, which will fail.</p>
<p>(e.g. osmo-mgw is bound to 1.1.1.1, 1.1.1.1 will be returned with any CRCX or MDCX, even when the remote host is connected through the interface with IP 2.2.2.2)</p>
<p>A solution could be to determine the correct interface/ip automatically. Then we could (bind to and) return the IP oft the interface that actually points to the remote side. All information we would need to do this is the IP-Address of the remote side. Then we can use osmo_sock_local_ip() from libosmocore to determine the IP we have to use.</p>
<p>However, this only works well if we can get the IP-Address of the remote side early with a CRCX message. Luckily this is the case with osmo-bsc, there we do a single phased connection assignment (one CRCX with remote IP, one response with local ip). For the other cases where we do not get the remote end IP with the CRCX we must fall back to the configured IP address.</p> libosmocore - Feature #2587 (Closed): helper function to finde the right interface for a remote I...https://osmocom.org/issues/25872017-10-20T14:48:40Zdexter
<p>In some cases (mgcp-gw) it is important to know the IP-Address of the interface through which a remote IP-Address is reachable. This can be done using the syscalls connect() and getsockname(). Implement a helper function in socket.c that takes a remote ip-address as input and outputs the coresponding local interface</p> OsmoSGSN - Bug #2585 (Closed): build osmo-sgsn without libosmo-sigtranhttps://osmocom.org/issues/25852017-10-20T09:25:33Zdexter
<p>In its current state osmo-sgen depends on libosmo-sigtran even if it is not build for IU (3G-Support).</p> OsmoMGW - Bug #2535 (Closed): improve mgcp client APIhttps://osmocom.org/issues/25352017-10-04T16:51:06Zdexter
<p>The API of the mgcp client is not very optimal designed and we should change it. The problem is that the set of functions that return the generated MGCP message take all parameters separately. MGCP messages have plenty of parameters and when we need one more parameter we have to add another function. I already did this a few times now.</p>
<p>The solution to the problem would be just one (or at least one per message type) generator function that takes a struct. Depending on how the struct is populated the parameter will be included in the MGCP message or not.</p> OsmoMGW - Bug #2521 (Rejected): reduce levels in struct mgcp_conn_rtp https://osmocom.org/issues/25212017-09-25T21:21:54Zdexter
<p>See struct mgcp_conn_rtp in mgcp_internal.h. Struct member struct mgcp_rtp_end end could be eliminated by putting all struct members from struct mgcp_rtp_end to struct mgcp_conn_rtp. This would reduce one level when dereferencing the pointers. The code would become more readable.</p> OpenBSC - Feature #2515 (Closed): integrate osmo-mgw in osmo-bschttps://osmocom.org/issues/25152017-09-18T15:48:17Zdexter
<p>osmo-mgw has reached a development state, where it makes sens to try out how it performs in a real life situation. Osmo-bsc seems like a good test target for that and requires mgcp features anyway to support handover. The complexity can be limited by leaving osmo-msc on the legacy mgcp, while performing changes only to osmo-bsc. When done, osmo-bsc should not behave any different on the A-Interface.</p> libosmo-sccp + libosmo-sigtran - Bug #2441 (Closed): chopped-off pointcodeshttps://osmocom.org/issues/24412017-08-15T11:16:54Zdexter
<p>It seems that that the pointcode data is chopped off when receiving unittdata.</p>
<p>When looking at the attached trace.pcapng file, one can see that the RESET<br />command is correctly transmitted, but the response, the RESET ACK is always<br />sent to the wrong destination address. (187 instead of 2235). When converting<br />those to numbers one can see that the addresses seem to be chopped off,<br />presumably at the 8th bit:</p>
<pre>
2235 = 100010111011
187 = 10111011
</pre>
<p>When investigating further it turns out that the pointcode is already chopped<br />off when the RESET is received:</p>
<pre>
Tue Aug 15 11:35:20 2017 <000a> a_iface.c:531 N-UNITDATA.ind(00 04 30 04 01 20 )
Tue Aug 15 11:35:20 2017 <000a> a_iface_bssap.c:184 Rx BSC UDT: 00 04 30 04 01 20
Tue Aug 15 11:35:20 2017 <000a> a_iface_bssap.c:157 Rx BSC UDT BSSMAP RESET
Tue Aug 15 11:35:20 2017 <000a> a_iface_bssap.c:110 Rx RESET from BSC RI=SSN_PC,PC=0.23.3,SSN=unknown 0xfe,GTI=NO_GT, sending RESET ACK
Tue Aug 15 11:35:20 2017 <000a> fsm.c:176 FSM RESET(FSM RESET INST)[0x55555589b7a0]{DISC}: Timeout of T0
Tue Aug 15 11:35:20 2017 <000a> a_reset.c:102 (RI=SSN_PC,PC=0.23.3,SSN=unknown 0xfe,GTI=NO_GT) reset-ack timeout (T0) in state ST_DISC (disconnected), resending...
Tue Aug 15 11:35:20 2017 <000a> a_iface.c:443 Sending RESET to BSC RI=SSN_PC,PC=0.23.3,SSN=unknown 0xfe,GTI=NO_GT
</pre>
<p>Presumably the upcoming primitive already contains the chopped pointcode.</p> libosmo-sccp + libosmo-sigtran - Feature #2417 (Closed): function to check sccp-addresseshttps://osmocom.org/issues/24172017-08-01T10:37:29Zdexter
<p>sccp-addresses consist of a number of components. A presence bitmask denotes if a component has a valid value or not. Add a function that checks the presence field against a given value. Also check if the values fall into the correct range, so that a malformed sccp-address is detected early.</p> OpenBSC - Feature #2396 (Closed): Comfortable CS7/SS7 VTY configuration for osmo-bsc and osmo-mschttps://osmocom.org/issues/23962017-07-24T19:54:54Zdexter
<p>osmo-msc and osmo-bsc currently lack important SS7 related VTY configuration features. Add proper VTY configuration commands to both applications</p> libosmo-sccp + libosmo-sigtran - Bug #2381 (Closed): function to automatically generate a local S...https://osmocom.org/issues/23812017-07-20T08:27:17Zdexter
<p>In some cases it would be very helpful if the user could just ask the sccp API for a local address. Such an address can be derived from the configuration data we have set for the CS7 instance via VTY.</p> libosmo-sccp + libosmo-sigtran - Bug #2375 (Closed): enforce unique names in sccp-addressbookhttps://osmocom.org/issues/23752017-07-19T07:56:56Zdexter
<p>Each sccp-addressbook entry is referenced by a name. Make sure that a name can not be used twice.</p>
<p>Inside a single ss7 instace, reusing a name twice is not possible, but a user could use the same name twice in different ss7 instance. This situation mus be prevented.</p> OsmoBTS - Bug #2349 (Closed): osmo-bts-octphy: segfault in l1_if.chttps://osmocom.org/issues/23492017-07-04T09:42:24Zdexter
<p>The current master of osmo-bts segfaults when used with osmo-bts-octphy:</p>
<pre>
Tue Jul 4 11:40:01 2017 <0006> l1_oml.c:385 (bts=0,trx=0,ts=2,ss=0) MPH-ACTIVATE.conf (FACCH/F RX_BTS_MS(UL))
Tue Jul 4 11:40:01 2017 <0006> l1_oml.c:250 (bts=0,trx=0,ts=2,ss=0): lchan2lch_par tch_mode=0x00
Tue Jul 4 11:40:01 2017 <0006> l1_oml.c:452 (bts=0,trx=0,ts=2,ss=0) MPH-ACTIVATE.req (SACCH TX_BTS_MS(DL))
Tue Jul 4 11:40:01 2017 <0006> l1_oml.c:385 (bts=0,trx=0,ts=2,ss=0) MPH-ACTIVATE.conf (SACCH TX_BTS_MS(DL))
Tue Jul 4 11:40:01 2017 <0006> l1_oml.c:250 (bts=0,trx=0,ts=2,ss=0): lchan2lch_par tch_mode=0x00
Tue Jul 4 11:40:01 2017 <0006> l1_oml.c:452 (bts=0,trx=0,ts=2,ss=0) MPH-ACTIVATE.req (SACCH RX_BTS_MS(UL))
Tue Jul 4 11:40:01 2017 <0006> l1_oml.c:385 (bts=0,trx=0,ts=2,ss=0) MPH-ACTIVATE.conf (SACCH RX_BTS_MS(UL))
Tue Jul 4 11:40:01 2017 <0007> l1sap.c:545 activate confirm chan_nr=0x0a trx=0
Tue Jul 4 11:40:01 2017 <0000> rsl.c:595 (bts=0,trx=0,ts=2,ss=0) Tx CHAN ACT ACK
Tue Jul 4 11:40:01 2017 <0006> l1_oml.c:859 (bts=0,trx=0,ts=2,ss=0) End of queue encountered. Now empty? 1
Tue Jul 4 11:40:01 2017 <0000> rsl.c:2386 (bts=0,trx=0,ts=0,ss=0) Rx RSL IMM_ASS_CMD
Program received signal SIGSEGV, Segmentation fault.
bts_model_l1sap_down (trx=trx@entry=0x7ffff7ef2070, l1sap=l1sap@entry=0x7fffffffdff0) at l1_if.c:705
705 rc = ph_tch_req(trx, msg, l1sap);
(gdb) bt
#0 bts_model_l1sap_down (trx=trx@entry=0x7ffff7ef2070, l1sap=l1sap@entry=0x7fffffffdff0) at l1_if.c:705
#1 0x000000000041f050 in l1sap_down (trx=trx@entry=0x7ffff7ef2070, l1sap=l1sap@entry=0x7fffffffdff0) at l1sap.c:1195
#2 0x000000000041fa4d in l1sap_tch_rts_ind (l1sap=<optimized out>, rts_ind=<optimized out>, rts_ind=<optimized out>, trx=0x7ffff7ef2070) at l1sap.c:863
#3 l1sap_up (trx=trx@entry=0x7ffff7ef2070, l1sap=<optimized out>) at l1sap.c:1160
#4 0x00000000004083d3 in handle_ph_rach_ind (fl1=<optimized out>, l1p_msg=<optimized out>, ra_ind=<optimized out>) at l1_if.c:1180
#5 rx_gsm_trx_rach_ind (msg=<optimized out>) at l1_if.c:1370
#6 rx_octvc1_notif (msg_id=<optimized out>, msg=<optimized out>) at l1_if.c:1408
#7 rx_octvc1_event_msg (msg=<optimized out>) at l1_if.c:1449
#8 rx_octvc1_data_f_msg (msg=<optimized out>) at l1_if.c:1557
#9 rx_octphy_msg (msg=<optimized out>) at l1_if.c:1609
#10 octphy_read_cb (ofd=<optimized out>) at l1_if.c:1661
#11 0x00007ffff79b99b3 in osmo_wqueue_bfd_cb (fd=0x6e8de8, what=1) at write_queue.c:49
#12 0x00007ffff79b5d9f in osmo_fd_disp_fds (_eset=0x7fffffffe2a0, _wset=0x7fffffffe220, _rset=0x7fffffffe1a0) at select.c:178
#13 osmo_select_main (polling=polling@entry=0) at select.c:218
#14 0x0000000000422365 in bts_main (argc=<optimized out>, argv=<optimized out>) at main.c:359
#15 0x00007ffff6756f45 in __libc_start_main (main=0x404700 <main>, argc=3, argv=0x7fffffffe498, init=<optimized out>, fini=<optimized out>, rtld_fini=<optimized out>, stack_end=0x7fffffffe488) at libc-start.c:287
#16 0x000000000040472e in _start ()
(gdb)
</pre> OsmoMSC - Bug #2348 (Closed): AoIP: garbled RTP in call following a call to an unreachable subscr...https://osmocom.org/issues/23482017-07-03T20:14:14Zdexter
<p>There is a problem somewhere in the call control, probably also connected to MGCP. The problem succours very rarely, but it can be provoked by doing the following:</p>
<p>1) Make a call to an unreachable subscriber</p>
<p>2) Make a call to a reachable subscriber</p>
<p>The other phone will ring. When picked up on the calling phone playing a metallic noise sound (garbeled RTP traffic).</p>
<p>The problem needs to be investigated further. It might be a race condition between the connection that is still open from the failed call and the new established connection.</p>
<hr />
<p>In a_iface.c, see a_iface_tx_dtap(), the link_id is permanently set to 0x00. Looks like a candidate for the cause of the problem.</p> OpenBSC - Bug #2347 (Closed): osmo-msc Make SSCP address configurablehttps://osmocom.org/issues/23472017-07-03T10:58:36Zdexter
<p>The SSCP-Address in osmo-msc is not configurable via VTY. Add the required VTY Commands</p> OpenBSC - Bug #2342 (Closed): fix MSC co located MGCPhttps://osmocom.org/issues/23422017-06-28T12:47:28Zdexter
<p>There is a problem with the handling of the MGCP on the MSC side. A crash/restart of the MSC might leave open MGCP endpoints. The endpoints will stay open and will be not available for new connections.</p>
<p>To get around problems after restarting the MSC we currently send a DLCX before we seize a new enpoint. This ensures that the endpoint is closed before we try to seize it. This works fine but is a spec violation. Normally a DLCX requires a connection identifier ("C"). Since our MGCP gateway ignores the connection identifier in a DLCX the current method work. However, it might fail when using an MGCP gateway from another vendor.</p>
<p>So sending a DLCX is not a good option becaus on restart we lost all information about the connection identifiers. Holger suggested to use an already existing "reset all endpoints" vendor extension in our MGCP. This would be a possible solution. However, it would reset everything at once. It would work, but this would certainly disturb possible other users of the MGCPGW. I do not know if this is even planned or required.</p> libosmo-sccp + libosmo-sigtran - Bug #2004 (Closed): Problem sending CR without datahttps://osmocom.org/issues/20042017-04-10T12:03:00Zdexter
<p>When attempting to send a connection response (CR) without any data using:</p>
<pre>
osmo_sccp_tx_conn_resp(scu, scu_prim->u.connect.conn_id,
&scu_prim->u.connect.called_addr,
NULL, 0);
</pre>
<p>Wireshark displays a malformed packet. To me it looks like if there is an optional parameter (the data part) announced, but the data is (of course) not there. However. CR seems to work fine when no data is attached.</p> pySim - Bug #1988 (Closed): problem changing the ICCID on sysmo-usim-sjs1https://osmocom.org/issues/19882017-03-20T17:10:57Zdexter
<p>When programming an sysmo-usim-sjs1, all parameters are written fine, except for the ICCID. There are no error messages printend. When checking the ICCID with the phone, the original ICCID is shown, regardless what is previously programmed.</p>
<pre>
$ ./mksim.sh
Insert card now (or CTRL-C to cancel)
Generated card parameters :
> Name : Magic
> SMSP : e1ffffffffffffffffffffffff0581005155f5ffffffffffff000000
> ICCID : 1234567890123456789
> MCC/MNC : 1/1
> IMSI : 001010000000006
> Ki : FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
> OPC : 79316551103BD050B7C8CDB0030C49F0
> ACC : None
Programming ...
Done !
</pre>
<p>Script used for programming:</p>
<pre>
#!/bin/bash
PYSIM=~/bin/pysim/pySim-prog.py
ADM1=14546485
OPC=79316551103BD050B7C8CDB0030C49F0
MCC=001
MNC=01
IMSI=001010000000006
KI=FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
ICCID=1234567890123456789
python $PYSIM -p 0 -t sysmoUSIM-SJS1 -o $OPC -k $KI -x $MCC -y $MNC -i $IMSI -s $ICCID -a $ADM1</pre> OsmoPCU - Bug #1957 (Closed): Fix calculation of absolute frame number from relative frame number...https://osmocom.org/issues/19572017-02-22T12:42:21Zdexter
<p>A RACH request that is forwarded to the PCU (usually) contains only a relative frame number. When a RACH request is processed by the BTS class, the absolute frame number is calculated from the relative framenumber. The current implementation lacks the handling of cornercases and race conditions.</p> OsmoPCU - Bug #1931 (Rejected): Downlink TBF allocation problem during PDP-Context Deact REQ on O...https://osmocom.org/issues/19312017-01-30T12:04:26Zdexter
<p>In some cases, primarily when a the MS requests to deactivate the PDP context a problem allocating a downlink TBF occurs:</p>
<p>The observation of the diag interface of the MS shows that that the MS is sending a PDP context deactivation request. The request reaches SGSN and the SGSN responds correctly with a PDP context deactivation ACK. The diag interface on the modem shows that the PDP context deactivation ACK is never received. The MS repeats the request 4 times until it finally gives up.</p>
<pre>
Thu Jan 19 11:27:19 2017 <0011> gprs_bssgp.c:379 BSSGP TLLI=0xd4d4ca34 Rx UPLINK-UNITDATA
Thu Jan 19 11:27:19 2017 <0012> gprs_llc_parse.c:82 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x20b16a CMD=UI DATA
Thu Jan 19 11:27:19 2017 <0002> gprs_gmm.c:2444 MM(001010000000001/d4d4ca34) -> DEACTIVATE PDP CONTEXT REQ (cause: Regular deactivation)
Thu Jan 19 11:27:19 2017 <000f> sgsn_libgtp.c:286 PDP(001010000000001/0) Delete PDP Context
Thu Jan 19 11:27:19 2017 <000f> sgsn_libgtp.c:613 PDP Context was deleted
Thu Jan 19 11:27:19 2017 <000f> sgsn_libgtp.c:590 libgtp cb_conf(type=20, cause=128, pdp=(nil), cbp=0x1a2e010)
Thu Jan 19 11:27:19 2017 <000f> sgsn_libgtp.c:513 PDP(001010000000001/0) Received DELETE PDP CTX CONF, cause=128(Request accepted)
Thu Jan 19 11:27:19 2017 <0013> gprs_sndcp.c:509 SNSM-DEACTIVATE.ind (lle=0x1a2be20, TLLI=d4d4ca34, SAPI=3, NSAPI=5)
Thu Jan 19 11:27:19 2017 <0002> gprs_gmm.c:2127 MM(001010000000001/d4d4ca34) <- DEACTIVATE PDP CONTEXT ACK
Thu Jan 19 11:27:24 2017 <0011> gprs_bssgp.c:797 BSSGP BVCI=23 Rx Flow Control BVC
Thu Jan 19 11:27:27 2017 <0011> gprs_bssgp.c:379 BSSGP TLLI=0xd4d4ca34 Rx UPLINK-UNITDATA
Thu Jan 19 11:27:27 2017 <0012> gprs_llc_parse.c:82 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xac2522 CMD=UI DATA
Thu Jan 19 11:27:27 2017 <0002> gprs_gmm.c:2444 MM(001010000000001/d4d4ca34) -> DEACTIVATE PDP CONTEXT REQ (cause: Regular deactivation)
Thu Jan 19 11:27:27 2017 <0002> gprs_gmm.c:2451 MM(001010000000001/d4d4ca34) Deactivate PDP Context Request for non-existing PDP Context (IMSI=001010000000001, TI=0)
Thu Jan 19 11:27:27 2017 <0002> gprs_gmm.c:2127 MM(001010000000001/d4d4ca34) <- DEACTIVATE PDP CONTEXT ACK
Thu Jan 19 11:27:30 2017 <000f> gprs_sgsn.c:870 Checking for inactive LLMEs, time = 126147
Thu Jan 19 11:27:34 2017 <0010> gprs_ns.c:528 NSEI=23 Tx NS ALIVE_ACK (NSVCI=23)
Thu Jan 19 11:27:34 2017 <0011> gprs_bssgp.c:797 BSSGP BVCI=23 Rx Flow Control BVC
Thu Jan 19 11:27:34 2017 <0010> gprs_ns.c:582 NSEI=23 Timer expired in mode tns-test (30 seconds)
Thu Jan 19 11:27:34 2017 <0010> gprs_ns.c:515 NSEI=23 Tx NS ALIVE (NSVCI=23)
Thu Jan 19 11:27:34 2017 <0010> gprs_ns.c:554 NSEI=23 Starting timer in mode tns-alive (3 seconds)
Thu Jan 19 11:27:34 2017 <0010> gprs_ns.c:554 NSEI=23 Starting timer in mode tns-test (30 seconds)
Thu Jan 19 11:27:35 2017 <0011> gprs_bssgp.c:379 BSSGP TLLI=0xd4d4ca34 Rx UPLINK-UNITDATA
Thu Jan 19 11:27:35 2017 <0012> gprs_llc_parse.c:82 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xb55300 CMD=UI DATA
Thu Jan 19 11:27:35 2017 <0002> gprs_gmm.c:2444 MM(001010000000001/d4d4ca34) -> DEACTIVATE PDP CONTEXT REQ (cause: Regular deactivation)
Thu Jan 19 11:27:35 2017 <0002> gprs_gmm.c:2451 MM(001010000000001/d4d4ca34) Deactivate PDP Context Request for non-existing PDP Context (IMSI=001010000000001, TI=0)
Thu Jan 19 11:27:35 2017 <0002> gprs_gmm.c:2127 MM(001010000000001/d4d4ca34) <- DEACTIVATE PDP CONTEXT ACK
Thu Jan 19 11:27:43 2017 <0011> gprs_bssgp.c:379 BSSGP TLLI=0xd4d4ca34 Rx UPLINK-UNITDATA
Thu Jan 19 11:27:43 2017 <0012> gprs_llc_parse.c:82 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x39c748 CMD=UI DATA
Thu Jan 19 11:27:43 2017 <0002> gprs_gmm.c:2444 MM(001010000000001/d4d4ca34) -> DEACTIVATE PDP CONTEXT REQ (cause: Regular deactivation)
Thu Jan 19 11:27:43 2017 <0002> gprs_gmm.c:2451 MM(001010000000001/d4d4ca34) Deactivate PDP Context Request for non-existing PDP Context (IMSI=001010000000001, TI=0)
Thu Jan 19 11:27:43 2017 <0002> gprs_gmm.c:2127 MM(001010000000001/d4d4ca34) <- DEACTIVATE PDP CONTEXT ACK
Thu Jan 19 11:27:44 2017 <0011> gprs_bssgp.c:797 BSSGP BVCI=23 Rx Flow Control BVC
Thu Jan 19 11:27:51 2017 <0011> gprs_bssgp.c:379 BSSGP TLLI=0xd4d4ca34 Rx UPLINK-UNITDATA
Thu Jan 19 11:27:51 2017 <0012> gprs_llc_parse.c:82 LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xf7702b CMD=UI DATA
Thu Jan 19 11:27:51 2017 <0002> gprs_gmm.c:2444 MM(001010000000001/d4d4ca34) -> DEACTIVATE PDP CONTEXT REQ (cause: Regular deactivation)
Thu Jan 19 11:27:51 2017 <0002> gprs_gmm.c:2451 MM(001010000000001/d4d4ca34) Deactivate PDP Context Request for non-existing PDP Context (IMSI=001010000000001, TI=0)
Thu Jan 19 11:27:51 2017 <0002> gprs_gmm.c:2127 MM(001010000000001/d4d4ca34) <- DEACTIVATE PDP CONTEXT ACK
Thu Jan 19 11:27:54 2017 <0011> gprs_bssgp.c:797 BSSGP BVCI=23 Rx Flow Control BVC
Thu Jan 19 11:28:00 2017 <000f> gprs_sgsn.c:870 Checking for inactive LLMEs, time = 126177
Thu Jan 19 11:28:04 2017 <0010> gprs_ns.c:528 NSEI=23 Tx NS ALIVE_ACK (NSVCI=23)
Thu Jan 19 11:28:04 2017 <0011> gprs_bssgp.c:797 BSSGP BVCI=23 Rx Flow Control BVC
Thu Jan 19 11:28:04 2017 <0010> gprs_ns.c:582 NSEI=23 Timer expired in mode tns-test (30 seconds)
</pre>
<p>By the diag log we see that the downlink assignment message, the PCU is sending is missing, while the log of the PCU confirms that the downlink assignment message is sent:</p>
<pre>
Thu Jan 19 11:27:51 2017 <0001> pcu_l1_if.cpp:350 RACH request received: sapi=1 qta=0, ra=123, fn=82125
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:562 MS requests UL TBF on RACH, so we provide one
ra=0x7b Fn=82125 qta=0 is_11bit=0:
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:708 pcu has not received burst type from bts
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:694 ********** TBF starts here **********
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:696 Allocating UL TBF: MS_CLASS=0/0
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:115 Creating MS object, TLLI = 0x00000000
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:454 Searching for first unallocated TFI: TRX=0
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:464 Found TFI=0.
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:525 Slot Allocation (Algorithm B) for unknown class (assuming 12)
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:560 - Rx=4 Tx=4 Sum Rx+Tx=5 Tta=2 Ttb=1 Tra=2 Trb=1 Type=1
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 0, because not enabled
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 1, because not enabled
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 2, because not enabled
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 3, because not enabled
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 4, because not enabled
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 5, because not enabled
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:157 - Skipping TS 6, because not enabled
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:579 - Possible DL/UL slots: (TS=0)".......C"(TS=7)
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:940 - Selected UL slots: (TS=0)".......U"(TS=7), single
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:966 Using single slot at TS 7 for UL
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:990 - Reserved DL/UL slots: (TS=0)".......C"(TS=7)
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:1017 - Assigning UL TS 7
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:1681 PDCH(TS 7, TRX 0): Attaching TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL), 1 TBFs, USFs = 01, TFIs = 00000001.
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:399 - Setting Control TS 7
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:268 Attaching TBF to MS object, TLLI = 0x00000000, TBF = TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL)
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:647 Allocated TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL): trx = 0, ul_slots = 80, dl_slots = 00
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:471 Modifying MS object, TLLI = 0x00000000, TA 220 -> 0
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:294 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=NULL) changes state from NULL to FLOW
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:422 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) starting timer 3169.
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:611 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) [UPLINK] START
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:617 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) RX: [PCU <- BTS] RACH qbit-ta=0 ra=0x7b, Fn=82125 (29,15,17)
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:620 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) TX: START Immediate Assignment Uplink (AGCH)
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:640 - TRX=0 (866) TS=7 TA=0 TSC=7 TFI=0 USF=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82134 block_nr=6 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82138 block_nr=7 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82142 block_nr=8 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82147 block_nr=9 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82151 block_nr=10 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82155 block_nr=11 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82160 block_nr=0 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82164 block_nr=1 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82168 block_nr=2 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82173 block_nr=3 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82177 block_nr=4 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82181 block_nr=5 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:109 Received RTS for PDCH: TRX=0 TS=7 FN=82186 block_nr=6 scheduling USF=0 for required uplink resource of UL TFI=0
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:426 TBF(TFI=0 TLLI=0x00000000 DIR=UL STATE=FLOW) restarting timer 3169 while old timer 3169 pending
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:457 Modifying MS object, TLLI = 0x00000000, IMSI '' -> '001010000000001'
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:361 Clearing MS object, TLLI: 0xd4d4ca34, IMSI: '001010000000001'
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:396 Modifying MS object, UL TLLI: 0x00000000 -> 0xd4d4ca34, not yet confirmed
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:141 Destroying MS object, TLLI = 0x00000000
Thu Jan 19 11:27:51 2017 <0009> tbf_ul.cpp:373 LLC [PCU -> SGSN] TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FLOW) len=10
Thu Jan 19 11:27:51 2017 <0002> tbf_ul.cpp:294 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FLOW) changes state from FLOW to FINISHED
Thu Jan 19 11:27:51 2017 <0009> gprs_bssgp_pcu.cpp:180 LLC [SGSN -> PCU] = TLLI: 0xd4d4ca34 IMSI: 001010000000001 len: 8
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:775 ********** TBF starts here **********
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:777 Allocating DL TBF: MS_CLASS=0/0
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:454 Searching for first unallocated TFI: TRX=0
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:464 Found TFI=0.
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:902 - Selected DL slots: (TS=0)".......D"(TS=7), single
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:966 Using single slot at TS 7 for DL
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac_ts_alloc.cpp:1004 - Assigning DL TS 7
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:1681 PDCH(TS 7, TRX 0): Attaching TBF(TFI=0 TLLI=0x00000000 DIR=DL STATE=NULL), 1 TBFs, USFs = 01, TFIs = 00000001.
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:399 - Setting Control TS 7
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:287 Attaching TBF to MS object, TLLI = 0xd4d4ca34, TBF = TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=NULL)
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:647 Allocated TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=NULL): trx = 0, ul_slots = 80, dl_slots = 80
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:430 Modifying MS object, TLLI: 0xd4d4ca34 confirmed
Thu Jan 19 11:27:51 2017 <0002> tbf_dl.cpp:161 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=NULL) [DOWNLINK] START
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:749 Send dowlink assignment for TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=NULL) on PCH, no TBF exist (IMSI=001010000000001)
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:294 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=NULL) changes state from NULL to ASSIGN
Thu Jan 19 11:27:51 2017 <0002> gprs_rlcmac.cpp:35 TX: [PCU -> BTS] Paging Request (CCCH)
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:770 TX: START TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) Immediate Assignment Downlink (PCH)
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:777 - TRX=0 (866) TS=7 TA=0 pollFN=-1
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:422 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) starting timer 0.
Thu Jan 19 11:27:51 2017 <0002> tbf_dl.cpp:90 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) append
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:487 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED): Scheduling polling at FN 82203 TS 7
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:181 Scheduling control message at RTS for TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED) (TRX=0, TS=7)
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:426 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED) restarting timer 3169 while old timer 3169 pending
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:426 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED) restarting timer 3169 while old timer 3169 pending
Thu Jan 19 11:27:51 2017 <0006> gprs_rlcmac_sched.cpp:329 Received RTS for PDCH: TRX=0 TS=7 FN=82199 block_nr=9 scheduling free USF for polling at FN=82203 of TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED)
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:1448 +++++++++++++++++++++++++ RX : Uplink Control Block +++++++++++++++++++++++++
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:1451 ------------------------- RX : Uplink Control Block -------------------------
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:955 RX: [PCU <- BTS] TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED) Packet Control Ack
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:960 TBF: [UPLINK] END TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED)
Thu Jan 19 11:27:51 2017 <0007> gprs_rlcmac_meas.cpp:104 UL RSSI of TLLI=0xd4d4ca34: -103 dBm
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:342 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED) free
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:447 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED) stopping timer 3169.
Thu Jan 19 11:27:51 2017 <0002> bts.cpp:1701 PDCH(TS 7, TRX 0): Detaching TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED), 0 TBFs, USFs = 00, TFIs = 00000000.
Thu Jan 19 11:27:51 2017 <0002> gprs_ms.cpp:324 Detaching TBF from MS object, TLLI = 0xd4d4ca34, TBF = TBF(TFI=0 TLLI=0xd4d4ca34 DIR=UL STATE=FINISHED)
Thu Jan 19 11:27:51 2017 <0002> tbf.cpp:363 ********** TBF ends here **********
Thu Jan 19 11:27:53 2017 <0002> tbf.cpp:829 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) timer 0 expired.
Thu Jan 19 11:27:53 2017 <0002> tbf.cpp:372 ********** TBF update **********
Thu Jan 19 11:27:53 2017 <0002> bts.cpp:1701 PDCH(TS 7, TRX 0): Detaching TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN), 0 TBFs, USFs = 00, TFIs = 00000000.
Thu Jan 19 11:27:53 2017 <0002> bts.cpp:454 Searching for first unallocated TFI: TRX=0
Thu Jan 19 11:27:53 2017 <0002> bts.cpp:464 Found TFI=0.
Thu Jan 19 11:27:53 2017 <0002> gprs_rlcmac_ts_alloc.cpp:902 - Selected DL slots: (TS=0)".......D"(TS=7)
Thu Jan 19 11:27:53 2017 <0002> gprs_rlcmac_ts_alloc.cpp:970 Using 1 slots for DL
Thu Jan 19 11:27:53 2017 <0002> gprs_rlcmac_ts_alloc.cpp:1004 - Assigning DL TS 7
Thu Jan 19 11:27:53 2017 <0002> bts.cpp:1681 PDCH(TS 7, TRX 0): Attaching TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN), 1 TBFs, USFs = 00, TFIs = 00000001.
Thu Jan 19 11:27:53 2017 <0002> bts.cpp:736 Send dowlink assignment on PACCH, because TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) exists
Thu Jan 19 11:27:53 2017 <0002> bts.cpp:294 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) changes state from ASSIGN to ASSIGN
Thu Jan 19 11:27:53 2017 <0002> tbf.cpp:422 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) starting timer 0.
Thu Jan 19 11:27:53 2017 <0002> tbf.cpp:985 New and old TBF are the same TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:53 2017 <0002> tbf.cpp:1007 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) start Packet Downlink Assignment (PACCH)
Thu Jan 19 11:27:53 2017 <0002> tbf.cpp:1014 +++++++++++++++++++++++++ TX : Packet Downlink Assignment +++++++++++++++++++++++++
Thu Jan 19 11:27:53 2017 <0002> tbf.cpp:1017 ------------------------- TX : Packet Downlink Assignment -------------------------
Thu Jan 19 11:27:53 2017 <0002> tbf.cpp:487 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN): Scheduling polling at FN 82636 TS 7
Thu Jan 19 11:27:53 2017 <0006> gprs_rlcmac_sched.cpp:181 Scheduling control message at RTS for TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) (TRX=0, TS=7)
Thu Jan 19 11:27:53 2017 <0006> gprs_rlcmac_sched.cpp:329 Received RTS for PDCH: TRX=0 TS=7 FN=82632 block_nr=1 scheduling free USF for polling at FN=82636 of TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:498 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) poll timeout for FN=82636, TS=7 (curr FN 82697)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:553 - Timeout for polling PACKET CONTROL ACK for PACKET DOWNLINK ASSIGNMENT.
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:914 - Assignment was on PACCH
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:922 - No downlink ACK received yet
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:985 New and old TBF are the same TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:1007 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) start Packet Downlink Assignment (PACCH)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:1014 +++++++++++++++++++++++++ TX : Packet Downlink Assignment +++++++++++++++++++++++++
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:1017 ------------------------- TX : Packet Downlink Assignment -------------------------
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:487 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN): Scheduling polling at FN 82710 TS 7
Thu Jan 19 11:27:54 2017 <0006> gprs_rlcmac_sched.cpp:181 Scheduling control message at RTS for TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) (TRX=0, TS=7)
Thu Jan 19 11:27:54 2017 <0006> gprs_rlcmac_sched.cpp:329 Received RTS for PDCH: TRX=0 TS=7 FN=82706 block_nr=6 scheduling free USF for polling at FN=82710 of TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:498 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) poll timeout for FN=82710, TS=7 (curr FN 82771)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:985 New and old TBF are the same TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:1007 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) start Packet Downlink Assignment (PACCH)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:1014 +++++++++++++++++++++++++ TX : Packet Downlink Assignment +++++++++++++++++++++++++
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:1017 ------------------------- TX : Packet Downlink Assignment -------------------------
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:487 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN): Scheduling polling at FN 82784 TS 7
Thu Jan 19 11:27:54 2017 <0006> gprs_rlcmac_sched.cpp:181 Scheduling control message at RTS for TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) (TRX=0, TS=7)
Thu Jan 19 11:27:54 2017 <0006> gprs_rlcmac_sched.cpp:329 Received RTS for PDCH: TRX=0 TS=7 FN=82779 block_nr=11 scheduling free USF for polling at FN=82784 of TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:498 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) poll timeout for FN=82784, TS=7 (curr FN 82849)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:985 New and old TBF are the same TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:1007 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) start Packet Downlink Assignment (PACCH)
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:1014 +++++++++++++++++++++++++ TX : Packet Downlink Assignment +++++++++++++++++++++++++
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:1017 ------------------------- TX : Packet Downlink Assignment -------------------------
Thu Jan 19 11:27:54 2017 <0002> tbf.cpp:487 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN): Scheduling polling at FN 82862 TS 7
Thu Jan 19 11:27:54 2017 <0006> gprs_rlcmac_sched.cpp:181 Scheduling control message at RTS for TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) (TRX=0, TS=7)
Thu Jan 19 11:27:54 2017 <0006> gprs_rlcmac_sched.cpp:329 Received RTS for PDCH: TRX=0 TS=7 FN=82857 block_nr=5 scheduling free USF for polling at FN=82862 of TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:498 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) poll timeout for FN=82862, TS=7 (curr FN 82927)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:985 New and old TBF are the same TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:1007 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) start Packet Downlink Assignment (PACCH)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:1014 +++++++++++++++++++++++++ TX : Packet Downlink Assignment +++++++++++++++++++++++++
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:1017 ------------------------- TX : Packet Downlink Assignment -------------------------
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:487 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN): Scheduling polling at FN 82940 TS 7
Thu Jan 19 11:27:55 2017 <0006> gprs_rlcmac_sched.cpp:181 Scheduling control message at RTS for TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) (TRX=0, TS=7)
Thu Jan 19 11:27:55 2017 <0006> gprs_rlcmac_sched.cpp:329 Received RTS for PDCH: TRX=0 TS=7 FN=82935 block_nr=11 scheduling free USF for polling at FN=82940 of TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:498 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) poll timeout for FN=82940, TS=7 (curr FN 83005)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:985 New and old TBF are the same TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:1007 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) start Packet Downlink Assignment (PACCH)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:1014 +++++++++++++++++++++++++ TX : Packet Downlink Assignment +++++++++++++++++++++++++
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:1017 ------------------------- TX : Packet Downlink Assignment -------------------------
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:487 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN): Scheduling polling at FN 83018 TS 7
Thu Jan 19 11:27:55 2017 <0006> gprs_rlcmac_sched.cpp:181 Scheduling control message at RTS for TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) (TRX=0, TS=7)
Thu Jan 19 11:27:55 2017 <0006> gprs_rlcmac_sched.cpp:329 Received RTS for PDCH: TRX=0 TS=7 FN=83013 block_nr=5 scheduling free USF for polling at FN=83018 of TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:829 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) timer 0 expired.
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:838 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) releasing due to PACCH assignment timeout.
Thu Jan 19 11:27:55 2017 <0002> tbf_dl.cpp:294 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=ASSIGN) changes state from ASSIGN to RELEASING
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:342 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=RELEASING) free
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:354 TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=RELEASING) Software error: Pending downlink assignment. This may not happen, because the assignment message never gets transmitted. Please be sure not to free in this state. PLEASE FIX!
Thu Jan 19 11:27:55 2017 <0002> bts.cpp:1701 PDCH(TS 7, TRX 0): Detaching TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=RELEASING), 0 TBFs, USFs = 00, TFIs = 00000000.
Thu Jan 19 11:27:55 2017 <0002> gprs_ms.cpp:324 Detaching TBF from MS object, TLLI = 0xd4d4ca34, TBF = TBF(TFI=0 TLLI=0xd4d4ca34 DIR=DL STATE=RELEASING)
Thu Jan 19 11:27:55 2017 <0002> tbf.cpp:363 ********** TBF ends here **********
</pre>
<p>With some experimentation we found out that after changing "gprs mode gprs" to "gprs mode egprs" in nitb.cfg the problem seems to dissappear, this leads to the assumption that it is a timing problem between osmo-bts and osmo-pcu.</p>
<p>The problem has been observerd with an OCTBTS/Octphy, see also traces, logs and configuration files in the attachment.</p> OsmoBTS - Bug #1895 (Closed): Multiple bts and gsm core network instances on the same machinehttps://osmocom.org/issues/18952016-12-21T12:14:38Zdexter
<p>In most of our software modules we offer flexibility to rename sockets and to bind services on different ip addresses, while some of the modules still have hardcoded socket names or similar limitations. In this task we want to setup two independed core networks on a single machine for test to rule out remaining limitations.</p>
<p>Things that should not be a problem:<br />GGSN: would simply bind on a different lo address (GGSN <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a> on 127.0.0.1, GGSN <a class="issue tracker-1 status-5 priority-3 priority-high3 closed" title="Bug: Fix / finish the paging code (Closed)" href="https://osmocom.org/issues/2">#2</a> 127.0.0.3)<br />SGSN: Connection from SGSN <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a> to GGSN <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a>: gtp local-ip 127.0.0.2; ggsn 0 remote-ip 127.0.0.1<br />SGSN: Connection from SGSN <a class="issue tracker-1 status-5 priority-3 priority-high3 closed" title="Bug: Fix / finish the paging code (Closed)" href="https://osmocom.org/issues/2">#2</a> to GGSN <a class="issue tracker-1 status-5 priority-3 priority-high3 closed" title="Bug: Fix / finish the paging code (Closed)" href="https://osmocom.org/issues/2">#2</a>: gtp local-ip 127.0.0.4; ggsn 0 remote-ip 127.0.0.3<br />SGSN: Connection from PCU <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a> to SGSN <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a>: encapsulation udp local-port 23000<br />SGSN: Connection from PCU <a class="issue tracker-1 status-5 priority-3 priority-high3 closed" title="Bug: Fix / finish the paging code (Closed)" href="https://osmocom.org/issues/2">#2</a> to SGSN <a class="issue tracker-1 status-5 priority-3 priority-high3 closed" title="Bug: Fix / finish the paging code (Closed)" href="https://osmocom.org/issues/2">#2</a>: encapsulation udp local-port 23001<br />PCU: Connection from PCU <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a> to SGSN <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a>: gprs nsvc 0 local udp port 23100; gprs nsvc 0 remote udp port 23000<br />PCU: Connection from PCU <a class="issue tracker-1 status-5 priority-3 priority-high3 closed" title="Bug: Fix / finish the paging code (Closed)" href="https://osmocom.org/issues/2">#2</a> to SGSN <a class="issue tracker-1 status-5 priority-3 priority-high3 closed" title="Bug: Fix / finish the paging code (Closed)" href="https://osmocom.org/issues/2">#2</a>: gprs nsvc 0 local udp port 23101; gprs nsvc 0 remote udp port 23001<br />BSC: MNCC socket paths of the BSC can be renamed via command line option but this should be migrated to a VTY option if possible.<br />BTS: Socket path can be changed via VTY<br />VTY/Control interfaces of all components can be bind to a different address</p>
<p>Things that are a problem:<br />BTS: Connection from BTS <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a> to BSC <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a>: oml remote-ip 127.0.0.1<br />BTS: Connection from BTS <a class="issue tracker-2 status-5 priority-5 priority-highest closed" title="Feature: port Dieter's windows code to mISDN (Closed)" href="https://osmocom.org/issues/1">#1</a> to BSC <a class="issue tracker-1 status-5 priority-3 priority-high3 closed" title="Bug: Fix / finish the paging code (Closed)" href="https://osmocom.org/issues/2">#2</a>: oml remote-ip 127.0.0.1 > Clash!<br />Check if there is an option to change the abis over ip port for both, BTS and BSC</p>
<p>PCU: Connection to BTS: hardcoded socket path > Clash!<br />Make a VTY option to change the socket path!</p>
<p>(When done we probably should look out for more of such limitations. For new features we should always ensure that there are VTY commands to change socket names, ports and ip addresses, devices etc...)</p>