From: gm p. <gm...@ya...> - 2011-09-14 20:15:52
|
here is the log of openbts 1316029754.7177 INFO 3067022192 MobilityManagement.cpp:56:CMServiceResponder: MM CM Service Request serviceType=MOC mobileIdentity=(TMSI=0x4e7102c3) classmark=(revision=1 ES-IND=1 A5/1=0 A5/2=1 A5/3=0 powerCap=3 PS=0 SSScrenInd=1 SM=1 VBS=0 VGCS=0 FC=1 CM3=1 LCSVA=1 SoLSA=0 CMSF=0) 1316029754.7179 INFO 3067022192 CallControl.cpp:570:MOCStarter: MM CM Service Request serviceType=MOC mobileIdentity=(TMSI=0x4e7102c3) classmark=(revision=1 ES-IND=1 A5/1=0 A5/2=1 A5/3=0 powerCap=3 PS=0 SSScrenInd=1 SM=1 VBS=0 VGCS=0 FC=1 CM3=1 LCSVA=1 SoLSA=0 CMSF=0) 1316029754.7181 DEBUG 3067022192 CallControl.cpp:599:MOCStarter: sending CMServiceAccept 1316029755.6592 INFO 3067022192 CallControl.cpp:613:MOCStarter: CC Setup TI=(0,0) CalledPartyBCDNumber=(type=unknown plan=E.164/ISDN digits=2004) 1316029755.6594 DEBUG 3067022192 CallControl.cpp:629:MOCStarter: SIP start engine 1316029755.6594 DEBUG 3067022192 CallControl.cpp:646:MOCStarter: transaction: 1804289404 TI=(0,0) IMSI=234261003917943 MOC to=2004 Q.931State=MOC initiated SIPState=Null (0 sec) 1316029755.6595 DEBUG 3067022192 CallControl.cpp:656:MOCStarter: starting SIP (INVITE) Calling 2004 1316029755.6599 DEBUG 3067022192 CallControl.cpp:659:MOCStarter: SIP state=Starting 1316029755.6600 DEBUG 3067022192 CallControl.cpp:660:MOCStarter: Q.931 state=MOC initiated 1316029755.6600 DEBUG 3067022192 CallControl.cpp:663:MOCStarter: Sending Call Proceeding 1316029755.6959 INFO 3069684592 RadioResource.cpp:359:addID: IMSI=234101493065396 added to table 1316029755.6963 INFO 3036404592 RadioResource.cpp:405:pageAll: paging 1 mobile(s) 1316029756.0343 DEBUG 3067022192 CallControl.cpp:673:MOCStarter: transaction: 1804289404 TI=(0,0) IMSI=234261003917943 MOC to=2004 Q.931State=MOC proceeding SIPState=Starting (0 sec) 1316029756.0345 INFO 3067022192 CallControl.cpp:178:assignTCHF: assignTCHF sending AssignmentCommand for 0x8757fa8 on 0xbfbf7848 1316029756.0784 INFO 3069152112 RadioResource.cpp:144:AccessGrantResponder: RA=0x17 when=0:1585007 age=6 delay=1.2148 RSSI=-37.0000 1316029756.0787 INFO 3069152112 RadioResource.cpp:221:AccessGrantResponder: sending PageMode=(0) DedicatedModeOrTBF=(TMA=0 Downlink=0 DMOrTBF=0) ChannelDescription=(typeAndOffset=SDCCH/4-1 TN=0 TSC=2 ARFCN=51) RequestReference=(RA=23 T1'=11 T2=21 T3=29) TimingAdvance=1 1316029756.1675 INFO 3036404592 RadioResource.cpp:405:pageAll: paging 1 mobile(s) 1316029756.3840 INFO 3065424752 RadioResource.cpp:236:PagingResponseHandler: RR Paging Response mobileID=(IMSI=234101493065396) classmark=(revision=1 ES-IND=1 A5/1=0 A5/2=0 A5/3=1 powerCap=3 PS=1 SSScrenInd=1 SM=1 VBS=0 VGCS=0 FC=1 CM3=1 LCSVA=1 SoLSA=0 CMSF=1) 1316029756.3841 INFO 3065424752 RadioResource.cpp:369:removeID: IMSI=234101493065396 1316029756.3842 INFO 3065424752 CallControl.cpp:833:MTCStarter: MTC on SDCCH transaction: 1804289406 TI=(1,0) IMSI=234101493065396 MTC from=2001 Q.931State=paging SIPState=Null (0 sec) 1316029756.3844 INFO 3065424752 CallControl.cpp:858:MTCStarter: sending GSM Setup to call type=national plan=E.164/ISDN digits=2001 1316029756.5235 DEBUG 3065424752 CallControl.cpp:865:MTCStarter: wait for GSM Call Confirmed 1316029756.6190 DEBUG 3065424752 CallControl.cpp:445:updateGSMSignalling: received RR GPRS Suspension Request 1316029756.6191 DEBUG 3065424752 CallControl.cpp:220:callManagementDispatchGSM: from IMSI=234101493065396 message RR GPRS Suspension Request 1316029756.6191 NOTICE 3065424752 CallControl.cpp:364:callManagementDispatchGSM: no support for message RR GPRS Suspension Request from IMSI=234101493065396 1316029756.6192 NOTICE 3065424752 SIPEngine.cpp:566:MTCWaitForACK: received duplicate INVITE 1316029757.0892 DEBUG 3065424752 CallControl.cpp:445:updateGSMSignalling: received CC Call Confirmed TI=(0,0) 1316029757.0893 DEBUG 3065424752 CallControl.cpp:220:callManagementDispatchGSM: from IMSI=234101493065396 message CC Call Confirmed TI=(0,0) 1316029757.0894 INFO 3065424752 CallControl.cpp:253:callManagementDispatchGSM: GSM Call Confirmed IMSI=234101493065396 1316029757.0895 NOTICE 3065424752 SIPEngine.cpp:566:MTCWaitForACK: received duplicate INVITE 1316029757.0895 DEBUG 3065424752 CallControl.cpp:892:MTCStarter: transaction: 1804289406 TI=(1,0) IMSI=234101493065396 MTC from=2001 Q.931State=MTC confirmed SIPState=Proceeding (0 sec) 1316029757.0897 INFO 3065424752 CallControl.cpp:178:assignTCHF: assignTCHF sending AssignmentCommand for 0x875d3c0 on 0xbfbf789c 1316029757.4959 DEBUG 3047852912 CallControl.cpp:445:updateGSMSignalling: received RR Assignment Complete cause=0x0 1316029757.4960 DEBUG 3047852912 CallControl.cpp:220:callManagementDispatchGSM: from IMSI=234261003917943 message RR Assignment Complete cause=0x0 1316029757.4960 NOTICE 3047852912 CallControl.cpp:364:callManagementDispatchGSM: no support for message RR Assignment Complete cause=0x0 from IMSI=234261003917943 1316029757.9946 INFO 3045989232 RadioResource.cpp:312:AssignmentCompleteHandler: service=MTC 1316029757.9948 DEBUG 3045989232 CallControl.cpp:926:MTCController: transaction: 1804289406 TI=(1,0) IMSI=234101493065396 MTC from=2001 Q.931State=MTC confirmed SIPState=Proceeding (1 sec) 1316029757.9948 INFO 3045989232 CallControl.cpp:932:MTCController: waiting for GSM Alerting and Connect 1316029758.1335 DEBUG 3045989232 CallControl.cpp:445:updateGSMSignalling: received CC Alerting TI=(0,0) 1316029758.1337 DEBUG 3045989232 CallControl.cpp:220:callManagementDispatchGSM: from IMSI=234101493065396 message CC Alerting TI=(0,0) 1316029758.1337 INFO 3045989232 CallControl.cpp:264:callManagementDispatchGSM: GSM Alerting IMSI=234101493065396 1316029758.1337 DEBUG 3045989232 CallControl.cpp:937:MTCController: sending SIP Ringing 1316029759.1341 NOTICE 3045989232 SIPEngine.cpp:545:MTCWaitForACK: timeout 1316029760.1346 DEBUG 3045989232 CallControl.cpp:937:MTCController: sending SIP Ringing 1316029761.1357 NOTICE 3045989232 SIPEngine.cpp:545:MTCWaitForACK: timeout 1316029761.9742 INFO 3067022192 CallControl.cpp:185:assignTCHF: assignmentTCHF exiting normally 1316029762.1368 DEBUG 3045989232 CallControl.cpp:937:MTCController: sending SIP Ringing 1316029762.4642 INFO 3065424752 CallControl.cpp:185:assignTCHF: assignmentTCHF exiting normally 1316029763.1372 NOTICE 3045989232 SIPEngine.cpp:545:MTCWaitForACK: timeout 1316029763.1373 DEBUG 3045989232 CallControl.cpp:445:updateGSMSignalling: received CC Connect TI=(0,0) 1316029763.1373 DEBUG 3045989232 CallControl.cpp:220:callManagementDispatchGSM: from IMSI=234101493065396 message CC Connect TI=(0,0) 1316029763.1374 INFO 3045989232 CallControl.cpp:242:callManagementDispatchGSM: GSM Connect IMSI=234101493065396 1316029763.1375 INFO 3045989232 CallControl.cpp:947:MTCController: allocating port and sending SIP OKAY 1316029763.1378 DEBUG 3045989232 CallControl.cpp:951:MTCController: wait for SIP OKAY-ACK 1316029763.1400 DEBUG 3045989232 CallControl.cpp:954:MTCController: SIP call state Active 1316029763.1932 DEBUG 3045989232 CallControl.cpp:974:MTCController: MTC send GSM Connect Acknowledge 1316029763.1933 INFO 3045989232 CallControl.cpp:551:callManagementLoop: IMSI=234101493065396 call connected 1316029774.5543 INFO 3047852912 CallControl.cpp:136:abortCall: cause: location=1 cause=0x66, transction: 1804289401 TI=(0,0) IMSI=234261003917943 MOC to=1001 Q.931State=disconnect indication SIPState=Clearing (30 sec) 1316029774.5544 INFO 3047852912 CallControl.cpp:94:forceGSMClearing: Q.931 state disconnect indication 1316029795.3075 DEBUG 3045989232 CallControl.cpp:474:updateSignalling: got BYE 1316029795.4521 DEBUG 3045989232 CallControl.cpp:445:updateGSMSignalling: received CC Release TI=(0,0) 1316029795.4522 DEBUG 3045989232 CallControl.cpp:220:callManagementDispatchGSM: from IMSI=234101493065396 message CC Release TI=(0,0) 1316029795.4522 INFO 3045989232 CallControl.cpp:292:callManagementDispatchGSM: GSM Release IMSI=234101493065396 1316029851.8824 INFO 3069152112 RadioResource.cpp:144:AccessGrantResponder: RA=0x41 when=0:1605765 age=5 delay=0.6641 RSSI=-32.0000 1316029851.9457 INFO 3069152112 RadioResource.cpp:221:AccessGrantResponder: sending PageMode=(0) DedicatedModeOrTBF=(TMA=0 Downlink=0 DMOrTBF=0) ChannelDescription=(typeAndOffset=SDCCH/4-0 TN=0 TSC=2 ARFCN=51) RequestReference=(RA=65 T1'=26 T2=5 T3=30) TimingAdvance=1 1316029852.1646 INFO 3067022192 MobilityManagement.cpp:56:CMServiceResponder: MM CM Service Request serviceType=MOC mobileIdentity=(TMSI=0x4e7102c3) classmark=(revision=1 ES-IND=1 A5/1=0 A5/2=1 A5/3=0 powerCap=3 PS=0 SSScrenInd=1 SM=1 VBS=0 VGCS=0 FC=1 CM3=1 LCSVA=1 SoLSA=0 CMSF=0) 1316029852.1648 INFO 3067022192 CallControl.cpp:570:MOCStarter: MM CM Service Request serviceType=MOC mobileIdentity=(TMSI=0x4e7102c3) classmark=(revision=1 ES-IND=1 A5/1=0 A5/2=1 A5/3=0 powerCap=3 PS=0 SSScrenInd=1 SM=1 VBS=0 VGCS=0 FC=1 CM3=1 LCSVA=1 SoLSA=0 CMSF=0) 1316029852.1649 DEBUG 3067022192 CallControl.cpp:599:MOCStarter: sending CMServiceAccept 1316029852.1658 WARN 3047852912 ControlCommon.cpp:252:update: attempt to update non-existent transaction entry with key 1804289401 1316029852.1659 INFO 3047852912 CallControl.cpp:114:forceSIPClearing: SIP state Clearing 1316029852.1661 NOTICE 3047852912 SIPInterface.cpp:74:read: missing SIP FIFO 1056261225 1316029852.1663 WARN 3047852912 DCCHDispatch.cpp:166:DCCHDispatcher: Uncaught SIPError, will leave a stray transcation 1316029852.1664 ERROR 3047852912 GSML2LAPDm.cpp:920:sendMultiframeData: obj: 0x8760450 attempt to send DATA on released LAPm channel 1316029852.1675 NOTICE 3069684592 SIPInterface.cpp:62:write: missing SIP FIFO 1056261225 1316029852.1676 WARN 3069684592 SIPInterface.cpp:236:drive: errant SIP Message: SIP/2.0 481 Call leg/transaction does not exist 1316029853.1075 INFO 3067022192 CallControl.cpp:613:MOCStarter: CC Setup TI=(0,0) CalledPartyBCDNumber=(type=unknown plan=E.164/ISDN digits=2004) 1316029853.1077 DEBUG 3067022192 CallControl.cpp:629:MOCStarter: SIP start engine 1316029853.1078 DEBUG 3067022192 CallControl.cpp:646:MOCStarter: transaction: 1804289411 TI=(0,0) IMSI=234261003917943 MOC to=2004 Q.931State=MOC initiated SIPState=Null (0 sec) 1316029853.1079 DEBUG 3067022192 CallControl.cpp:656:MOCStarter: starting SIP (INVITE) Calling 2004 1316029853.1082 DEBUG 3067022192 CallControl.cpp:659:MOCStarter: SIP state=Starting 1316029853.1083 DEBUG 3067022192 CallControl.cpp:660:MOCStarter: Q.931 state=MOC initiated 1316029853.1083 DEBUG 3067022192 CallControl.cpp:663:MOCStarter: Sending Call Proceeding 1316029853.1408 INFO 3069684592 RadioResource.cpp:359:addID: IMSI=234101493065396 added to table 1316029853.1411 INFO 3036404592 RadioResource.cpp:405:pageAll: paging 1 mobile(s) 1316029853.4817 DEBUG 3067022192 CallControl.cpp:673:MOCStarter: transaction: 1804289411 TI=(0,0) IMSI=234261003917943 MOC to=2004 Q.931State=MOC proceeding SIPState=Starting (0 sec) 1316029853.4820 INFO 3067022192 CallControl.cpp:178:assignTCHF: assignTCHF sending AssignmentCommand for 0x8757fa8 on 0xbfbf7848 1316029853.6125 INFO 3036404592 RadioResource.cpp:405:pageAll: paging 1 mobile(s) 1316029853.6500 INFO 3069152112 RadioResource.cpp:144:AccessGrantResponder: RA=0x1f when=0:1606148 age=5 delay=0.9766 RSSI=-35.0000 1316029853.6506 INFO 3069152112 RadioResource.cpp:221:AccessGrantResponder: sending PageMode=(0) DedicatedModeOrTBF=(TMA=0 Downlink=0 DMOrTBF=0) ChannelDescription=(typeAndOffset=SDCCH/4-1 TN=0 TSC=2 ARFCN=51) RequestReference=(RA=31 T1'=27 T2=24 T3=5) TimingAdvance=1 1316029854.0665 INFO 3065424752 RadioResource.cpp:236:PagingResponseHandler: RR Paging Response mobileID=(IMSI=234101493065396) classmark=(revision=1 ES-IND=1 A5/1=0 A5/2=0 A5/3=1 powerCap=3 PS=1 SSScrenInd=1 SM=1 VBS=0 VGCS=0 FC=1 CM3=1 LCSVA=1 SoLSA=0 CMSF=1) 1316029854.0666 INFO 3065424752 RadioResource.cpp:369:removeID: IMSI=234101493065396 1316029854.0667 INFO 3065424752 CallControl.cpp:833:MTCStarter: MTC on SDCCH transaction: 1804289413 TI=(1,0) IMSI=234101493065396 MTC from=2001 Q.931State=paging SIPState=Null (0 sec) 1316029854.0668 INFO 3065424752 CallControl.cpp:858:MTCStarter: sending GSM Setup to call type=national plan=E.164/ISDN digits=2001 1316029854.2058 DEBUG 3065424752 CallControl.cpp:865:MTCStarter: wait for GSM Call Confirmed 1316029854.3019 DEBUG 3065424752 CallControl.cpp:445:updateGSMSignalling: received RR GPRS Suspension Request 1316029854.3020 DEBUG 3065424752 CallControl.cpp:220:callManagementDispatchGSM: from IMSI=234101493065396 message RR GPRS Suspension Request 1316029854.3021 NOTICE 3065424752 CallControl.cpp:364:callManagementDispatchGSM: no support for message RR GPRS Suspension Request from IMSI=234101493065396 1316029854.3021 NOTICE 3065424752 SIPEngine.cpp:566:MTCWaitForACK: received duplicate INVITE 1316029854.7726 DEBUG 3065424752 CallControl.cpp:445:updateGSMSignalling: received CC Call Confirmed TI=(0,0) 1316029854.7728 DEBUG 3065424752 CallControl.cpp:220:callManagementDispatchGSM: from IMSI=234101493065396 message CC Call Confirmed TI=(0,0) 1316029854.7728 INFO 3065424752 CallControl.cpp:253:callManagementDispatchGSM: GSM Call Confirmed IMSI=234101493065396 1316029854.7729 NOTICE 3065424752 SIPEngine.cpp:566:MTCWaitForACK: received duplicate INVITE 1316029854.7729 DEBUG 3065424752 CallControl.cpp:892:MTCStarter: transaction: 1804289413 TI=(1,0) IMSI=234101493065396 MTC from=2001 Q.931State=MTC confirmed SIPState=Proceeding (0 sec) 1316029854.7730 INFO 3065424752 CallControl.cpp:178:assignTCHF: assignTCHF sending AssignmentCommand for 0x875d3c0 on 0xbfbf789c 1316029854.9521 INFO 3047852912 RadioResource.cpp:312:AssignmentCompleteHandler: service=MOC 1316029854.9522 INFO 3047852912 CallControl.cpp:711:MOCController: transaction: 1804289411 TI=(0,0) IMSI=234261003917943 MOC to=2004 Q.931State=MOC proceeding SIPState=Starting (1 sec) 1316029854.9522 INFO 3047852912 CallControl.cpp:725:MOCController: MOC A: wait for Ringing or OK 1316029854.9522 DEBUG 3047852912 CallControl.cpp:727:MOCController: MOC A: SIP state=Proceeding 1316029854.9523 DEBUG 3047852912 CallControl.cpp:745:MOCController: MOC A: SIP:Proceeding, send progress 1316029854.9523 INFO 3047852912 CallControl.cpp:725:MOCController: MOC A: wait for Ringing or OK 1316029855.6727 INFO 3045989232 RadioResource.cpp:312:AssignmentCompleteHandler: service=MTC 1316029855.6729 DEBUG 3045989232 CallControl.cpp:926:MTCController: transaction: 1804289413 TI=(1,0) IMSI=234101493065396 MTC from=2001 Q.931State=MTC confirmed SIPState=Proceeding (1 sec) 1316029855.6729 INFO 3045989232 CallControl.cpp:932:MTCController: waiting for GSM Alerting and Connect 1316029855.8115 DEBUG 3045989232 CallControl.cpp:445:updateGSMSignalling: received CC Alerting TI=(0,0) 1316029855.8116 DEBUG 3045989232 CallControl.cpp:220:callManagementDispatchGSM: from IMSI=234101493065396 message CC Alerting TI=(0,0) call is automatically disconnected after 30 sec.. i have the simillar problem when i run with the asterisk 1.8.6 so i have downloadgraded to asterisk 1.8.0 even through same problem.. please help me........... |