Open Source Mobile Communications: Issueshttps://osmocom.org/https://osmocom.org/favicon.ico?16647414092018-01-08T20:43:22ZOpen Source Mobile Communications
Redmine OsmoMSC - Feature #2822 (Closed): work out MSC test scenarioshttps://osmocom.org/issues/28222018-01-08T20:43:22Zdexter
<p>We plan to test osmo-msc with TTCN3 as well. In Preparation of this we have to collect Ideas for test-cases.</p> OsmoMSC - Bug #2745 (Resolved): UMTS ciphering on GSM does not work when both 2G and 3G keys are ...https://osmocom.org/issues/27452017-12-13T16:20:57Zdexter
<p>When populating the hlr database with 3G keys (auc_3g), then 2G authentication stops working, while 3G authentication on nano3G still works fine. When the 3G keys are removed, then 2G authentication works again. At the moment we suspect that osmo-msc trys to perform 3G authentication on 2G (specified) and fails because it does not work.</p>
<p>In the attached archive one finds a trace from the connection between MSC and BSC and the complete network configuration, hlr database logs and start scripts.</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> OsmoMGW - Bug #2454 (Closed): Restructure existing codehttps://osmocom.org/issues/24542017-08-21T08:20:30Zdexter
<p>Large parts of the existing code (e.g. the protocol parser) can be reused. We need to identify the re-usable parts and structure them into modules, we also need tests for this modules.</p>
<p>mgcp_protocol.c seems to be a good starting point.</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> OpenBSC - Bug #2424 (Closed): use osmo_sccp_addr_set_ssn() in osmo-bschttps://osmocom.org/issues/24242017-08-07T15:14:20Zdexter
<p><a class="external" href="https://gerrit.osmocom.org/#/c/3359/">https://gerrit.osmocom.org/#/c/3359/</a> is still in review, use the provided function osmo_sccp_addr_set_ssn() as soon as it is merged.</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 #2376 (Closed): search for an sscp-address and return ss7 in...https://osmocom.org/issues/23762017-07-19T08:09:09Zdexter
<p>By the current API definition, the user can limit the search range to a specific ss7 instance (if that instance is known). When the given ss7 instance pointer is NULL, the global list is searched. This scheme will be changed to:</p>
<p>When searching, always the global list is searched. If found, the address will be copied to the pointer which the caller has issued. We will also return the pointer to the ss7 instance. A returned NULL pointer indicates that the entry was not found. By creating a copy of the addressbook entry we also prevent that the caller might change the address data in the addressbook. The only legal way to change addressbook entries is the VTY.</p>
<p>The API should look like this:</p>
<p>struct osmo_ss7_instance *osmo_sccp_addr_by_name(const char *name, struct osmo_sccp_addr *dest);</p>
<p>const char *osmo_sccp_name_by_addr(const struct osmo_sccp_addr *addr);</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> libosmo-sccp + libosmo-sigtran - Bug #2351 (Closed): unify sccp instance configurationhttps://osmocom.org/issues/23512017-07-06T09:41:55Zdexter
<p>The configuration of the sccp instance can be unified. The parameters of osmo_sccp_simple_client() which is used to create the sccp instance could be configured using the built in VTY of libosmo-sccp. This would unify the configuration of SCCP and offload the application specific VTY. It also would help to simplify the configuration from the users perspective. The user will not have to learn a new way to configur SS7 applications all the time.</p>
<pre>
struct osmo_sccp_instance *
osmo_sccp_simple_client(void *ctx, const char *name, uint32_t pc,
enum osmo_ss7_asp_protocol prot, int local_port,
const char *local_ip, int remote_port, const char *remote_ip);
</pre> 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> libosmo-sccp + libosmo-sigtran - Bug #2345 (Closed): VTY: Check returncode of osmo_ss7_pointcode_...https://osmocom.org/issues/23452017-06-30T13:50:30Zdexter
<p>The function osmo_ss7_pointcode_parse() may yield -EINVAL as result. Make sure that if this is the case, the result will not be used and a CMD_WARNING is returned.</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 #2328 (Closed): sccp-address nodeshttps://osmocom.org/issues/23282017-06-15T10:04:54Zdexter
<p>the SCCP level addresses. Unfortunately a SCC (called|calling) party<br />address is rather complex and hence it cannot easily be supplied by a<br />single VTY command. My idea is thus to add 'sccp-address' nodes,<br />where one can specify a SCCP address like this:</p>
<pre><code>sccp-address my-bsc-addr<br /> routing-indicator 1<br /> point-code 2.3.1<br /> subsystem-number 253<br /> global-title<br /> gti 4<br /> digits 2342<br /> ...</code></pre>
<p>The above vty code would become part of libosmo-sigtran, and the<br />BSC/MSC then can simply have a single vty line like<br />"bsc sccp-address my-bsc-addr" <br />and then call a function that resolves this address by it's<br />string-name "my-bsc-addr"</p> libosmo-sccp + libosmo-sigtran - Bug #2259 (New): problem with local referencehttps://osmocom.org/issues/22592017-05-16T13:05:55Zdexter
<p>When a connection attemt (local reference = 2) is made, libosmo-sccp complains with "<000d> sccp_scoc.c:1528 Cannot find connection for local reference 2". Current master is at 872c6b2a8e309ca6739ef295f1fc468c189e4ec9. The problem seems to be introduced with commit 5527df78adc08b76df07c4b682263b5bdd6181d4 (libosmo-sccp.git). When the commit is reverted, the correct functionality of libosmo-sccp seems to be restored.</p>
<p>The problem can be demonstrated by using the client/server example that is shipped with libosmo-sccp. The following VTY-Commands were used to trigger the problem:</p>
<pre>
enable
sccp-user
called-addr-ssn 202
connect-req 2 hello
</pre>
<p>Note: In the good case I can see the normal CR with payload attached and the CC that with the payload that is echoed by the the echo subsystem. The bad case results in an CREF message, which contains an refusal cause code 0x0F (unqalified)</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> libosmo-sccp + libosmo-sigtran - Bug #1995 (Closed): Segfault when callin osmo_sccp_tx_unitdata()...https://osmocom.org/issues/19952017-04-07T08:44:51Zdexter
<p>The problem occurs when a peer (server role) tries to send unitdata to a remote peer (client) that is not connected.</p>
<p>The attached code contains the source code snippets I used for experimentation.</p>
<p>Good case:<br />fist start dummy_msc (client), then dummy_bsc (server). The client will connect and when the timer at the server expires unitdata is sent from the server to the client.</p>
<p>Bad case:<br />start dummy_bsc, when the timer expires, the segfault occurs.</p>
<p>Note: The scheme is a bit odd and not covered by the examples, since there, the server never actively sends data without being stimulated through an existing connection. It is questionable if a server should send unsolicited data at all. In this test, the server role has been chosen for one side because of the lack of an STP.</p> pySim - Bug #1989 (Closed): SMSC not set for sysmo-usim-sjs1https://osmocom.org/issues/19892017-03-27T07:55:36Zdexter
<p>When setting the SMSC using pysim, it stays at its original value. Possibly setting of the SMSC value is not implemented for sysmo-usim-sjs1.</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> OsmoSGSN - Bug #1972 (Closed): show online-help outputs generates multiple commandshttps://osmocom.org/issues/19722017-03-08T12:43:20Zdexter
<p>The attached file was generated using the "show online-help" vty command in osmo-sgsn. Some of the commands show up 3 times on different nodes.</p>
For example:
<ul>
<li>"<command id='logging level (all| ..." shows up in node 1, 3 and 7</li>
<li>"<command id='show mm-context all [pdp]'>" shows up in node 1 and 3</li>
<li>"<command id='write file'>" shows up in node 3, 4, 7, 8, 9, 12, 13, 26</li>
<li>"<command id='no compression v42bis'> shows up once at node 26 (as expected)</li>
</ul> 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 #1932 (Closed): vty command write corrupts config file for osmo-bts-octphyhttps://osmocom.org/issues/19322017-01-30T14:19:12Zdexter
<p>After issuing the write command from the VTY console, osmo-bts-octphy will refuse to start due to unparsable config file:</p>
<pre>
((*))
|
/ \ OsmoBTS
<0006> l1_if.c:765 model_init()
There is no such command.
Error occurred during reading below line:
netdev eth4
Failed to parse the config file: 'osmo-bts.cfg'
</pre> 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> OsmoSGSN - Bug #1882 (Closed): xid is not re-transmitted on pdp-ctx-ack retransmissionhttps://osmocom.org/issues/18822016-12-19T12:20:18Zdexter
<p>When a pdp-context is established, usually a xid is sent. This works the first time, but when the pdp-ctx-ack is retransmitted from do_act_pdp_req() in gprs_gmm.c the xid negotiation should also be repeated.</p> OpenBSC - Bug #1779 (Closed): Problems with automatic subscriber creation.https://osmocom.org/issues/17792016-07-21T22:50:12Zdexter
<p>Maybe this is a problem with my configuration, but after long time I tried the automatic subscriber creation mode again. It constantly rejects the location update with the cause that no subscriber could be found. I traced the problem down to gsm_04_08.c:static bool subscr_regexp_check(). There the regexec() seems to fail.</p>
<p>The VTY show s the following:</p>
<p>OpenBSC> show network <br />BSC is on Country Code 1, Network Code 1 and has 1 BTS<br /> Long network name: 'Vaderfone'<br /> Short network name: 'Vaderfone'<br /> Authentication policy: accept-all, authorized regexp: *<br /> Auto create subscriber: yes<br /> Auto assign extension: yes<br /> Location updating reject cause: 13<br /> Encryption: A5/0<br /> NECI (TCH/H): 1<br /> Use TCH for Paging any: 0<br /> RRLP Mode: none<br /> MM Info: On<br /> Handover: Off<br /> Current Channel Load:<br /> Last RF Command: <br /> Last RF Lock Command:</p>
<p>If i get things right, "authorized regexp: *" should be ok with any IMSI. When I hardcode the returnvalue of subscr_regexp_check() to true it accepts any IMSI again and happyly creates the subscriber.</p>