mobile - making a call

eisencah eisenach wbg_1000 at yahoo.com
Tue May 3 05:39:23 UTC 2011


So is this normal at the current stage of the project or there's
something wrong with my setup ? (and all the calls where suppose to
advance to the alert phase)
Cheers.

--- On Mon, 5/2/11, eisencah eisenach <wbg_1000 at yahoo.com> wrote:

From: eisencah eisenach <wbg_1000 at yahoo.com>
Subject: mobile - making a call
To: baseband-devel at lists.osmocom.org
Date: Monday, May 2, 2011, 1:17 PM

Hi.
I'm using mobile application (sylvain/testing branch) to initiate calls. My experience is that the call si succesfull in about 20% of cases. Most of the time the connection seems to be lost after receiving the AUTHENTICATION REQUEST message. Has anyone else had this experience, it has to do with the power measurements above?
Thanks,
Mihai.


[0;m[1;33m<0005> gsm48_cc.c:496 Sending MMCC_EST_REQ
[0;m[1;32m<0004> gsm48_mm.c:3568 (ms 1) Received 'MMCC_EST_REQ' event in state MM idle
[0;m[1;32m<0004> gsm48_mm.c:3571 -> substate normal service
[0;m[1;32m<0004> gsm48_mm.c:3573 -> callref 1, transaction_id 255
[0;m[1;32m<0004> gsm48_mm.c:2873 Init MM Connection.
[0;m[1;32m<0004> gsm48_mm.c:1332 New MM Connection (proto 0x03 trans_id 255 ref 1)
[0;m[1;32m<0004>
 gsm48_mm.c:1291 (ref 1) new state IDLE -> CONN_PEND
[0;m[1;32m<0004> gsm48_mm.c:2650 CM SERVICE REQUEST (cause 9)
[0;m[1;32m<0004> gsm48_mm.c:2681 -> Using TMSI
[0;m[1;34m<0001> gsm48_rr.c:4904 (ms 1) Message 'RR_EST_REQ' received in state idle
[0;m[1;37m<000d> gsm48_rr.c:1181 Establish radio link due to mobility management request
[0;m[34m<0003> gsm322.c:3319 (ms 1) Event 'EVENT_LEAVE_IDLE' for Cell selection in state 'C3 camped normally'
[0;m[34m<0003> gsm322.c:2959 Going to camping frequency 47.
[0;m[34m<0003> gsm322.c:252 Sync to ARFCN=47 rxlev=-61 (Sysinfo, ccch mode NON-COMB)
[0;m[1;34m<0001> gsm48_rr.c:363 new state idle -> connection pending
[0;m[1;34m<0001> gsm48_rr.c:1313 CHANNEL REQUEST: e0 (Orig TCH/F)
[0;m[1;32m<0004> gsm48_mm.c:887 new state MM IDLE, normal service -> wait for RR connection (MM
 connection)
[0;m[34m<0003> gsm322.c:2434 Channel synched. (ARFCN=47, snr=16, BSIC=33)
[0;m[1;34m<0001> gsm322.c:2461 using DSC of 90
[0;m[34m<0003> gsm48_rr.c:4541 Channel provides data.
[0;m[1;34m<0001> gsm48_rr.c:1452 RANDOM ACCESS (requests left 5)
[0;m[1;34m<0001> gsm48_rr.c:1509 RANDOM ACCESS (Tx-integer 32 combined no S(lots) 0 ra 0xed)
[0;m[1;34m<0001> gsm48_rr.c:1547 Use MS-TXPWR-MAX-CCH power value 5 (33 dBm)
[0;m[1;34m<0001> gsm48_rr.c:1452 RANDOM ACCESS (requests left 4)
[0;m[1;34m<0001> gsm48_rr.c:1509 RANDOM ACCESS (Tx-integer 32 combined no S(lots) 217 ra 0xed)
[0;m[1;34m<0001> gsm48_rr.c:1547 Use MS-TXPWR-MAX-CCH power value 5 (33 dBm)
[0;m[1;34m<0001> gsm48_rr.c:2258 IMMEDIATE ASSIGNMENT:
[0;m[1;34m<0001> gsm48_rr.c:2270  (ta 1/553m ra 0xed chan_nr 0x41 MAIO 2 HSN 36 TS 1 SS 0 TSC 1)
[0;m[1;34m<0001>
 gsm48_rr.c:2204 request ed matches (fn=4,24,16)
[0;m[1;34m<0001> gsm48_rr.c:2304 resetting scheduler
[0;m[1;34m<0001> gsm48_rr.c:2858 decoding mobile allocation
[0;m[1;34m<0001> sysinfo.c:396 Serving cell ARFCN #0: 31
[0;m[1;34m<0001> sysinfo.c:396 Serving cell ARFCN #1: 44
[0;m[1;34m<0001> sysinfo.c:396 Serving cell ARFCN #2: 47
[0;m[1;34m<0001> sysinfo.c:396 Serving cell ARFCN #3: 57
[0;m[1;34m<0001> sysinfo.c:410 Hopping ARFCN: 0 (bit 31)
[0;m[1;34m<0001> sysinfo.c:410 Hopping ARFCN: 1 (bit 44)
[0;m[1;34m<0001> sysinfo.c:410 Hopping ARFCN: 2 (bit 47)
[0;m[1;34m<0001> sysinfo.c:410 Hopping ARFCN: 3 (bit 57)
[0;m[1;34m<0001> gsm48_rr.c:3019 sending establish message
[0;m[1;34m<0001> gsm48_rr.c:235 Using and incrementing V(SD) = 0 (pdisc 5)
[0;m[1;34m<0001> gsm48_rr.c:2763 setting indicated TA 1 (actual TA
 1)
[0;m[1;34m<0001> gsm48_rr.c:2774 using last SACCH timeout 16
[0;m[1;34m<0001> gsm48_rr.c:774 stopping pending timer T_meas
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=5 TA=1 meas-invalid=1 rxlev-full=-110 rxlev-sub=-110 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:2796 establishing channel in dedicated mode
[0;m[1;34m<0001> gsm48_rr.c:2800  Channel type 64, subch 0, ts 1, mode 0, cipher 1
[0;m[1;34m<0001> gsm48_rr.c:363 new state connection pending -> dedicated
[0;m[1;32m<0004> gsm48_mm.c:3695 (ms 1) Received 'RR_EST_CNF' from RR in state wait for RR connection (MM connection)
[0;m[1;32m<0004> gsm48_mm.c:431 starting T3230 (MM connection timeout) with 15.0 seconds
[0;m[1;32m<0004> gsm48_mm.c:897 new state wait for RR connection (MM connection) -> wait for outgoing MM connection
[0;m[1;34m<0001>
 gsm48_rr.c:4488 Indicated ta 1 (actual ta 1)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 5
[0;m[1;34m<0001> gsm48_rr.c:1828 New SYSTEM INFORMATION 5
[0;m[1;34m<0001> gsm48_rr.c:1583 Complete set of SI5* for BA(0)
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 1
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 3
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 6
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 13
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 17
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 19
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 20
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 21
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 24
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 26
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn
 30
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 33
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 36
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 37
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 40
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 41
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 42
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 47
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 49
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 58
[0;m[1;34m<0001> gsm48_rr.c:1593 SI5* report arfcn 59
[0;m[1;32m<0004> gsm48_mm.c:4083 (ms 1) Received 'MM_EVENT_SYSINFO' event in state wait for outgoing MM connection
[0;m[34m<0003> gsm322.c:2104 Sysinfo of selected cell is updated.
[0;m[1;32m<0004> gsm48_mm.c:3695 (ms 1) Received 'RR_DATA_IND' from RR in state wait for outgoing MM
 connection
[0;m[1;32m<0004> gsm48_mm.c:3863 (ms 1) Received 'MT_MM_AUTH_REQ' in MM state wait for outgoing MM connection
[0;m[1;32m<0004> gsm48_mm.c:468 stopping pending (periodic loc. upd. delay) timer T3212
[0;m[1;32m<0004> gsm48_mm.c:1533 AUTHENTICATION REQUEST (seq 1)
[0;m[1;32m<0004> subscriber.c:898 Generating KEY at SIM
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 5
[0;m[1;34m<0001> gsm48_rr.c:4493 setting new ta and tx_power
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-60 snr=11 ber= 27 LAI=226 01 2b96 ID=e328 TA=0 pwr=5 TS=1/0
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=5 TA=0 meas-invalid=0 rxlev-full=-60 rxlev-sub=-60 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001>
 gsm48_rr.c:4490 Indicated tx_power 5
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 6
[0;m[1;34m<0001> gsm48_rr.c:4493 setting new ta and tx_power
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-58 snr= 0 ber= 17 LAI=226 01 2b96 ID=e328 TA=0 pwr=6 TS=1/0
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=6 TA=0 meas-invalid=0 rxlev-full=-58 rxlev-sub=-58 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 7
[0;m[1;34m<0001> gsm48_rr.c:4493 setting new ta and tx_power
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 7
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-59 snr= 0 ber=  0 LAI=226 01
 2b96 ID=e328 TA=0 pwr=7 TS=1/0
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=7 TA=0 meas-invalid=0 rxlev-full=-59 rxlev-sub=-59 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 8
[0;m[1;34m<0001> gsm48_rr.c:4493 setting new ta and tx_power
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 8
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-57 snr= 0 ber= 18 LAI=226 01 2b96 ID=e328 TA=0 pwr=8 TS=1/0
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=8 TA=0 meas-invalid=0 rxlev-full=-57 rxlev-sub=-57 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power
 8
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 9
[0;m[1;34m<0001> gsm48_rr.c:4493 setting new ta and tx_power
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-57 snr= 0 ber= 49 LAI=226 01 2b96 ID=e328 TA=0 pwr=9 TS=1/0
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=9 TA=0 meas-invalid=0 rxlev-full=-57 rxlev-sub=-57 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 9
[0;m[1;32m<0004> gsm48_mm.c:3695 (ms 1) Received 'RR_DATA_IND' from RR in state wait for outgoing MM connection
[0;m[1;32m<0004> gsm48_mm.c:3863 (ms 1) Received 'MT_MM_AUTH_REQ' in MM state wait for outgoing MM connection
[0;m[1;32m<0004> gsm48_mm.c:1533 AUTHENTICATION REQUEST (seq
 1)
[0;m[1;32m<0004> subscriber.c:898 Generating KEY at SIM
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 9
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-57 snr= 0 ber= 32 LAI=226 01 2b96 ID=e328 TA=0 pwr=9 TS=1/0
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=9 TA=0 meas-invalid=0 rxlev-full=-57 rxlev-sub=-57 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 10
[0;m[1;34m<0001> gsm48_rr.c:4493 setting new ta and tx_power
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 10
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-57 snr= 0 ber= 41 LAI=226 01 2b96 ID=e328 TA=0 pwr=10
 TS=1/0
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=10 TA=0 meas-invalid=0 rxlev-full=-57 rxlev-sub=-57 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 10
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 10
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 10
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-58 snr= 0 ber= 74 LAI=226 01 2b96 ID=e328 TA=0 pwr=10 TS=1/0
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=10 TA=0 meas-invalid=0 rxlev-full=-58 rxlev-sub=-58 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001>
 gsm48_rr.c:4490 Indicated tx_power 11
[0;m[1;34m<0001> gsm48_rr.c:4493 setting new ta and tx_power
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 11
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-59 snr= 0 ber=  9 LAI=226 01 2b96 ID=e328 TA=0 pwr=11 TS=1/0
[0;m[1;34m<0001> gsm48_rr.c:2666 MEAS REP: pwr=11 TA=0 meas-invalid=0 rxlev-full=-59 rxlev-sub=-59 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 11
[0;m[1;34m<0001> gsm48_rr.c:4488 Indicated ta 0 (actual ta 0)
[0;m[1;34m<0001> gsm48_rr.c:4490 Indicated tx_power 11
[0;m[1;34m<0001> gsm48_rr.c:609 MON: f=47 lev=-58 snr= 0 ber= 35 LAI=226 01 2b96 ID=e328 TA=0 pwr=11 TS=1/0
[0;m[1;34m<0001>
 gsm48_rr.c:2666 MEAS REP: pwr=11 TA=0 meas-invalid=0 rxlev-full=-58 rxlev-sub=-58 rxqual-full=0 rxqual-sub=0 dtx 0 ba 0 no-ncell-n 0
[0;m[1;34m<0001> gsm48_rr.c:3174 channel release request with cause 0x00)
[0;m[1;34m<0001> gsm48_rr.c:363 new state dedicated -> release pending
[0;m[1;34m<0001> gsm48_rr.c:738 starting T3110 with 1.500 seconds
[0;m[1;37m<000d> gsm48_rr.c:4666 Requested channel aborted
[0;m[1;34m<0001> gsm48_rr.c:798 stopping pending timer T3110
[0;m[1;34m<0001> gsm48_rr.c:363 new state release pending -> idle
[0;m[34m<0003> gsm322.c:3319 (ms 1) Event 'EVENT_RET_IDLE' for Cell selection in state 'C3 camped normally'
[0;m[34m<0003> gsm322.c:541 new state 'C3 camped normally' -> 'C5 choose cell'
[0;m[34m<0003> gsm322.c:2820 No BA range(s), try sysinfo.
[0;m[34m<0003> gsm322.c:2859 free sysinfo
 arfcn=47
[0;m[34m<0003> gsm322.c:2859 free sysinfo arfcn=66
[0;m[1;32m<0004> gsm48_mm.c:3695 (ms 1) Received 'RR_REL_IND' from RR in state wait for outgoing MM connection
[0;m[1;32m<0004> gsm48_mm.c:495 stopping pending (MM connection timeout) timer T3230
[0;m[1;32m<0004> gsm48_mm.c:1367 Release any MM Connection
[0;m[1;32m<0004> gsm48_mm.c:1348 Freeing MM Connection
[0;m[1;32m<0004> gsm48_mm.c:1291 (ref 1) new state CONN_PEND -> IDLE
[0;m[1;33m<0005> gsm48_cc.c:2122 (ms 1) Received 'MMCC_ERR_IND' in CC state MM_CONNECTION_PEND
[0;m[1;33m<0005> gsm48_cc.c:189 (ms 1 ti ff) Sending 'MNCC_REL_IND' to MNCC.
[0;m[1;37m<0007> mnccms.c:336 Call has been released (cause 16)
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.osmocom.org/pipermail/baseband-devel/attachments/20110502/0f304de6/attachment.html>


More information about the baseband-devel mailing list